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

Not getting audio #23

Open
philjones88 opened this issue Nov 8, 2023 · 1 comment
Open

Not getting audio #23

philjones88 opened this issue Nov 8, 2023 · 1 comment

Comments

@philjones88
Copy link

philjones88 commented Nov 8, 2023 •
edited
Loading

Using:

  • Drachtio Docker image latest (ports exposed)
  • RTPEngine Docker image (built from latest) (in priviledged and net host mode)
  • SIP.js (using SimpleUser demo code)

Setup is:

It connects ok (not 100% sure but it seems audio/video streams don't connect) but don't get audio from the other end.

RTP Engine logs:

[1699451602.709274] INFO: [fumf5g6k29c09v52od90]: [control] Received command 'offer' from 10.10.60.2:57150
[1699451602.709440] NOTICE: [fumf5g6k29c09v52od90]: [core] Creating new call
[1699451602.711432] INFO: [fumf5g6k29c09v52od90]: [control] Replying to 'offer' from 10.10.60.2:57150 (elapsed time 0.002122 sec)
[1699451602.725873] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [ice] ICE negotiated: new peer for component 1 is 10.10.60.2:49617
[1699451602.725889] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [ice] ICE negotiated: local interface 192.168.4.31
[1699451602.889996] INFO: [fumf5g6k29c09v52od90]: [control] Received command 'answer' from 10.10.60.2:57150
[1699451602.890221] INFO: [fumf5g6k29c09v52od90]: [control] Replying to 'answer' from 10.10.60.2:57150 (elapsed time 0.000208 sec)
[1699451602.929821] ERR: [fumf5g6k29c09v52od90/72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660/1 port 31146]: [srtp] SRTP output wanted, but no crypto suite was negotiated
[1699451603.043088] ERR: [fumf5g6k29c09v52od90/72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660/1 port 31147]: [rtcp] SRTCP output wanted, but no crypto suite was negotiated
[1699451603.049972] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [ice] ICE negotiated: peer for component 1 is 10.10.60.2:49617
[1699451603.049996] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [ice] ICE negotiated: local interface 192.168.4.31
[1699451603.860787] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [crypto] DTLS: Peer certificate accepted
[1699451603.875979] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [crypto] DTLS-SRTP successfully negotiated using AEAD_AES_128_GCM
[1699451603.876016] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [crypto] DTLS-SRTP successfully negotiated using AEAD_AES_128_GCM
[1699451604.209920] INFO: [control] Received command 'ping' from 10.10.60.2:57150
[1699451604.209948] INFO: [control] Replying to 'ping' from 10.10.60.2:57150 (elapsed time 0.000001 sec)
[1699451606.010200] INFO: [fumf5g6k29c09v52od90/72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660/1 port 31146]: [core] Confirmed peer address as 192.168.6.247:59278
[1699451606.056574] INFO: [fumf5g6k29c09v52od90/72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660/1 port 31147]: [core] Confirmed peer address as 192.168.6.247:59279
[1699451624.175464] INFO: [control] Received command 'ping' from 10.10.60.2:57150
[1699451624.175485] INFO: [control] Replying to 'ping' from 10.10.60.2:57150 (elapsed time 0.000000 sec)
[1699451644.197091] INFO: [control] Received command 'ping' from 10.10.60.2:57150
[1699451644.197118] INFO: [control] Replying to 'ping' from 10.10.60.2:57150 (elapsed time 0.000000 sec)
[1699451662.530506] INFO: [fumf5g6k29c09v52od90/10lp13pgu6/1 port 31470]: [crypto] DTLS peer has closed the connection
[1699451662.562528] INFO: [fumf5g6k29c09v52od90]: [control] Received command 'delete' from 10.10.60.2:57150
[1699451662.562554] INFO: [fumf5g6k29c09v52od90]: [core] Deleting entire call
[1699451662.562566] INFO: [fumf5g6k29c09v52od90]: [core] Final packet stats:
[1699451662.562572] INFO: [fumf5g6k29c09v52od90]: [core] --- Tag '10lp13pgu6', created 1:00 ago for branch ''
[1699451662.562577] INFO: [fumf5g6k29c09v52od90]: [core] ---     subscribed to '72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660'
[1699451662.562581] INFO: [fumf5g6k29c09v52od90]: [core] ---     subscription for '72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660'
[1699451662.562587] INFO: [fumf5g6k29c09v52od90]: [core] ------ Media #1 (audio over UDP/TLS/RTP/SAVPF) using unknown codec
[1699451662.562594] INFO: [fumf5g6k29c09v52od90]: [core] --------- Port    192.168.4.31:31470 <>      10.10.60.2:49617, SSRC 0, in 12 p, 156 b, 49 e, 0 ts, out 2975 p, 556204 b, 0 e
[1699451662.562600] INFO: [fumf5g6k29c09v52od90]: [core] --- Tag '72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660', created 1:00 ago for branch ''
[1699451662.562604] INFO: [fumf5g6k29c09v52od90]: [core] ---     subscribed to '10lp13pgu6'
[1699451662.562607] INFO: [fumf5g6k29c09v52od90]: [core] ---     subscription for '10lp13pgu6'
[1699451662.562615] INFO: [fumf5g6k29c09v52od90]: [core] ------ Media #1 (audio over RTP/AVP) using unknown codec
[1699451662.562620] INFO: [fumf5g6k29c09v52od90]: [core] --------- Port    192.168.4.31:31146 <>   192.168.6.247:59278, SSRC c6326932, in 2982 p, 512904 b, 0 e, 0 ts, out 0 p, 0 b, 0 e
[1699451662.562626] INFO: [fumf5g6k29c09v52od90]: [core] --------- Port    192.168.4.31:31147 <>   192.168.6.247:59279 (RTCP), SSRC c6326932, in 39 p, 2724 b, 0 e, 0 ts, out 12 p, 156 b, 0 e
[1699451662.562718] INFO: [fumf5g6k29c09v52od90]: [control] Replying to 'delete' from 10.10.60.2:57150 (elapsed time 0.000168 sec)

Drachtio server logs:

2023-11-08 13:54:22.527728 recv 357 bytes from wss/[10.10.60.2]:61179 at 13:54:22.527579:
BYE sips:192.168.4.31:5443;transport=wss SIP/2.0
Via: SIP/2.0/WSS 02lap1tktpg4.invalid;branch=z9hG4bK7363583
To: <sip:606355@cucm.lab>;tag=e4Zejgt1KF41j
From: "SIPjs" <sip:7070@cucm.lab>;tag=10lp13pgu6
CSeq: 2 BYE
Call-ID: fumf5g6k29c09v52od90
Max-Forwards: 70
Supported: outbound
User-Agent: SIP.js/0.21.1
Content-Length: 0

2023-11-08 13:54:22.527824 DrachtioController::processRequestInsideDialog
2023-11-08 13:54:22.527848 SipDialogController::processRequestInsideDialog: BYE irq 0x55fc78722a00
2023-11-08 13:54:22.527947 ClientController::addNetTransaction: transactionId b4bd6e52-0949-4cdb-bac1-85a4f3b55c23; size: 1
2023-11-08 13:54:22.527977 ClientController::removeDialog - after removing dialogs count is now: 1
2023-11-08 13:54:22.527990 SipDialogController::addIncomingRequestTransaction - adding transactionId b4bd6e52-0949-4cdb-bac1-85a4f3b55c23 for irq:0x55fc78722a00
2023-11-08 13:54:22.528003 SipDialogController::clearSipTimers for fumf5g6k29c09v52od90
2023-11-08 13:54:22.528019 No connected clients found to handle incoming cdr:stop request
2023-11-08 13:54:22.528031 SipDialog::~SipDialog - destroying sip dialog with call-id fumf5g6k29c09v52od90
2023-11-08 13:54:22.528316 Client::write_handler - wrote 563 bytes: system:0
2023-11-08 13:54:22.547513 Client::read_handler read: 23172203-2ef1-4735-ba43-6178d6eb8cec|sip|b4bd6e52-0949-4cdb-bac1-85a4f3b55c23|
SIP/2.0 200 OK
Call-ID: fumf5g6k29c09v52od90
cseq: 2 BYE
from: "SIPjs" <sip:7070@cucm.lab>;tag=10lp13pgu6
to: <sip:606355@cucm.lab>;tag=e4Zejgt1KF41j
Content-Length: 0


2023-11-08 13:54:22.547562 Client::processMessage - got request with 4 tokens
2023-11-08 13:54:22.547573 Client::processMessage - request id 23172203-2ef1-4735-ba43-6178d6eb8cec, request type: sip transaction id: b4bd6e52-0949-4cdb-bac1-85a4f3b55c23, dialog id:
2023-11-08 13:54:22.547585 ClientController::addApiRequest: clientMsgId 23172203-2ef1-4735-ba43-6178d6eb8cec; size: 1
2023-11-08 13:54:22.547686 SipDialogController::doRespondToSipRequest thread 140155270414016
2023-11-08 13:54:22.547736 SipDialogController::findAndRemoveTransactionIdForIncomingRequest - searching transactionId b4bd6e52-0949-4cdb-bac1-85a4f3b55c23
2023-11-08 13:54:22.547755 SipDialogController::doRespondToSipRequest found incoming transaction 0x55fc78722a00
2023-11-08 13:54:22.547790 SipTransport::getContactUri - created Contact header: sips:192.168.4.31:5443;transport=wss
2023-11-08 13:54:22.547858 makeTags - Adding well-known header 'Call-ID' with value 'fumf5g6k29c09v52od90'
2023-11-08 13:54:22.547873 makeTags - Adding well-known header 'cseq' with value '2 BYE'
2023-11-08 13:54:22.547882 makeTags - Adding well-known header 'from' with value '"SIPjs" <sip:7070@cucm.lab>;tag=10lp13pgu6'
2023-11-08 13:54:22.547891 makeTags - Adding well-known header 'to' with value '<sip:606355@cucm.lab>;tag=e4Zejgt1KF41j'
2023-11-08 13:54:22.548054 send 289 bytes to wss/[10.10.60.2]:61179 at 13:54:22.547950:
SIP/2.0 200 OK
Via: SIP/2.0/WSS 02lap1tktpg4.invalid;branch=z9hG4bK7363583;received=10.10.60.2;rport=61179
From: "SIPjs" <sip:7070@cucm.lab>;tag=10lp13pgu6
To: <sip:606355@cucm.lab>;tag=e4Zejgt1KF41j
Call-ID: fumf5g6k29c09v52od90
CSeq: 2 BYE
Content-Length: 0

2023-11-08 13:54:22.548085 nta.c:6985 incoming_reply() nta: sent 200 OK for BYE (2)
2023-11-08 13:54:22.548101 SipDialogController::doRespondToSipRequest destroying irq 0x55fc78722a00
2023-11-08 13:54:22.548118 ClientController::removeApiRequest: clientMsgId 23172203-2ef1-4735-ba43-6178d6eb8cec; size: 0
2023-11-08 13:54:22.548136 ClientController::removeNetTransaction: transactionId b4bd6e52-0949-4cdb-bac1-85a4f3b55c23; size: 0
2023-11-08 13:54:22.548220 Client::write_handler - wrote 480 bytes: system:0
2023-11-08 13:54:22.562521 Client::read_handler read: 4cc08bdb-1f9b-442e-a438-00941e538b39|sip||05dbbd76-f8e1-123c-6c8e-0242ac110002;from-tag=10lp13pgu6
BYE sip:placeholder SIP/2.0
Content-Length: 0


2023-11-08 13:54:22.562555 Client::processMessage - got request with 4 tokens
2023-11-08 13:54:22.562571 Client::processMessage - request id 4cc08bdb-1f9b-442e-a438-00941e538b39, request type: sip transaction id: , dialog id: 05dbbd76-f8e1-123c-6c8e-0242ac110002;from-tag=10lp13pgu6
2023-11-08 13:54:22.562583 Client::processMessage - sending a request inside a dialog (dialogId provided)
2023-11-08 13:54:22.562602 ClientController::addAppTransaction: transactionId 72ba2981-a29b-4b64-ba1f-bcaa23d31b14; size: 1
2023-11-08 13:54:22.562611 ClientController::addApiRequest: clientMsgId 4cc08bdb-1f9b-442e-a438-00941e538b39; size: 1
2023-11-08 13:54:22.562660 SipDialogController::doSendRequestInsideDialog dialog id: 05dbbd76-f8e1-123c-6c8e-0242ac110002;from-tag=10lp13pgu6
2023-11-08 13:54:22.562732 SipDialogController::doSendRequestInsideDialog - defaulting request uri to sip:606355@192.168.3.111:5060
2023-11-08 13:54:22.562745 DrachtioController::findTportForSubscription: no transport found for 606355@192.168.3.111
2023-11-08 13:54:22.562886 send 360 bytes to udp/[192.168.3.111]:5060 at 13:54:22.562821:
BYE sip:606355@192.168.3.111:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.31;rport;branch=z9hG4bKQ9pQmgmHXgS4N
Max-Forwards: 70
From: "SIPjs" <sip:7070@cucm.lab>;tag=10lp13pgu6
To: <sip:606355@cucm.lab>;tag=72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660
Call-ID: 05dbbd76-f8e1-123c-6c8e-0242ac110002
CSeq: 2 BYE
Content-Length: 0

2023-11-08 13:54:22.562913 SipDialogController::doSendRequestInsideDialog - created orq 0x55fc786f91e0 sending BYE to sip:606355@192.168.3.111:5060
2023-11-08 13:54:22.562935 SipDialogController::addRIP adding orq 0x55fc786f91e0
2023-11-08 13:54:22.562952 No connected clients found to handle incoming cdr:stop request
2023-11-08 13:54:22.562965 ClientController::removeApiRequest: clientMsgId 4cc08bdb-1f9b-442e-a438-00941e538b39; size: 0
2023-11-08 13:54:22.563058 Client::write_handler - wrote 551 bytes: system:0
2023-11-08 13:54:22.569338 recv 376 bytes from udp/[192.168.3.111]:5060 at 13:54:22.569245:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.4.31;rport;branch=z9hG4bKQ9pQmgmHXgS4N
From: "SIPjs" <sip:7070@cucm.lab>;tag=10lp13pgu6
To: <sip:606355@cucm.lab>;tag=72123~0d99a536-6d7b-446d-bc8e-6686089e1b7a-34217660
Date: Wed, 08 Nov 2023 13:54:22 GMT
Call-ID: 05dbbd76-f8e1-123c-6c8e-0242ac110002
Server: Cisco-CUCM12.5
CSeq: 2 BYE
Content-Length: 0

2023-11-08 13:54:22.569390 nta.c:3484 agent_recv_response() nta: received 200 OK for BYE (2)
2023-11-08 13:54:22.569415 SipDialogController::processResponseInsideDialog:
2023-11-08 13:54:22.569433 SipDialogController::findRIPByOrq orq 0x55fc786f91e0
2023-11-08 13:54:22.569449 SipDialogController::processResponseInsideDialog: found request for BYE sip status 200
2023-11-08 13:54:22.569485 ClientController::removeAppTransaction: transactionId 72ba2981-a29b-4b64-ba1f-bcaa23d31b14; size: 0
2023-11-08 13:54:22.569505 ClientController::removeDialog - after removing dialogs count is now: 0
2023-11-08 13:54:22.569523 SipDialogController::processResponseInsideDialog: clearing dialog after receiving response to BYE or notify w/ subscription-state terminated
2023-11-08 13:54:22.569542 SipDialogController::clearRIP clearing orq 0x55fc786f91e0
2023-11-08 13:54:22.569566 SipDialog::~SipDialog - destroying sip dialog with call-id 05dbbd76-f8e1-123c-6c8e-0242ac110002
2023-11-08 13:54:22.569681 Client::write_handler - wrote 568 bytes: system:0
2023-11-08 13:54:47.345697 DrachtioController::processWatchdogTimer
2023-11-08 13:54:47.345801 size of hash table for server-side transactions                  31
2023-11-08 13:54:47.345819 size of hash table for client-side transactions                  31
2023-11-08 13:54:47.345875 size of hash table for dialogs                                   31
2023-11-08 13:54:47.345883 number of server-side transactions in the hash table             0
2023-11-08 13:54:47.345900 number of client-side transactions in the hash table             0
2023-11-08 13:54:47.345914 number of dialogs in the hash table                              0
2023-11-08 13:54:47.345922 number of sip messages received                                  97
2023-11-08 13:54:47.345929 number of sip messages sent                                      72
2023-11-08 13:54:47.345940 number of sip requests received                                  41
2023-11-08 13:54:47.345958 number of sip requests sent                                      34
2023-11-08 13:54:47.345966 number of bad sip messages received                              0
2023-11-08 13:54:47.345974 number of bad sip requests received                              0
2023-11-08 13:54:47.345984 number of bad sip requests dropped                               0
2023-11-08 13:54:47.345992 number of bad sip reponses dropped                               0
2023-11-08 13:54:47.346000 number of client transactions created                            34
2023-11-08 13:54:47.346013 number of server transactions created                            27
2023-11-08 13:54:47.346021 number of in-dialog server transactions created                  8
2023-11-08 13:54:47.346059 number of server transactions that have received ack             3
2023-11-08 13:54:47.346074 number of server transactions that have received cancel          0
2023-11-08 13:54:47.346082 number of requests that were processed stateless                 30
2023-11-08 13:54:47.346095 number of requests converted to transactions by message callback 0
2023-11-08 13:54:47.346103 number of responses without matching request                     0
2023-11-08 13:54:47.346115 number of successful responses missing INVITE client transaction 0
2023-11-08 13:54:47.346123 number of requests merged by UAS                                 0
2023-11-08 13:54:47.346136 number of SIP responses sent by stack                            38
2023-11-08 13:54:47.346144 number of SIP requests retransmitted by stack                    0
2023-11-08 13:54:47.346157 number of SIP responses retransmitted by stack                   0
2023-11-08 13:54:47.346165 number of retransmitted SIP requests received by stack           0
2023-11-08 13:54:47.346177 number of SIP client transactions that has timeout               0
2023-11-08 13:54:47.346185 number of SIP server transactions that has timeout               0
2023-11-08 13:54:47.346198 SipDialogController storage counts
2023-11-08 13:54:47.346212 ----------------------------------
2023-11-08 13:54:47.346220 IIP size:                                                0
2023-11-08 13:54:47.346235 StableDialogs total size:                                                0
2023-11-08 13:54:47.346248 StableDialogs uac:                                                       0
2023-11-08 13:54:47.346256 StableDialogs uas:                                                       0
2023-11-08 13:54:47.346263 m_mapTransactionId2Irq size:                                     0
2023-11-08 13:54:47.346273 number of outgoing transactions held for timerD:                 0
2023-11-08 13:54:47.346281 number of outgoing transactions waiting for ACK from app:        0
2023-11-08 13:54:47.346294 general queue size:                                              0
2023-11-08 13:54:47.346303 timer A queue size:                                              0
2023-11-08 13:54:47.346315 timer B queue size:                                              0
2023-11-08 13:54:47.346324 timer C queue size:                                              0
2023-11-08 13:54:47.346336 timer D queue size:                                              0
2023-11-08 13:54:47.346345 timer E queue size:                                              0
2023-11-08 13:54:47.346358 timer F queue size:                                              0
2023-11-08 13:54:47.346366 timer G queue size:                                              0
2023-11-08 13:54:47.346378 timer K queue size:                                              0
2023-11-08 13:54:47.346387 ClientController storage counts
2023-11-08 13:54:47.346398 ----------------------------------
2023-11-08 13:54:47.346405 m_clients size:                                                  1
2023-11-08 13:54:47.346418 m_services size:                                                 0
2023-11-08 13:54:47.346426 m_request_types size:                                            4
2023-11-08 13:54:47.346433 m_map_of_request_type_offsets size:                              3
2023-11-08 13:54:47.346440 m_mapDialogs size:                                               0
2023-11-08 13:54:47.346454 m_mapNetTransactions size:                                       0
2023-11-08 13:54:47.346461 m_mapAppTransactions size:                                       0
2023-11-08 13:54:47.346474 m_mapApiRequests size:                                           0
2023-11-08 13:54:47.346482 m_mapDialogId2Appname size:                                      0
2023-11-08 13:54:47.346495 PendingRequestController storage counts
2023-11-08 13:54:47.346508 ----------------------------------
2023-11-08 13:54:47.346516 m_mapCallId2Invite size:                                         0
2023-11-08 13:54:47.346529 m_mapTxnId2Invite size:                                          0
2023-11-08 13:54:47.346538 SipProxyController storage counts
2023-11-08 13:54:47.346550 ----------------------------------
2023-11-08 13:54:47.346558 m_mapCallId2Proxy size:                                          0
2023-11-08 13:54:47.346571 m_mapNonce2Challenge size:                                       0
2023-11-08 13:54:47.346579 general queue size:                                              0
2023-11-08 13:54:47.346591 timer A queue size:                                              0
2023-11-08 13:54:47.346599 timer B queue size:                                              0
2023-11-08 13:54:47.346612 timer C queue size:                                              0
2023-11-08 13:54:47.346620 timer D queue size:                                              0
2023-11-08 13:54:47.346632 timer E queue size:                                              0
2023-11-08 13:54:47.346640 timer F queue size:                                              0
2023-11-08 13:54:47.346652 timer G queue size:                                              0
2023-11-08 13:54:47.346660 timer K queue size:                                              0
2023-11-08 13:54:47.346673 m_mapUri2InvalidData size:                                       8

Node app logs:

> node app.js

INFO: connected to drachtio listening for SIP on hostport wss/192.168.4.31:5443,wss/192.168.4.31:5443,tcp/192.168.4.31:5060,udp/192.168.4.31:5060,tcp/192.168.4.31:5060,udp/192.168.4.31:5060
    v: 1
DEBUG: outbound call to: sip:606355@cucm.lab
    callid: "fumf5g6k29c09v52od90"
    v: 1
INFO: received invite from tcp/10.10.60.2:sip:606355@cucm.lab with request uri sip:606355@cucm.lab
    callid: "fumf5g6k29c09v52od90"
    v: 1
DEBUG: response from rtpengine to offer
    callid: "fumf5g6k29c09v52od90"
    opts: {
      "call-id": "fumf5g6k29c09v52od90",
      "replace": [
        "origin",
        "session-connection"
      ],
      "transport protocol": "RTP/AVP",
      "DTLS": "off",
      "ICE": "remove",
      "rtcp-mux": [
        "demux"
      ],
      "flags": [
        "SDES-no"
      ],
      "from-tag": "10lp13pgu6",
      "direction": [
        "public",
        "private"
      ],
      "sdp": "v=0\r\no=mozilla...THIS_IS_SDPARTA-99.0 2738367214294939416 0 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=sendrecv\r\na=fingerprint:sha-256 1F:69:6F:65:34:D7:FA:A1:AB:41:FC:67:AD:C3:CE:F8:29:0D:3E:37:90:A0:65:9C:F6:AC:15:4B:CB:53:CB:31\r\na=group:BUNDLE 0\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\nm=audio 49617 UDP/TLS/RTP/SAVPF 109 9 0 8 101\r\nc=IN IP4 51.191.160.246\r\na=candidate:0 1 UDP 2122252543 10.10.60.2 49617 typ host\r\na=candidate:2 1 UDP 2122121471 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 59754 typ host\r\na=candidate:4 1 UDP 2122055935 192.168.64.1 52789 typ host\r\na=candidate:6 1 UDP 2122187007 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 58713 typ host\r\na=candidate:8 1 TCP 2105524479 10.10.60.2 9 typ host tcptype active\r\na=candidate:9 1 TCP 2105393407 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 9 typ host tcptype active\r\na=candidate:10 1 TCP 2105327871 192.168.64.1 9 typ host tcptype active\r\na=candidate:11 1 TCP 2105458943 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 9 typ host tcptype active\r\na=candidate:0 2 UDP 2122252542 10.10.60.2 51768 typ host\r\na=candidate:2 2 UDP 2122121470 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 62915 typ host\r\na=candidate:4 2 UDP 2122055934 192.168.64.1 61853 typ host\r\na=candidate:6 2 UDP 2122187006 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 60150 typ host\r\na=candidate:8 2 TCP 2105524478 10.10.60.2 9 typ host tcptype active\r\na=candidate:9 2 TCP 2105393406 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 9 typ host tcptype active\r\na=candidate:10 2 TCP 2105327870 192.168.64.1 9 typ host tcptype active\r\na=candidate:11 2 TCP 2105458942 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 9 typ host tcptype active\r\na=candidate:1 1 UDP 1686052863 51.191.160.246 49617 typ srflx raddr 10.10.60.2 rport 49617\r\na=candidate:1 2 UDP 1686052862 51.191.160.246 51768 typ srflx raddr 10.10.60.2 rport 51768\r\na=sendrecv\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=fmtp:101 0-15\r\na=ice-pwd:98df6a3b4c94b3301afde5be275340ed\r\na=ice-ufrag:ad4925e9\r\na=mid:0\r\na=msid:{7a54887a-cf57-4710-924f-b4a235ac58a1} {39297b5a-cf60-449a-81ab-c74aa7de7281}\r\na=rtcp:51768 IN IP4 51.191.160.246\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=setup:actpass\r\na=ssrc:1731254657 cname:{24598677-1e20-4366-8dc4-85288456a3c2}\r\n"
    }
    response: {
      "sdp": "v=0\r\no=mozilla...THIS_IS_SDPARTA-99.0 2738367214294939416 0 IN IP4 192.168.4.31\r\ns=-\r\nt=0 0\r\na=msid-semantic:WMS *\r\nm=audio 31146 RTP/AVP 109 9 0 8 101\r\nc=IN IP4 192.168.4.31\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=msid:{7a54887a-cf57-4710-924f-b4a235ac58a1} {39297b5a-cf60-449a-81ab-c74aa7de7281}\r\na=ssrc:1731254657 cname:{24598677-1e20-4366-8dc4-85288456a3c2}\r\na=mid:0\r\na=rtpmap:109 opus/48000/2\r\na=fmtp:109 stereo=1; useinbandfec=1; maxplaybackrate=48000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=sendrecv\r\na=rtcp:31147\r\n",
      "result": "ok"
    }
    v: 1
INFO: sending INVITE to B
    callid: "fumf5g6k29c09v52od90"
    callOpts: {
      "headers": {
        "CSeq": "1 INVITE"
      },
      "localSdpB": "v=0\r\no=mozilla...THIS_IS_SDPARTA-99.0 2738367214294939416 0 IN IP4 192.168.4.31\r\ns=-\r\nt=0 0\r\na=msid-semantic:WMS *\r\nm=audio 31146 RTP/AVP 109 9 0 8 101\r\nc=IN IP4 192.168.4.31\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=msid:{7a54887a-cf57-4710-924f-b4a235ac58a1} {39297b5a-cf60-449a-81ab-c74aa7de7281}\r\na=ssrc:1731254657 cname:{24598677-1e20-4366-8dc4-85288456a3c2}\r\na=mid:0\r\na=rtpmap:109 opus/48000/2\r\na=fmtp:109 stereo=1; useinbandfec=1; maxplaybackrate=48000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=sendrecv\r\na=rtcp:31147\r\n",
      "proxyRequestHeaders": [
        "from",
        "to",
        "proxy-authorization",
        "authorization",
        "supported",
        "allow",
        "content-type",
        "user-agent",
        "Diversion"
      ],
      "proxyResponseHeaders": [
        "proxy-authenticate",
        "www-authenticate",
        "accept",
        "allow",
        "allow-events"
      ]
    }
    v: 1
INFO: after adding call there are now 1 calls in progress
    callid: "fumf5g6k29c09v52od90"
    v: 1
INFO: call ended with normal termination, there are 0 active calls
    callid: "fumf5g6k29c09v52od90"
    v: 1

RTP Engine Codecs

root@cypress-runner:/# rtpengine --codecs
                PCMA: fully supported
                PCMU: fully supported
                G723: fully supported
                G722: fully supported
               QCELP: supported for decoding only
                G729: fully supported
               G729a: fully supported
               speex: fully supported
                 GSM: fully supported
                iLBC: supported for decoding only
                opus: fully supported
                 EVS: not supported
              vorbis: codec supported but lacks RTP definition
                 ac3: codec supported but lacks RTP definition
                eac3: codec supported but lacks RTP definition
              ATRAC3: supported for decoding only
             ATRAC-X: supported for decoding only
                EVRC: supported for decoding only
               EVRC0: supported for decoding only
               EVRC1: supported for decoding only
                 AMR: fully supported
              AMR-WB: fully supported
     telephone-event: fully supported
                  CN: fully supported
           PCM-S16LE: codec supported but lacks RTP definition
              PCM-U8: codec supported but lacks RTP definition
                 MP3: codec supported but lacks RTP definition
@philjones88
Copy link
Author

philjones88 commented Nov 8, 2023 •
edited
Loading

Not the same call. The SIP messages SIPjs generates/receives

REGISTER sip:cucm.lab SIP/2.0
Via: SIP/2.0/WSS hlgd63mgkb2c.invalid;branch=z9hG4bK3088947
To: <sip:7070@cucm.lab>
From: "SIPjs" <sip:7070@cucm.lab>;tag=mluli4q858
CSeq: 3 REGISTER
Call-ID: dsgsqaem2o518689kq57
Max-Forwards: 70
Authorization: Digest algorithm=MD5, username="7070", realm="ccmsipline", nonce="xxxx", uri="sip:cucm.lab", response="xxxx"
Contact: <sip:7070@hlgd63mgkb2c.invalid;transport=ws>;expires=3600
Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
Supported: outbound, path, gruu
User-Agent: SIP.js/0.21.1
Content-Length: 0


SIP/2.0 200 OK
Via: SIP/2.0/WSS hlgd63mgkb2c.invalid;branch=z9hG4bK3088947;received=10.10.60.2;rport=62779
From: "SIPjs" <sip:7070@cucm.lab>;tag=mluli4q858
To: <sip:7070@cucm.lab>;tag=Ha1FpF4QSZeXa
Call-ID: dsgsqaem2o518689kq57
CSeq: 3 REGISTER
Contact: <sip:7070@hlgd63mgkb2c.invalid;transport=ws>;expires=3600
Content-Length: 0


INVITE sip:606355@cucm.lab SIP/2.0
Via: SIP/2.0/WSS hlgd63mgkb2c.invalid;branch=z9hG4bK1726309
To: <sip:606355@cucm.lab>
From: "SIPjs" <sip:7070@cucm.lab>;tag=1k8erfqkac
CSeq: 1 INVITE
Call-ID: dsgsq7l24r33ent7pcdt
Max-Forwards: 70
Contact: <sip:7070@hlgd63mgkb2c.invalid;transport=ws;ob>
Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
Supported: outbound
User-Agent: SIP.js/0.21.1
Content-Type: application/sdp
Content-Length: 2488

v=0
o=mozilla...THIS_IS_SDPARTA-99.0 8614063317839580609 0 IN IP4 0.0.0.0
s=-
t=0 0
a=sendrecv
a=fingerprint:sha-256 6F:7E:57:E7:BB:9F:33:D6:18:A0:D2:B2:7F:DA:BC:A5:33:72:E9:51:BD:12:01:C5:24:F8:25:CA:5C:79:49:28
a=group:BUNDLE 0
a=ice-options:trickle
a=msid-semantic:WMS *
m=audio 51539 UDP/TLS/RTP/SAVPF 109 9 0 8 101
c=IN IP4 51.x.x.x
a=candidate:0 1 UDP 2122252543 10.10.60.2 51539 typ host
a=candidate:2 1 UDP 2122121471 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 59684 typ host
a=candidate:4 1 UDP 2122055935 192.168.64.1 50365 typ host
a=candidate:6 1 UDP 2122187007 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 57141 typ host
a=candidate:8 1 TCP 2105524479 10.10.60.2 9 typ host tcptype active
a=candidate:9 1 TCP 2105393407 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 9 typ host tcptype active
a=candidate:10 1 TCP 2105327871 192.168.64.1 9 typ host tcptype active
a=candidate:11 1 TCP 2105458943 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 9 typ host tcptype active
a=candidate:0 2 UDP 2122252542 10.10.60.2 59192 typ host
a=candidate:2 2 UDP 2122121470 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 53381 typ host
a=candidate:4 2 UDP 2122055934 192.168.64.1 63504 typ host
a=candidate:6 2 UDP 2122187006 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 58533 typ host
a=candidate:8 2 TCP 2105524478 10.10.60.2 9 typ host tcptype active
a=candidate:9 2 TCP 2105393406 2a01:4c8:e000:1190:986d:8dd5:bf22:d5d3 9 typ host tcptype active
a=candidate:10 2 TCP 2105327870 192.168.64.1 9 typ host tcptype active
a=candidate:11 2 TCP 2105458942 fdd9:15f2:f672:afd8:453:6510:7dc4:c8d7 9 typ host tcptype active
a=candidate:1 1 UDP 1686052863 51.x.x.x 51539 typ srflx raddr 10.10.60.2 rport 51539
a=candidate:1 2 UDP 1686052862 51.x.x.x 59192 typ srflx raddr 10.10.60.2 rport 59192
a=sendrecv
a=end-of-candidates
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-audio-level
a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid
a=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1
a=fmtp:101 0-15
a=ice-pwd:dcb19a4c09aa4e65d04fcbd33882026f
a=ice-ufrag:886882ce
a=mid:0
a=msid:{88d1ba99-1802-4ff9-b64e-6664ab31b346} {f4f9622d-9ff6-464a-93cf-c03bf26a1397}
a=rtcp:59192 IN IP4 51.x.x.x
a=rtcp-mux
a=rtpmap:109 opus/48000/2
a=rtpmap:9 G722/8000/1
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=setup:actpass
a=ssrc:2181697991 cname:{3fbb5a8b-683a-4fc8-a87d-beee089e8463}


SIP/2.0 100 Trying
Via: SIP/2.0/WSS hlgd63mgkb2c.invalid;branch=z9hG4bK1726309;received=10.10.60.2;rport=62779
From: "SIPjs" <sip:7070@cucm.lab>;tag=1k8erfqkac
To: <sip:606355@cucm.lab>
Call-ID: dsgsq7l24r33ent7pcdt
CSeq: 1 INVITE
Content-Length: 0


SIP/2.0 180 Ringing
Via: SIP/2.0/WSS hlgd63mgkb2c.invalid;branch=z9hG4bK1726309;received=10.10.60.2;rport=62779
From: "SIPjs" <sip:7070@cucm.lab>;tag=1k8erfqkac
To: <sip:606355@cucm.lab>;tag=jKt8QaNUp84Fp
Call-ID: dsgsq7l24r33ent7pcdt
CSeq: 1 INVITE
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
Allow-Events: presence
Content-Length: 0


SIP/2.0 200 OK
Via: SIP/2.0/WSS hlgd63mgkb2c.invalid;branch=z9hG4bK1726309;received=10.10.60.2;rport=62779
From: "SIPjs" <sip:7070@cucm.lab>;tag=1k8erfqkac
To: <sip:606355@cucm.lab>;tag=jKt8QaNUp84Fp
Call-ID: dsgsq7l24r33ent7pcdt
CSeq: 1 INVITE
Contact: <sips:192.168.4.31:5443;transport=wss>
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
Allow-Events: presence
Content-Type: application/sdp
Content-Length: 616

v=0
o=CiscoSystemsCCM-SIP 72421 1 IN IP4 192.168.4.31
s=SIP Call
c=IN IP4 192.168.4.31
b=TIAS:256000
b=AS:272
t=0 0
m=audio 31005 UDP/TLS/RTP/SAVPF 101
a=mid:0
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:31005
a=rtcp-mux
a=setup:active
a=fingerprint:sha-256 47:5A:1F:1A:70:D6:92:24:0D:3E:D0:B7:AE:0F:64:EB:CD:07:B3:B5:61:7C:97:93:F2:B9:34:E2:07:76:62:76
a=tls-id:2c839787edf0acfa0178edac6f6adfb2
a=ice-ufrag:IC0tZWzZ
a=ice-pwd:P92DI6R9ayJwtEwHcBMRnXa2h6
a=ice-options:trickle
a=candidate:wFCsocUfA7wrKaYM 1 UDP 2130706431 192.168.4.31 31005 typ host
a=end-of-candidates


ACK sips:192.168.4.31:5443;transport=wss SIP/2.0
Via: SIP/2.0/WSS hlgd63mgkb2c.invalid;branch=z9hG4bK1468061
To: <sip:606355@cucm.lab>;tag=jKt8QaNUp84Fp
From: "SIPjs" <sip:7070@cucm.lab>;tag=1k8erfqkac
CSeq: 1 ACK
Call-ID: dsgsq7l24r33ent7pcdt
Max-Forwards: 70
Supported: outbound
User-Agent: SIP.js/0.21.1
Content-Length: 0


BYE sip:7070@hlgd63mgkb2c.invalid;transport=ws;ob SIP/2.0
Via: SIP/2.0/WSS 192.168.4.31:5443;branch=z9hG4bKHr5ZU8e7epUKK
Max-Forwards: 70
From: <sip:606355@cucm.lab>;tag=jKt8QaNUp84Fp
To: "SIPjs" <sip:7070@cucm.lab>;tag=1k8erfqkac
Call-ID: dsgsq7l24r33ent7pcdt
CSeq: 2 BYE
Content-Length: 0

I did notice SIPjs log out:

sip.user-agent-client | Non-100 1xx INVITE response received without a Contact header field, dropping.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@philjones88