Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Incoming call from Restcomm App +1238 fails #597

Open
atsakiridis opened this issue Jul 6, 2017 · 0 comments
Open

Incoming call from Restcomm App +1238 fails #597

atsakiridis opened this issue Jul 6, 2017 · 0 comments
Labels
bug
Milestone
Upcoming

Comments

@atsakiridis
Copy link
Collaborator

Scenario:

  • iOS client (bob) calls +1238
  • RC Dials alice that has registered from another iOS client
  • alice answers the call but WebRTC processing fails with Failed to set remote offer sdp: Failed to create channels

Analysis

At first I thought it was an issue with MS not making a webrtc call, but seems this isn't the case; need to investigate more. Here are the iOS console logs for alice:

   ------------------------------------------------------------------------
2017-07-06 15:02:30.880907+0300 restcomm-olympus[766:252259] (common.m:45)    INVITE sip:alice@192.168.2.16:54652 SIP/2.0
2017-07-06 15:02:30.880948+0300 restcomm-olympus[766:252259] (common.m:45)    Call-ID: 80f17e4edb7113d29d8c2add019a2c40@192.168.2.24
2017-07-06 15:02:30.880981+0300 restcomm-olympus[766:252259] (common.m:45)    CSeq: 1 INVITE
2017-07-06 15:02:30.881013+0300 restcomm-olympus[766:252259] (common.m:45)    From: "bob" <sip:bob@192.168.2.24:5080>;tag=82888136_f551bd97_57a5b08a_bcd00b30
2017-07-06 15:02:30.881079+0300 restcomm-olympus[766:252259] (common.m:45)    To: <sip:alice@192.168.2.16:54652>
2017-07-06 15:02:30.881166+0300 restcomm-olympus[766:252259] (common.m:45)    Max-Forwards: 70
2017-07-06 15:02:30.881206+0300 restcomm-olympus[766:252259] (common.m:45)    User-Agent: Restcomm 8.2.0.1240
2017-07-06 15:02:30.881238+0300 restcomm-olympus[766:252259] (common.m:45)    Contact: "bob" <sip:bob@192.168.2.24:5080;transport=tcp>
2017-07-06 15:02:30.881271+0300 restcomm-olympus[766:252259] (common.m:45)    Via: SIP/2.0/TCP 192.168.2.24:5080;branch=z9hG4bKbcd00b30_57a5b08a_74fa5513-17cb-48cf-aa53-dff6916035b9
2017-07-06 15:02:30.881427+0300 restcomm-olympus[766:252259] (common.m:45)    Route: <sip:192.168.2.16:54652;transport=tcp;lr>
2017-07-06 15:02:30.881566+0300 restcomm-olympus[766:252259] (common.m:45)    X-RestComm-ApiVersion: 2012-04-24
2017-07-06 15:02:30.881654+0300 restcomm-olympus[766:252259] (common.m:45)    X-RestComm-AccountSid: ACae6e420f425248d6a26948c17a9e2acf
2017-07-06 15:02:30.881707+0300 restcomm-olympus[766:252259] (common.m:45)    X-RestComm-CallSid: IDac315a18f9f444e2ac2b59bfdc1e4e8f-CAadff7334b65d46c7ac3b25da86008e1b
2017-07-06 15:02:30.881806+0300 restcomm-olympus[766:252259] (common.m:45)    Content-Type: application/sdp
2017-07-06 15:02:30.881839+0300 restcomm-olympus[766:252259] (common.m:45)    Content-Length: 623
2017-07-06 15:02:30.881869+0300 restcomm-olympus[766:252259] (common.m:45)
2017-07-06 15:02:30.881899+0300 restcomm-olympus[766:252259] (common.m:45)    v=0
2017-07-06 15:02:30.881931+0300 restcomm-olympus[766:252259] (common.m:45)    o=- 1499342550778 1 IN IP4 192.168.2.24
2017-07-06 15:02:30.882040+0300 restcomm-olympus[766:252259] (common.m:45)    s=Mobicents Media Server
2017-07-06 15:02:30.882122+0300 restcomm-olympus[766:252259] (common.m:45)    c=IN IP4 192.168.2.24
2017-07-06 15:02:30.882187+0300 restcomm-olympus[766:252259] (common.m:45)    t=0 0
2017-07-06 15:02:30.882303+0300 restcomm-olympus[766:252259] (common.m:45)    a=ice-lite
2017-07-06 15:02:30.882342+0300 restcomm-olympus[766:252259] (common.m:45)    m=audio 65526 RTP/SAVPF 8 0 101
2017-07-06 15:02:30.882485+0300 restcomm-olympus[766:252259] (common.m:45)    c=IN IP4 192.168.2.24
2017-07-06 15:02:30.882519+0300 restcomm-olympus[766:252259] (common.m:45)    a=sendrecv
2017-07-06 15:02:30.882623+0300 restcomm-olympus[766:252259] (common.m:45)    a=rtcp:65526 IN IP4 192.168.2.24
2017-07-06 15:02:30.882660+0300 restcomm-olympus[766:252259] (common.m:45)    a=rtcp-mux
2017-07-06 15:02:30.882690+0300 restcomm-olympus[766:252259] (common.m:45)    a=ptime:20
2017-07-06 15:02:30.882810+0300 restcomm-olympus[766:252259] (common.m:45)    a=ice-ufrag:tfq1
2017-07-06 15:02:30.882905+0300 restcomm-olympus[766:252259] (common.m:45)    a=ice-pwd:4rn8c51625odkt0g11r29991p2
2017-07-06 15:02:30.882968+0300 restcomm-olympus[766:252259] (common.m:45)    a=candidate:11111111 1 udp 1 192.168.2.24 65526 typ host generation 0
2017-07-06 15:02:30.883041+0300 restcomm-olympus[766:252259] (common.m:45)    a=rtpmap:0 pcmu/8000
2017-07-06 15:02:30.883081+0300 restcomm-olympus[766:252259] (common.m:45)    a=rtpmap:101 telephone-event/8000
2017-07-06 15:02:30.883147+0300 restcomm-olympus[766:252259] (common.m:45)    a=rtpmap:8 pcma/8000
2017-07-06 15:02:30.883180+0300 restcomm-olympus[766:252259] (common.m:45)    a=setup:actpass
2017-07-06 15:02:30.883275+0300 restcomm-olympus[766:252259] (common.m:45)    a=fingerprint:sha-256 82:1E:5E:EB:B5:0D:F8:CF:7A:72:43:FE:91:3A:CE:DC:20:D1:4E:F4:69:4B:06:B4:AA:03:41:67:19:F1:E5:24
2017-07-06 15:02:30.883310+0300 restcomm-olympus[766:252259] (common.m:45)    a=ssrc:2914258912 cname:vgwkxfQKccYB96m0
2017-07-06 15:02:30.883427+0300 restcomm-olympus[766:252259] (common.m:45)    ------------------------------------------------------------------------
2017-07-06 15:02:30.883510+0300 restcomm-olympus[766:252259] (common.m:45) tport_deliver(0x11fd119a0): msg 0x11fd35510 (1360 bytes) from tcp/192.168.2.24:5080/sip next=0x0
2017-07-06 15:02:30.883636+0300 restcomm-olympus[766:252259] (common.m:45) nta: received INVITE sip:alice@192.168.2.16:54652 SIP/2.0 (CSeq 1)
2017-07-06 15:02:30.883728+0300 restcomm-olympus[766:252259] (common.m:45) nta: INVITE (1) going to a default leg
2017-07-06 15:02:30.883778+0300 restcomm-olympus[766:252259] (common.m:45) nta: timer set to 2000 ms
2017-07-06 15:02:30.883894+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_stack_process_request: entering
2017-07-06 15:02:30.884027+0300 restcomm-olympus[766:252259] (common.m:45) nua: nh_create: entering
2017-07-06 15:02:30.884136+0300 restcomm-olympus[766:252259] (common.m:45) nua: nh_create_handle: entering
2017-07-06 15:02:30.884340+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_stack_set_params: entering
2017-07-06 15:02:30.884524+0300 restcomm-olympus[766:252259] (common.m:45) nta_leg_tcreate(0x1742fa400)
2017-07-06 15:02:30.884659+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): adding session usage
2017-07-06 15:02:30.884792+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_invite_server_respond: entering
2017-07-06 15:02:30.884844+0300 restcomm-olympus[766:252259] (common.m:45) tport_tsend(0x11fd119a0) tpn = TCP/192.168.2.24:5080
2017-07-06 15:02:30.885032+0300 restcomm-olympus[766:252259] (common.m:45) tport_vsend(0x11fd119a0): 611 bytes of 611 to tcp/192.168.2.24:5080
2017-07-06 15:02:30.885112+0300 restcomm-olympus[766:252259] (common.m:45) tport_vsend returned 611
2017-07-06 15:02:30.885172+0300 restcomm-olympus[766:252259] (common.m:45) send 611 bytes to tcp/[192.168.2.24]:5080 at 12:02:30.884905:
   ------------------------------------------------------------------------
