Genesys CTI User Forum

Genesys CTI User Forum => Genesys CTI Technical Discussion => Topic started by: victor on December 15, 2006, 01:19:46 PM

Title: SIP error
Post by: victor on December 15, 2006, 01:19:46 PM
Hi, can someone lpease help me with SIP TServer error?

What exactly does it not like?

here is an excerpt:
[color=red]
sipcs: 21:56:23.750 Received [364,UDP] 385 bytes from 172.30.0.128:62390 <<<<<
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-341
To: <sip:6002@172.30.0.222>;tag=975b4d14
From: <sip:6003@172.30.0.222>;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-341
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-306@172.30.0.222
CSeq: 1 INVITE
Warning: 499 172.30.0.128 "Invalid message body"
Content-Length: 0

[/color]


And here is the whole transaction...

21:56:22.625 Trc 04541 RequestMakeCall received from 424 (0020 CTISIP_Client)
message RequestMakeCall
AttributeThisDN '6003'
AttributeOtherDN '6002'
AttributeMakeCallType 0 (MakeCallRegular)
AttributeReferenceID 9
21:56:22.625 Int 04543 Interaction message "RequestMakeCall" received from 424 ("CTISIP_Client")
21:56:22.625 -- created: CRequest@01BD5010 RequestMakeCall-CTISIP_Client[424]/9
21:56:22.625 +++ CIFace::Request +++
  -- new invoke
  Parsed: RequestMakeCall
  From: CTISIP_Client[424]/9
  Numbers: +<6003> +<6002>
  Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
  -----
  -- validate
  -- state check: ok
  SIPTS_IFace::QServe()01BD5010
  CIFace: Sent CRequest@01BD5010 RequestMakeCall-CTISIP_Client[424]/9
  FinishRequest CRequest@01BD5010 RequestMakeCall-CTISIP_Client[424]/9
  IFace stats: q=20 s=4294967249
  -- complete
sipcs: +dialog [0:00@01741420]
sipcs: TwoPhaseOriginationDEM: RefreshLocalContactEP()
gsip:DLG[622]: INVITE TD = TRN[737]

sipcs: 21:56:22.625 Sending  [364,UDP] 489 bytes to 172.30.0.170:10785 >>>>>
INVITE sip:172.30.0.170:10785 SIP/2.0
From: sip:6002@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-340
To: sip:6003@172.30.0.222
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-305@172.30.0.222
CSeq: 1 INVITE
Content-Length: 0
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-339
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, BYE, REFER
Contact: sip:6003@172.30.0.222
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: timer


sipcs: 21:56:22.625 dispatchDE: dlg[622]
sipcs:  dialog [622:02@01741420]: << Event 00 << TRN[737]
sipcs: TwoPhaseOriginationDEM: << Event 0 << DLG[622]
  Response (0): for CRequest@01BD5010 RequestMakeCall-CTISIP_Client[424]/9
  FinishRequest CRequest@01BD5010 RequestMakeCall-CTISIP_Client[424]/9
  IFace stats: q=20 s=4294967248
  -- complete
21:56:22.625 --- CIFace::Request ---
21:56:22.625 -- deleted: CRequest@01BD5010 RequestMakeCall-CTISIP_Client[424]/9

sipcs: 21:56:22.656 Received [364,UDP] 371 bytes from 172.30.0.170:1326 <<<<<
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-339
From: sip:6002@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-340
To: sip:6003@172.30.0.222;tag=3f34c090fdce40008376f4409e04c5f3
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-305@172.30.0.222
CSeq: 1 INVITE
User-Agent: RTC/1.2
Content-Length: 0


sipcs: 21:56:22.656 dispatchDE: dlg[622]
sipcs:  dialog [622:02@01741420]: << Event 01 << TRN[737]
sipcs: TwoPhaseOriginationDEM: << Event 1 << DLG[622]

sipcs: 21:56:22.671 Received [364,UDP] 372 bytes from 172.30.0.170:1326 <<<<<
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-339
From: sip:6002@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-340
To: sip:6003@172.30.0.222;tag=3f34c090fdce40008376f4409e04c5f3
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-305@172.30.0.222
CSeq: 1 INVITE
User-Agent: RTC/1.2
Content-Length: 0


