drachtio / drachtio-rtpengine-webrtcproxy

Webrtc proxy server built using drachtio (SIP Proxy) and rtpengine (RTP)
MIT License
39 stars 19 forks source link

Not getting audio #23

Open philjones88 opened 1 year ago

philjones88 commented 1 year ago

Using:

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 commented 1 year ago

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.