2017-07-06 15:02:30.885207+0300 restcomm-olympus[766:252259] (common.m:45)    SIP/2.0 180 Ringing
2017-07-06 15:02:30.885406+0300 restcomm-olympus[766:252259] (common.m:45)    Via: SIP/2.0/TCP 192.168.2.24:5080;branch=z9hG4bKbcd00b30_57a5b08a_74fa5513-17cb-48cf-aa53-dff6916035b9;rport=5080
2017-07-06 15:02:30.885442+0300 restcomm-olympus[766:252259] (common.m:45)    From: "bob" <sip:bob@192.168.2.24:5080>;tag=82888136_f551bd97_57a5b08a_bcd00b30
2017-07-06 15:02:30.885474+0300 restcomm-olympus[766:252259] (common.m:45)    To: <sip:alice@192.168.2.16:54652>;tag=61H4g875mmatS
2017-07-06 15:02:30.885614+0300 restcomm-olympus[766:252259] (common.m:45)    Call-ID: 80f17e4edb7113d29d8c2add019a2c40@192.168.2.24
2017-07-06 15:02:30.885651+0300 restcomm-olympus[766:252259] (common.m:45)    CSeq: 1 INVITE
2017-07-06 15:02:30.885690+0300 restcomm-olympus[766:252259] (common.m:45)    Contact: <sip:192.168.2.16:54651;transport=tcp>
2017-07-06 15:02:30.885721+0300 restcomm-olympus[766:252259] (common.m:45)    User-Agent: TelScale Restcomm iOS Client #BASE_VERSION-#VERSION_SUFFIX+#BUILD
2017-07-06 15:02:30.885783+0300 restcomm-olympus[766:252259] (common.m:45)    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE
2017-07-06 15:02:30.885814+0300 restcomm-olympus[766:252259] (common.m:45)    Supported: timer, 100rel
2017-07-06 15:02:30.885914+0300 restcomm-olympus[766:252259] (common.m:45)    Content-Length: 0
2017-07-06 15:02:30.885948+0300 restcomm-olympus[766:252259] (common.m:45)
2017-07-06 15:02:30.886084+0300 restcomm-olympus[766:252259] (common.m:45)    ------------------------------------------------------------------------
2017-07-06 15:02:30.886167+0300 restcomm-olympus[766:252259] (common.m:45) tport(0x11fd119a0): reset timer
2017-07-06 15:02:30.886229+0300 restcomm-olympus[766:252259] (common.m:45) nta: sent 180 Ringing for INVITE (1)
2017-07-06 15:02:30.886310+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): event i_invite 180 Ringing
2017-07-06 15:02:30.886377+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): call state changed: init -> early, received offer
2017-07-06 15:02:30.886509+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): event i_state 180 Ringing
2017-07-06 15:02:30.886687+0300 restcomm-olympus[766:252259] (common.m:45) tport(0x11fd119a0): reset timer
2017-07-06 15:02:30.886729+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_application_event: entering
2017-07-06 15:02:30.886801+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_handle_bind: entering
2017-07-06 15:02:30.886918+0300 restcomm-olympus[766:252259] (ssc_sip.mm:977) UA: incoming call
	From: "bob" <sip:bob@192.168.2.24:5080>;tag=82888136_f551bd97_57a5b08a_bcd00b30