sipcs: 21:56:22.671 dispatchDE: dlg[622]
sipcs:  dialog [622:02@01741420]: << Event 01 << TRN[737]
sipcs: TwoPhaseOriginationDEM: << Event 1 << DLG[622]
21:56:22.671 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventOriginated
    Devices: <6003/6003> <6002/6002> <-/->
    ANI/DNIS: <6003> <6002>
    Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
  --- Pre-event ---
  +++ Dialing +++
    -- internal call originator not found
    +++ CIFace::Event +++
      +++ Pre-event +++
        Type EventInitiated
        Devices: <6003/6003> <6002/6002> <-/->
        ANI/DNIS: <6003> <6002>
        Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
      --- Pre-event ---
      +++ NewCall +++
        -- created call_info_tspp 006e0167c0e52011@01BC5C00
@21:56:22.6710 Call [006e0167c0e52011]: urid 0001006e
        -- created aTmCall 0169DAE0/01BC5C00
sipcs: CALL[0]:>> SYNC-INIT >>
        -- TmCallThread created: 01BC4200
        SetANI for 006e0167c0e52011.0169DAE0: 6003
        SetDNIS for 006e0167c0e52011.0169DAE0: 6002
        -- call_info::committed created
@21:56:22.6710 [ISCC] Debug: Call created:
+ c:006e0167c0e52011,01bc5c00 @ m:0000000000000000,00000000 p:0 i:00000011 nw:0000000000000000 t:0
@21:56:22.6710 [ISCC] Debug: Translate: '6003' -> ''; result 1 ()
        -- created party_info_tspp 01BC6610
        -- created aTmParty 01BC31F0
sipcs: +party [6003@01bc31f0:0], call [006e0167c0e52011@0169dae0]
        SetRole: Origination for 6003.01BC31F0-006e0167c0e52011.0169DAE0
        -- AddParty to 0169DAE0: 6003.01BC31F0-006e0167c0e52011.0169DAE0
        -- new TSCP call leg 1
        -- call leg created leg_id=1
sipcs: SIPTS_TMHAU::PartyAdded(CNP):>>
sipcs: TMODEL:CALL[17]:>> SYNC-STATE >>
sipcs: PARTY[0]:>> SYNC-INIT >>
sipcs: SIPTS_TMHAU::PartyAdded(CNP):<<
        CreateParty new internal: 6003.01BC31F0-006e0167c0e52011.0169DAE0
@21:56:22.6710 [0] 7.2.001.18 distribute_event: message EventOffHook
AttributeEventSequenceNumber 00000000000000fe
AttributeTimeinuSecs 671000
AttributeTimeinSecs 1166187382 (21:56:22)
AttributeThisDN '6003'
@21:56:22.6710 [ISCC] Debug: Translate: '' -> ''; result 1 ()
21:56:22.671 Int 04544 Interaction message "EventOffHook" generated
21:56:22.671 Trc 04542 EventOffHook sent to 424 (0020 CTISIP_Client)
21:56:22.671 Trc 04542 EventOffHook sent to 380 (0002 StatServer)
        SetActive: party 6003.01BC31F0-006e0167c0e52011.0169DAE0, cause Null
        SetOriginating: party 6003.01BC31F0-006e0167c0e52011.0169DAE0, cause NewCall
        -- party_info 6003.01BC6610 state change: from <Null> to <Initiated>
        -- G7 init
@21:56:22.6710 [ISCC] Debug: Party added [ssp view]:
@ c:006e0167c0e52011,01bc5c00 @ m:0000000000000000,00000000,0000000000000000 p:1 i:00000011 nw:00000000:0000000000000000 t:0
+ p:01bc6610 @ c:006e0167c0e52011,01bc5c00 r:1 t:0 s:1 n:6003
@21:56:22.6710 [ISCC] Debug: Party added:
@ c:006e0167c0e52011,01bc5c00 @ m:0000000000000000,00000000 p:1 i:00000011 nw:0000000000000000 t:0
+ p:0000000000000000,01bc6610 @ c:006e0167c0e52011,01bc5c00 r:1 ------ n:6003:
sipcs: HA:PARTY[6003@01bc31f0:0]:SetOriginating
      --- NewCall ---
      -- AgentEmu: private call
      +++ Post-event +++
        Type EventInitiated
        Devices: <6003/6003> <6002/6002> <-/->
        Calls: 0/006e0167c0e52011/17.0169DAE0/c:1/r:0 0/none
        Parties: <d6003/6003-006e0167c0e52011>.01BC31F0/l:1/r:0/Originating,Active,Origination
        none
        none
        ANI/DNIS: <6003> <6002>
        Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
      --- Post-event ---
    --- CIFace::Event ---
    -- created party_info_tspp 01BB9AD0
    -- created aTmParty 01BC9C60
sipcs: +party [6002@01bc9c60:0], call [006e0167c0e52011@0169dae0]
    SetRole: Destination for 6002.01BC9C60-006e0167c0e52011.0169DAE0
    -- AddParty to 0169DAE0: 6002.01BC9C60-006e0167c0e52011.0169DAE0
    -- new TSCP call leg 2
    -- call leg created leg_id=2
sipcs: SIPTS_TMHAU::PartyAdded(CNP):>>
sipcs: PARTY[0]:>> SYNC-INIT >>
sipcs: SIPTS_TMHAU::PartyAdded(CNP):<<
    CreateParty new internal: 6002.01BC9C60-006e0167c0e52011.0169DAE0
    SetDialling: party 6003.01BC31F0-006e0167c0e52011.0169DAE0, cause Null
    -- party_info 6003.01BC6610 state change: from <Initiated> to <Connected,Dialing>
    -- G7 dialing
@21:56:22.6710 [0] 7.2.001.18 distribute_event: message EventDialing
AttributeEventSequenceNumber 00000000000000ff
AttributeTimeinuSecs 671000
AttributeTimeinSecs 1166187382 (21:56:22)
AttributeDNIS '6002'
AttributeCallUUID '336C29C9-810B-49B2-AB61-EBBE0A718182'
AttributeConnID 006e0167c0e52011
AttributeCallID 17
AttributeCallType 1
AttributeCallState 0
AttributeThisDNRole 1
AttributeAgentID '10003'
AttributeThisDN '6003'
AttributeOtherDNRole 2
AttributeOtherDN '6002'
@21:56:22.6710 [ISCC] Debug: Translate: '' -> ''; result 1 ()
21:56:22.671 Int 04544 Interaction message "EventDialing" generated
21:56:22.671 Trc 04542 EventDialing sent to 424 (0020 CTISIP_Client)
21:56:22.671 Trc 04542 EventDialing sent to 380 (0002 StatServer)
sipcs: HA:PARTY[6003@01bc31f0:0]:SetDialing
  --- Dialing ---
  +++ Post-event +++
    Type EventOriginated
    Devices: <6003/6003> <6002/6002> <-/->
    Calls: 0/006e0167c0e52011/17.0169DAE0/c:1/r:0 0/none
    Parties: <d6003/6003-006e0167c0e52011>.01BC31F0/l:1/r:0/Dialing,Active,Origination
    <d6002/6002-006e0167c0e52011>.01BC9C60/l:2/r:0/Null,DNull,Destination
    none
    ANI/DNIS: <6003> <6002>
    Call type: Internal/1
    Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
  --- Post-event ---
21:56:22.671 --- CIFace::Event ---
sipcs:  party [6003@01bc31f0:0] +dlg [622@1741420]
sipcs:  dialog [622:02@01741420] : -party [0x00000000] +party [0x01bc31f0]

sipcs: 21:56:22.671 Sending  [364,UDP] 416 bytes to 172.30.0.170:10785 >>>>>
NOTIFY sip:172.30.0.170:10785 SIP/2.0
From: sip:6002@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-340
To: sip:6003@172.30.0.222;tag=3f34c090fdce40008376f4409e04c5f3
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-305@172.30.0.222
CSeq: 2 NOTIFY
Content-Length: 0
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-340
Contact: <sip:172.30.0.222:5060>
Event: talk


sipcs: 21:56:22.671 dispatchDE: dlg[622]
sipcs:  dialog [622:02@01741420]: << Event 73 << TRN[738]
sipcs: TwoPhaseOriginationDEM: << Event 73 << DLG[622]
sipcs:  dialog [622:02@01741420] : no handler for [73]
sipcs:  party [6003@01bc31f0:1]:ANSWER(BSOFT):FORCED

sipcs: 21:56:22.750 Received [364,UDP] 386 bytes from 172.30.0.170:1326 <<<<<
SIP/2.0 500 Server Internal Error
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-340
From: sip:6002@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-340
To: sip:6003@172.30.0.222;tag=3f34c090fdce40008376f4409e04c5f3
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-305@172.30.0.222
CSeq: 2 NOTIFY
User-Agent: RTC/1.2
Content-Length: 0