2017-07-06 15:02:30.886954+0300 restcomm-olympus[766:252259] (ssc_sip.mm:981) 	To:  <sip:alice@192.168.2.16:54652>
2017-07-06 15:02:30.887148+0300 restcomm-olympus[766:252259] (ssc_sip.mm:989) Please Answer(a), decline(d) or Decline(D) the call
2017-07-06 15:02:30.887337+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_application_event: entering
2017-07-06 15:02:30.887858+0300 restcomm-olympus[766:252183] (RCDevice.m:412) [RCDevice sipManagerDidReceiveCall]
2017-07-06 15:02:30.887978+0300 restcomm-olympus[766:252183] (RCConnection.m:61) [RCConnection initWithDelegate: ... andIncoming:1 andState:1 andParameters:{
  "from" : "bob"
}]
2017-07-06 15:02:30.889554+0300 restcomm-olympus[766:252183] (RCConnection.m:85) [RCConnection dealloc]
2017-07-06 15:02:35.947014+0300 restcomm-olympus[766:252183] (RCConnection.m:90) [RCConnection accept: {
  "video-enabled" : false
}]
2017-07-06 15:02:35.947369+0300 restcomm-olympus[766:252183] (SipManager.mm:579) [SipManager permissionFsm: type: request status:0]
2017-07-06 15:02:35.947633+0300 restcomm-olympus[766:252183] (SipManager.mm:579) [SipManager permissionFsm:soun type: response status:1]
2017-07-06 15:02:35.967523+0300 restcomm-olympus[766:252183] (MediaWebRTC.m:95) [MediaWebRTC initWithDelegate]
2017-07-06 15:02:35.967909+0300 restcomm-olympus[766:252183] (MediaWebRTC.m:124) [MediaWebRTC connect: (null) 
sdp:v=0

o=- 1499342550778 1 IN IP4 192.168.2.24

s=Mobicents Media Server

c=IN IP4 192.168.2.24

t=0 0

a=ice-lite

m=audio 65526 RTP/SAVPF 8 0 101

c=IN IP4 192.168.2.24

a=sendrecv

a=rtcp:65526 IN IP4 192.168.2.24

a=rtcp-mux

a=ptime:20

a=ice-ufrag:tfq1

a=ice-pwd:4rn8c51625odkt0g11r29991p2

a=candidate:11111111 1 udp 1 192.168.2.24 65526 typ host generation 0

a=rtpmap:0 pcmu/8000

a=rtpmap:101 telephone-event/8000

a=rtpmap:8 pcma/8000

a=setup:actpass

a=fingerprint:sha-256 82:1E:5E:EB:B5:0D:F8:CF:7A:72:43:FE:91:3A:CE:DC:20:D1:4E:F4:69:4B:06:B4:AA:03:41:67:19:F1:E5:24

a=ssrc:2914258912 cname:vgwkxfQKccYB96m0
 
isInitiator:false 
withVideo:false]
2017-07-06 15:02:37.340155+0300 restcomm-olympus[766:252183] (RTCICEServer+JSON.m:85) [RTCIceServer serverFromXirsysArray] adding ICE server, url: stun:turn01.uswest.xirsys.com, username:
2017-07-06 15:02:37.340491+0300 restcomm-olympus[766:252183] (RTCICEServer+JSON.m:85) [RTCIceServer serverFromXirsysArray] adding ICE server, url: turn:turn01.uswest.xirsys.com:80?transport=udp, username: 00a4e1cc-6243-11e7-9a68-798c7d2f5f2e
2017-07-06 15:02:37.340739+0300 restcomm-olympus[766:252183] (RTCICEServer+JSON.m:85) [RTCIceServer serverFromXirsysArray] adding ICE server, url: turn:turn01.uswest.xirsys.com:3478?transport=udp, username: 00a4e1cc-6243-11e7-9a68-798c7d2f5f2e
2017-07-06 15:02:37.340972+0300 restcomm-olympus[766:252183] (RTCICEServer+JSON.m:85) [RTCIceServer serverFromXirsysArray] adding ICE server, url: turn:turn01.uswest.xirsys.com:80?transport=tcp, username: 00a4e1cc-6243-11e7-9a68-798c7d2f5f2e
2017-07-06 15:02:37.341244+0300 restcomm-olympus[766:252183] (RTCICEServer+JSON.m:85) [RTCIceServer serverFromXirsysArray] adding ICE server, url: turn:turn01.uswest.xirsys.com:3478?transport=tcp, username: 00a4e1cc-6243-11e7-9a68-798c7d2f5f2e
2017-07-06 15:02:37.347439+0300 restcomm-olympus[766:252183] (RTCICEServer+JSON.m:85) [RTCIceServer serverFromXirsysArray] adding ICE server, url: turns:turn01.uswest.xirsys.com:443?transport=tcp, username: 00a4e1cc-6243-11e7-9a68-798c7d2f5f2e
2017-07-06 15:02:37.347698+0300 restcomm-olympus[766:252183] (RTCICEServer+JSON.m:85) [RTCIceServer serverFromXirsysArray] adding ICE server, url: turns:turn01.uswest.xirsys.com:5349?transport=tcp, username: 00a4e1cc-6243-11e7-9a68-798c7d2f5f2e
2017-07-06 15:02:37.367655+0300 restcomm-olympus[766:252183] (MediaWebRTC.m:902) [MediaWebRTC didSetSessionDescriptionWithError]
2017-07-06 15:02:37.367973+0300 restcomm-olympus[766:252183] (MediaWebRTC.m:904) [MediaWebRTC didSetSessionDescriptionWithError] Failed to set session description. Error: {
  "NSLocalizedDescription" : "Failed to set remote offer sdp: Failed to create channels."
}
2017-07-06 15:02:37.368244+0300 restcomm-olympus[766:252183] (MediaWebRTC.m:181) [MediaWebRTC disconnect]
2017-07-06 15:02:37.371270+0300 restcomm-olympus[766:252183] (MediaWebRTC.m:213) [MediaWebRTC disconnect] end
2017-07-06 15:02:37.371592+0300 restcomm-olympus[766:252183] (RCConnection.m:356) [RCConnection didMediaError: {
  "NSLocalizedDescription" : "didSetSessionDescriptionWithError: Failed to set session description"
}]
2017-07-06 15:02:37.371822+0300 restcomm-olympus[766:252183] (RCConnection.m:129) [RCConnection disconnect]
2017-07-06 15:02:37.371980+0300 restcomm-olympus[766:252183] [RCConnection disconnect:bye]
2017-07-06 15:02:37.372291+0300 restcomm-olympus[766:252183] (RCConnection.m:158) [RCConnection disconnect] before disconnectMedia
2017-07-06 15:02:37.372480+0300 restcomm-olympus[766:252259] (ssc_sip.mm:1371) UA: BYE to "bob" <sip:bob@192.168.2.24:5080>;tag=82888136_f551bd97_57a5b08a_bcd00b30
2017-07-06 15:02:37.372644+0300 restcomm-olympus[766:252183] (MediaWebRTC.m:181) [MediaWebRTC disconnect]
2017-07-06 15:02:37.372838+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_bye: entering
2017-07-06 15:02:37.373205+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): sent signal r_bye
2017-07-06 15:02:37.373500+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): recv signal r_bye
2017-07-06 15:02:37.373709+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_stack_set_params: entering
2017-07-06 15:02:37.374136+0300 restcomm-olympus[766:252259] (common.m:45) nta: selecting scheme sip
2017-07-06 15:02:37.374403+0300 restcomm-olympus[766:252259] (common.m:45) tport(0x11fd0f8d0): found 0x11fd119a0 by name */192.168.2.24:5080
2017-07-06 15:02:37.374630+0300 restcomm-olympus[766:252259] (common.m:45) tport_tsend(0x11fd119a0) tpn = */192.168.2.24:5080
2017-07-06 15:02:37.375380+0300 restcomm-olympus[766:252259] (common.m:45) tport_vsend(0x11fd119a0): 566 bytes of 566 to tcp/192.168.2.24:5080
2017-07-06 15:02:37.375595+0300 restcomm-olympus[766:252259] (common.m:45) tport_vsend returned 566
2017-07-06 15:02:37.375862+0300 restcomm-olympus[766:252259] (common.m:45) send 566 bytes to tcp/[192.168.2.24]:5080 at 12:02:37.374876:
   ------------------------------------------------------------------------