sipcs: 21:56:22.750 dispatchDE: dlg[622]
sipcs:  dialog [622:02@01741420]: << Event 74 << TRN[738]
sipcs: TwoPhaseOriginationDEM: << Event 74 << DLG[622]
sipcs:  dialog [622:02@01741420] : no handler for [74]
sipcs: [+#]unregister_trx_reactor [738]

sipcs: 21:56:23.625 Received [364,UDP] 958 bytes from 172.30.0.170:1326 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-339
From: sip:6002@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-340
To: sip:6003@172.30.0.222;tag=3f34c090fdce40008376f4409e04c5f3
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-305@172.30.0.222
CSeq: 1 INVITE
Contact: <sip:172.30.0.170:10785>
User-Agent: RTC/1.2
Content-Type: application/sdp
Content-Length: 523

v=0
o=- 0 0 IN IP4 172.30.0.170
s=session
c=IN IP4 172.30.0.170
b=CT:1000
t=0 0
m=audio 49866 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:QqkntrDhlEsoJ1pTbo+cDj81yiZRuI/lpuMBQSKzSmw
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional

sipcs: 21:56:23.625 dispatchDE: dlg[622]
sipcs:  dialog [622:07@01741420]: << Event 04 << TRN[737]
sipcs: 21:56:23.625 Stored This SDP [622]
sipcs: TwoPhaseOriginationDEM: << Event 4 << DLG[622]
sipcs: TwoPhaseOriginationDEM: dlg[622] received 200 OK
sipcs: +dialog [0:00@016e8c18]
sipcs: CInf[0x016b3c10]
sipcs:  party [6002@01bc9c60:0] +dlg [0@16e8c18]
sipcs:  dialog [0:00@016e8c18] : -party [0x00000000] +party [0x01bc9c60]
gsip:DLG[623]: INVITE TD = TRN[739]

sipcs: 21:56:23.625 Sending  [364,UDP] 1238 bytes to 172.30.0.128:62390 >>>>>
INVITE sip:6002@172.30.0.128:62390;rinstance=5952450057c2fa17 SIP/2.0
From: sip:6003@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-341
To: sip:6002@172.30.0.222
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-306@172.30.0.222
CSeq: 1 INVITE
Content-Length: 539
Content-Type: application/sdp
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-341
Contact: <sip:172.30.0.222:5060>
Call-Info: <http://example.com>; E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-305@172.30.0.222;gen-rt=3f34c090fdce40008376f4409e04c5f3;gen-lt=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-340
Max-Forwards: 70
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: 100rel,timer

v=0
o=GSIP 1000009 1000009  IN IP4 172.30.0.170
s=session
c=IN IP4 172.30.0.170
b=CT:1000
t=0 0
m=audio 49866 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:QqkntrDhlEsoJ1pTbo+cDj81yiZRuI/lpuMBQSKzSmw
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional

sipcs: 21:56:23.625 dispatchDE: dlg[623]
sipcs:  dialog [623:02@016e8c18]: << Event 00 << TRN[739]
sipcs: 21:56:23.625 Stored Other SDP [623]

sipcs: 21:56:23.750 Received [364,UDP] 385 bytes from 172.30.0.128:62390 <<<<<
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-341
To: <sip:6002@172.30.0.222>;tag=975b4d14
From: <sip:6003@172.30.0.222>;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-341
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-306@172.30.0.222
CSeq: 1 INVITE
Warning: 499 172.30.0.128 "Invalid message body"
Content-Length: 0



sipcs: 21:56:23.750 Sending  [364,UDP] 373 bytes to 172.30.0.128:62390 >>>>>
ACK sip:6002@172.30.0.128:62390;rinstance=5952450057c2fa17 SIP/2.0
Via: SIP/2.0/UDP 172.30.0.222:5060;branch=z9hG4bK9951BFA7-D8C5-4806-B5A2-73CC2323EE9C-341
From: sip:6003@172.30.0.222;tag=4945A0E0-BC15-4524-BA73-A537D5A7F9A0-341
To: <sip:6002@172.30.0.222>;tag=975b4d14
Call-ID: E67449A0-AFF0-44D2-9A1F-A81C94B8CCEE-306@172.30.0.222
CSeq: 1 ACK
Content-Length: 0


....

Thanks,
Vic