2017-07-06 15:02:37.382023+0300 restcomm-olympus[766:252259] (common.m:45)    BYE sip:bob@192.168.2.24:5080;transport=tcp SIP/2.0
2017-07-06 15:02:37.382254+0300 restcomm-olympus[766:252259] (common.m:45)    Via: SIP/2.0/TCP 192.168.2.16:54651;branch=z9hG4bK0Uj86j2FFBFZF
2017-07-06 15:02:37.382457+0300 restcomm-olympus[766:252259] (common.m:45)    Max-Forwards: 70
2017-07-06 15:02:37.382654+0300 restcomm-olympus[766:252259] (common.m:45)    From: <sip:alice@192.168.2.16:54652>;tag=61H4g875mmatS
2017-07-06 15:02:37.382853+0300 restcomm-olympus[766:252259] (common.m:45)    To: "bob" <sip:bob@192.168.2.24:5080>;tag=82888136_f551bd97_57a5b08a_bcd00b30
2017-07-06 15:02:37.383046+0300 restcomm-olympus[766:252259] (common.m:45)    Call-ID: 80f17e4edb7113d29d8c2add019a2c40@192.168.2.24
2017-07-06 15:02:37.383238+0300 restcomm-olympus[766:252259] (common.m:45)    CSeq: 109335214 BYE
2017-07-06 15:02:37.383429+0300 restcomm-olympus[766:252259] (common.m:45)    User-Agent: TelScale Restcomm iOS Client #BASE_VERSION-#VERSION_SUFFIX+#BUILD
2017-07-06 15:02:37.387514+0300 restcomm-olympus[766:252259] (common.m:45)    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE
2017-07-06 15:02:37.387633+0300 restcomm-olympus[766:252259] (common.m:45)    Supported: timer, 100rel
2017-07-06 15:02:37.387742+0300 restcomm-olympus[766:252259] (common.m:45)    Content-Length: 0
2017-07-06 15:02:37.387846+0300 restcomm-olympus[766:252259] (common.m:45)
2017-07-06 15:02:37.387948+0300 restcomm-olympus[766:252259] (common.m:45)    ------------------------------------------------------------------------
2017-07-06 15:02:37.388055+0300 restcomm-olympus[766:252259] (common.m:45) tport(0x11fd119a0): reset timer
2017-07-06 15:02:37.388435+0300 restcomm-olympus[766:252259] (common.m:45) nta: sent BYE (109335214) to */192.168.2.24:5080
2017-07-06 15:02:37.388560+0300 restcomm-olympus[766:252259] (common.m:45) tport_pend(0x11fd119a0): pending 0x11fe570e0 for tcp/192.168.2.24:5080 (already 1)
2017-07-06 15:02:37.391386+0300 restcomm-olympus[766:252259] (common.m:45) nua: nua_invite_server_respond: entering
2017-07-06 15:02:37.391520+0300 restcomm-olympus[766:252259] (common.m:45) tport_tsend(0x11fd119a0) tpn = TCP/192.168.2.24:5080
2017-07-06 15:02:37.391886+0300 restcomm-olympus[766:252259] (common.m:45) tport_vsend(0x11fd119a0): 564 bytes of 564 to tcp/192.168.2.24:5080
2017-07-06 15:02:37.392022+0300 restcomm-olympus[766:252259] (common.m:45) tport_vsend returned 564
2017-07-06 15:02:37.392226+0300 restcomm-olympus[766:252259] (common.m:45) send 564 bytes to tcp/[192.168.2.24]:5080 at 12:02:37.391643:
   ------------------------------------------------------------------------
2017-07-06 15:02:37.392838+0300 restcomm-olympus[766:252259] (common.m:45)    SIP/2.0 486 Busy Here
2017-07-06 15:02:37.393044+0300 restcomm-olympus[766:252259] (common.m:45)    Via: SIP/2.0/TCP 192.168.2.24:5080;branch=z9hG4bKbcd00b30_57a5b08a_74fa5513-17cb-48cf-aa53-dff6916035b9;rport=5080
2017-07-06 15:02:37.393434+0300 restcomm-olympus[766:252259] (common.m:45)    From: "bob" <sip:bob@192.168.2.24:5080>;tag=82888136_f551bd97_57a5b08a_bcd00b30
2017-07-06 15:02:37.393561+0300 restcomm-olympus[766:252259] (common.m:45)    To: <sip:alice@192.168.2.16:54652>;tag=61H4g875mmatS
2017-07-06 15:02:37.394179+0300 restcomm-olympus[766:252259] (common.m:45)    Call-ID: 80f17e4edb7113d29d8c2add019a2c40@192.168.2.24
2017-07-06 15:02:37.394544+0300 restcomm-olympus[766:252259] (common.m:45)    CSeq: 1 INVITE
2017-07-06 15:02:37.394843+0300 restcomm-olympus[766:252259] (common.m:45)    User-Agent: TelScale Restcomm iOS Client #BASE_VERSION-#VERSION_SUFFIX+#BUILD
2017-07-06 15:02:37.395035+0300 restcomm-olympus[766:252259] (common.m:45)    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE
2017-07-06 15:02:37.395673+0300 restcomm-olympus[766:252259] (common.m:45)    Supported: timer, 100rel
2017-07-06 15:02:37.395787+0300 restcomm-olympus[766:252259] (common.m:45)    Content-Length: 0
2017-07-06 15:02:37.396036+0300 restcomm-olympus[766:252259] (common.m:45)
2017-07-06 15:02:37.396252+0300 restcomm-olympus[766:252259] (common.m:45)    ------------------------------------------------------------------------
2017-07-06 15:02:37.396695+0300 restcomm-olympus[766:252259] (common.m:45) tport(0x11fd119a0): reset timer
2017-07-06 15:02:37.396810+0300 restcomm-olympus[766:252259] (common.m:45) nta: sent 486 Busy Here for INVITE (1)
2017-07-06 15:02:37.397172+0300 restcomm-olympus[766:252259] (common.m:45) nta: timer H fired, timeout and terminate 486 response
2017-07-06 15:02:37.397521+0300 restcomm-olympus[766:252259] (common.m:45) nua: process_ack_or_cancel: entering
2017-07-06 15:02:37.397942+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): event i_error 408 ACK Timeout
2017-07-06 15:02:37.398218+0300 restcomm-olympus[766:252259] (common.m:45) nua(0x11fd2c490): call state changed: terminating -> terminating
2017-07-06 15:02:37.398406+0300 restcomm-olympus[766:252259] (common.m:45) nta: incoming_free(0x11fd30d60)
2017-07-06 15:02:37.398967+0300 restcomm-olympus[766:252259] (common.m:45) nta_incoming_timer: 0/1 resent, 1/1 tout, 0/0 term, 0/1 free
2017-07-06 15:02:37.399297+0300 restcomm-olympus[766:252259] (common.m:45) nta: timer set next to 7995 ms
2017-07-06 15:02:37.399925+0300 restcomm-olympus[766:252259] (common.m:45) tport_wakeup(0x11fd119a0): events IN
2017-07-06 15:02:37.400167+0300 restcomm-olympus[766:252259] (common.m:45) tport_recv_event(0x11fd119a0)
2017-07-06 15:02:37.400617+0300 restcomm-olympus[766:252259] (common.m:45) tport_recv_iovec(0x11fd119a0) msg 0x11fe81580 from (tcp/192.168.2.24:5080) has 828 bytes, veclen = 1
2017-07-06 15:02:37.401159+0300 restcomm-olympus[766:252259] (common.m:45) recv 355 bytes from tcp/[192.168.2.24]:5080 at 12:02:37.400723:
@atsakiridis atsakiridis added the bug label Jul 6, 2017
@atsakiridis atsakiridis added this to the 1.0.0 BETA6 - Sprint 1 milestone Jul 6, 2017
@atsakiridis atsakiridis modified the milestones: 1.0.0 BETA6 - Sprint 1, Upcoming Sep 26, 2017
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
Upcoming
Development

No branches or pull requests
1 participant
@atsakiridis