No audio in call between Jigasi & Asterisk

s0d · September 15, 2022, 4:41am

Hello,

I am trying to connect Jigasi and Asterisk. I am running jitsi using docker. The image is docker-jitsi-meet-stable-7648-4. The problem I am facing is when I do outgoing call inside meeting, the asterisk extension rings, and I can accept the call. But, after accepting the call no audio is transmitted to either side.

I am new to jitsi and I have read other posts about this but I couldn’t get it work. It would be a great help if anybody could help me on this.

Here is my .env config:

DOCKER_HOST_ADDRESS=MY_PUBLIC_IP
...
JIGASI_SIP_URI=jitsi@my.asterisk.domain.com
JIGASI_SIP_PASSWORD=MY_PASSWORD
JIGASI_SIP_SERVER=my.asterisk.domain.com
JIGASI_SIP_PORT=5060
JIGASI_SIP_TRANSPORT=UDP
JIGASI_SIP_DEFAULT_ROOM=myroom

sip-communicator.properties is as default.

Here is my asterisk pjsip.conf:

[jitsi]
type=endpoint
transport=transport-udp
context=from-extensions
disallow=all
allow=ulaw,alaw
auth=jitsi
aors=jitsi
rtp_symmetric=yes
force_rport=yes
rewrite_contact=yes  ; necessary if endpoint does not know/register public ip:port
ice_support=yes   ;This is specific to clients that support NAT traversal
                   ;for media via ICE,STUN,TURN. See the wiki at:
                   ;https://wiki.asterisk.org/wiki/x/D4FHAQ
                   ;for a deeper explanation of this topic.
[jitsi]
type=aor
max_contacts=1
remove_existing=yes     ; Allow a registration to succeed by displacing any existing
                        ; contacts that now exceed the max_contacts count.  Any
                        ; removed contacts are the next to expire.  The behaviour is
                        ; beneficial when rewrite_contact is enabled and max_contacts
                        ; is greater than one.  The removed contact is likely the old
                        ; contact created by rewrite_contact that the device is
                        ; refreshing.
                        ; (default: "no")

Here is my asterisk log during outgoing call:

<--- Transmitting SIP request (439 bytes) to UDP:MY_PUBLIC_IP:54185 --->
ACK sip:500@MY_PUBLIC_IP:54185;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 18.220.96.228:5060;rport;branch=z9hG4bKPj666a73de-21e8-46d4-b1b0-fd5ebee080b4
From: "jitsi" <sip:jitsi@172.31.11.212>;tag=56bf6f74-0063-4521-adca-39fa2a6fcb5d
To: <sip:500@MY_PUBLIC_IP;rinstance=b9ec0bdb8df49409>;tag=0a894655
Call-ID: 5ebe9886-1689-40f9-b6e4-d65ee86b9bf0
CSeq: 6261 ACK
Max-Forwards: 70
User-Agent: Asterisk PBX 18.12.1
Content-Length:  0


    -- PJSIP/500-00000001 answered PJSIP/jitsi-00000000
       > 0x7f009804b520 -- Strict RTP learning after remote address set to: 172.21.0.5:20007
<--- Transmitting SIP response (877 bytes) to UDP:103.168.56.74:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.21.0.5:5060;rport=5060;received=103.168.56.74;branch=z9hG4bK-353732-f31f24670764825b33b6eaafb1c40685
Call-ID: c99bd0f2c497bffabe0752f87604e480@0.0.0.0
From: "jitsi" <sip:jitsi@my.asterisk.domain.com>;tag=73eb2ee6
To: <sip:500@my.asterisk.domain.com>;tag=80c24616-c51c-409a-b186-3776e3d5d7c0
CSeq: 2 INVITE
Server: Asterisk PBX 18.12.1
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Contact: <sip:18.220.96.228:5060>
Supported: 100rel, timer, replaces, norefersub
Content-Type: application/sdp
Content-Length:   245

v=0
o=- 0 2 IN IP4 18.220.96.228
s=Asterisk
c=IN IP4 18.220.96.228
t=0 0
m=audio 11540 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

    -- Channel PJSIP/500-00000001 joined 'simple_bridge' basic-bridge <8a1a805c-36ae-4e49-bdc0-c4e61c73066f>
    -- Channel PJSIP/jitsi-00000000 joined 'simple_bridge' basic-bridge <8a1a805c-36ae-4e49-bdc0-c4e61c73066f>
       > Bridge 8a1a805c-36ae-4e49-bdc0-c4e61c73066f: switching from simple_bridge technology to native_rtp
       > Locally RTP bridged 'PJSIP/jitsi-00000000' and 'PJSIP/500-00000001' in stack
       > 0x7f0098082610 -- Strict RTP switching to RTP target address MY_PUBLIC_IP:52341 as source
<--- Received SIP request (782 bytes) from UDP:103.168.56.74:5060 --->
ACK sip:18.220.96.228:5060 SIP/2.0
Call-ID: c99bd0f2c497bffabe0752f87604e480@0.0.0.0
CSeq: 2 ACK
Via: SIP/2.0/UDP 172.21.0.5:5060;branch=z9hG4bK-353732-e0dd3a519cd37784e3c1edc473998bc1
From: "jitsi" <sip:jitsi@my.asterisk.domain.com>;tag=73eb2ee6
To: <sip:500@my.asterisk.domain.com>;tag=80c24616-c51c-409a-b186-3776e3d5d7c0
Max-Forwards: 70
Authorization: Digest username="jitsi",realm="asterisk",nonce="1663214636/d86426bf0289458c3054d5b1f285986c",uri="sip:500@my.asterisk.domain.com",response="e6cefc34619a5bb6f289fa5aa341dd67",algorithm=MD5,opaque="0a43c61c74db1e97",qop=auth,cnonce="xyz",nc=00000001
Contact: "jitsi" <sip:jitsi@172.21.0.5:5060;transport=udp;registering_acc=asterisk_monosolution_co>
User-Agent: Jigasi1.1.265-g4049f97Linux
Content-Length: 0


       > 0x7f009804b520 -- Strict RTP qualifying stream type: audio
       > 0x7f0098082610 -- Strict RTP learning complete - Locking on source address MY_PUBLIC_IP:52341
<--- Received SIP request (918 bytes) from UDP:MY_PUBLIC_IP:54185 --->
REGISTER sip:my.asterisk.domain.com;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 10.0.1.192:54185;branch=z9hG4bK-524287-1---e7b51536e3231869;rport
Max-Forwards: 70
Contact: <sip:500@MY_PUBLIC_IP:54185;transport=UDP;rinstance=b9ec0bdb8df49409>
To: "500"<sip:500@my.asterisk.domain.com;transport=UDP>
From: "500"<sip:500@my.asterisk.domain.com;transport=UDP>;tag=dceabd62
Call-ID: nxgm4DtG-JYWnSJSIOVSxw..
CSeq: 10 REGISTER
Expires: 60
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
User-Agent: Zoiper v2.10.17.3
Authorization: Digest username="500",realm="asterisk",nonce="1663214590/826b40822d49e848e0f7e12651ea3c2a",uri="sip:my.asterisk.domain.com;transport=UDP",response="2688f93549eb3a259e2f1c00473c0dff",cnonce="d53d0142e028bc1635416671e1eb138e",nc=00000003,qop=auth,algorithm=MD5,opaque="506dc5a67bcbfaa5"
Allow-Events: presence, kpml, talk
Content-Length: 0


<--- Transmitting SIP response (549 bytes) to UDP:MY_PUBLIC_IP:54185 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.0.1.192:54185;rport=54185;received=MY_PUBLIC_IP;branch=z9hG4bK-524287-1---e7b51536e3231869
Call-ID: nxgm4DtG-JYWnSJSIOVSxw..
From: "500" <sip:500@my.asterisk.domain.com>;tag=dceabd62
To: "500" <sip:500@my.asterisk.domain.com>;tag=z9hG4bK-524287-1---e7b51536e3231869
CSeq: 10 REGISTER
WWW-Authenticate: Digest realm="asterisk",nonce="1663214646/c452fd28d101fa409d73038d17c5367f",opaque="1e45426469ff6a1a",stale=true,algorithm=MD5,qop="auth"
Server: Asterisk PBX 18.12.1
Content-Length:  0


<--- Received SIP request (918 bytes) from UDP:MY_PUBLIC_IP:54185 --->
REGISTER sip:my.asterisk.domain.com;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 10.0.1.192:54185;branch=z9hG4bK-524287-1---7a35a738732322fd;rport
Max-Forwards: 70
Contact: <sip:500@MY_PUBLIC_IP:54185;transport=UDP;rinstance=b9ec0bdb8df49409>
To: "500"<sip:500@my.asterisk.domain.com;transport=UDP>
From: "500"<sip:500@my.asterisk.domain.com;transport=UDP>;tag=dceabd62
Call-ID: nxgm4DtG-JYWnSJSIOVSxw..
CSeq: 11 REGISTER
Expires: 60
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
User-Agent: Zoiper v2.10.17.3
Authorization: Digest username="500",realm="asterisk",nonce="1663214646/c452fd28d101fa409d73038d17c5367f",uri="sip:my.asterisk.domain.com;transport=UDP",response="da6963d399e3b9fb8629fa9126ce0fc2",cnonce="04a5aa091a96f7b51c9c3bba227d11fb",nc=00000001,qop=auth,algorithm=MD5,opaque="1e45426469ff6a1a"
Allow-Events: presence, kpml, talk
Content-Length: 0

Here is my jitsi log during outgoing call:

jvb_1      | JVB 2022-09-15 04:12:18.823 INFO: [158] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] EndpointConnectionStatusMonitor.start#57: Starting connection status monitor
jvb_1      | JVB 2022-09-15 04:12:18.824 INFO: [158] Videobridge.createConference#303: create_conf, id=b1056a1d12e68bd9 meetingId=f5af9f73-232e-4404-b437-89f7af56ae55
jvb_1      | JVB 2022-09-15 04:12:18.835 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.gatherCandidates#647: Gathering candidates for component stream-59d694d9.RTP.
jicofo_1   | Jicofo 2022-09-15 04:12:18.859 INFO: [70] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] ColibriV2SessionManager.allocate#296: Selected 814ac84482e6, session exists: true
jvb_1      | JVB 2022-09-15 04:12:18.890 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9] Endpoint.<init>#296: Created new endpoint isUsingSourceNames=false, iceControlling=true
jicofo_1   | Jicofo 2022-09-15 04:12:18.922 INFO: [69] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55 participant=59d694d9] ParticipantInviteRunnable.doInviteOrReinvite#379: Sending session-initiate to: sincerestrandsshelteraccidentally@muc.meet.jitsi/59d694d9 sources={jvb=[audio=[2191341485], video=[3065097952], groups=[]]}
jvb_1      | JVB 2022-09-15 04:12:18.917 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.gatherCandidates#647: Gathering candidates for component stream-bbbb93df.RTP.
jvb_1      | JVB 2022-09-15 04:12:18.962 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df] Endpoint.<init>#296: Created new endpoint isUsingSourceNames=true, iceControlling=true
jicofo_1   | Jicofo 2022-09-15 04:12:18.974 INFO: [70] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55 participant=bbbb93df] ParticipantInviteRunnable.doInviteOrReinvite#379: Sending session-initiate to: sincerestrandsshelteraccidentally@muc.meet.jitsi/bbbb93df sources={jvb=[audio=[2191341485], video=[3065097952], groups=[]]}
web_1      | 103.80.211.50 - - [15/Sep/2022:04:12:19 +0000] "GET /sounds/joined.wav HTTP/2.0" 206 42156 "https://meet2.monosolution.co/SincereStrandsShelterAccidentally" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36"
jicofo_1   | Jicofo 2022-09-15 04:12:19.363 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] ColibriV2SessionManager.updateParticipant#473: Updating Participant[sincerestrandsshelteraccidentally@muc.meet.jitsi/59d694d9]@1666110602 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@55779cc6, sources=null
jvb_1      | JVB 2022-09-15 04:12:19.367 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f name=stream-59d694d9 componentId=1] Component.addRemoteCandidate#328: Add remote candidate for stream-59d694d9.RTP: 172.21.0.5:20009/udp/host
jvb_1      | JVB 2022-09-15 04:12:19.367 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f] IceTransport.startConnectivityEstablishment#192: Starting the agent with remote candidates.
jvb_1      | JVB 2022-09-15 04:12:19.367 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
jvb_1      | JVB 2022-09-15 04:12:19.367 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.initCheckLists#972: Init checklist for stream stream-59d694d9
jvb_1      | JVB 2022-09-15 04:12:19.367 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.setState#946: ICE state changed from Waiting to Running.
jvb_1      | JVB 2022-09-15 04:12:19.367 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f] IceTransport.iceStateChanged#341: ICE state changed old=Waiting new=Running
jvb_1      | JVB 2022-09-15 04:12:19.367 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
jvb_1      | JVB 2022-09-15 04:12:19.407 INFO: [168] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.startCheckForPair#350: Could not start connectivity check: No socket found for 172.21.0.6:10000/udp->172.21.0.5:20009/udp
jvb_1      | JVB 2022-09-15 04:12:19.433 INFO: [166] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 172.21.0.6:10000/udp/host -> 172.21.0.5:20009/udp/host (stream-59d694d9.RTP).
jvb_1      | JVB 2022-09-15 04:12:19.436 INFO: [166] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f name=stream-59d694d9 componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 172.21.0.5:20009/udp
jvb_1      | JVB 2022-09-15 04:12:19.437 INFO: [166] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 172.21.0.6:10000/udp/host -> 172.21.0.5:20009/udp/host (stream-59d694d9.RTP).
jvb_1      | JVB 2022-09-15 04:12:19.438 INFO: [166] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 172.21.0.6:10000/udp/host -> 172.21.0.5:20009/udp/host (stream-59d694d9.RTP)
jvb_1      | JVB 2022-09-15 04:12:19.439 INFO: [166] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.nominate#1810: verify if nominated pair answer again
jvb_1      | JVB 2022-09-15 04:12:19.445 INFO: [166] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
jvb_1      | JVB 2022-09-15 04:12:19.446 INFO: [166] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7998392938176446463):
jvb_1      | 	LocalCandidate=candidate:1 1 udp 2130706431 172.21.0.6 10000 typ host
jvb_1      | 	RemoteCandidate=candidate:10001 1 udp 1862270975 172.21.0.1 58640 typ prflx
jicofo_1   | Jicofo 2022-09-15 04:12:19.472 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] JitsiMeetConferenceImpl.onSessionAccept#938: Receive session-accept from sincerestrandsshelteraccidentally@muc.meet.jitsi/59d694d9
jicofo_1   | Jicofo 2022-09-15 04:12:19.473 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] JitsiMeetConferenceImpl.onSessionAcceptInternal#1290: Accepted initial sources from 59d694d9: {sincerestrandsshelteraccidentally@muc.meet.jitsi/59d694d9=[audio=[1657850262], video=[], groups=[]]}
jicofo_1   | Jicofo 2022-09-15 04:12:19.473 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] ColibriV2SessionManager.updateParticipant#473: Updating Participant[sincerestrandsshelteraccidentally@muc.meet.jitsi/59d694d9]@1666110602 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@32f958bf, sources={sincerestrandsshelteraccidentally@muc.meet.jitsi/59d694d9=[audio=[1657850262], video=[], groups=[]]}
jvb_1      | JVB 2022-09-15 04:12:19.486 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 172.21.0.6:10000/udp/host -> 172.21.0.5:20009/udp/host (stream-59d694d9.RTP).
jvb_1      | JVB 2022-09-15 04:12:19.489 INFO: [159] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
jvb_1      | JVB 2022-09-15 04:12:19.491 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
jvb_1      | JVB 2022-09-15 04:12:19.498 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: 172.21.0.6:10000/udp/host -> 172.21.0.5:20009/udp/host (stream-59d694d9.RTP).
jvb_1      | JVB 2022-09-15 04:12:19.500 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f name=stream-59d694d9] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-59d694d9.RTP: 172.21.0.6:10000/udp/host -> 172.21.0.5:20009/udp/host (stream-59d694d9.RTP)
jvb_1      | JVB 2022-09-15 04:12:19.504 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.checkListStatesUpdated#1901: CheckList of stream stream-59d694d9 is COMPLETED
jvb_1      | JVB 2022-09-15 04:12:19.507 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.setState#946: ICE state changed from Running to Completed.
jvb_1      | JVB 2022-09-15 04:12:19.508 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f] IceTransport.iceStateChanged#341: ICE state changed old=Running new=Completed
jvb_1      | JVB 2022-09-15 04:12:19.509 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9] Endpoint$setupIceTransport$2.connected#343: ICE connected
jvb_1      | JVB 2022-09-15 04:12:19.513 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@5e059640
jvb_1      | JVB 2022-09-15 04:12:19.514 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
jvb_1      | JVB 2022-09-15 04:12:19.518 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.logCandTypes#2009: Harvester used for selected pair for stream-59d694d9.RTP: host
jvb_1      | JVB 2022-09-15 04:12:19.522 WARNING: [168] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:19.539 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f name=stream-59d694d9 componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 172.21.0.1:58640/udp
jvb_1      | JVB 2022-09-15 04:12:19.608 WARNING: [168] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:19.610 INFO: [168] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] ConnectivityCheckClient$PaceMaker.run#943: Pair failed: 172.21.0.6:10000/udp/host -> 172.21.0.1:58640/udp/prflx (stream-59d694d9.RTP)
jvb_1      | JVB 2022-09-15 04:12:19.640 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9] Endpoint$setupDtlsTransport$3.handshakeComplete#387: DTLS handshake complete
jicofo_1   | Jicofo 2022-09-15 04:12:19.733 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] JitsiMeetConferenceImpl.onSessionAccept#938: Receive session-accept from sincerestrandsshelteraccidentally@muc.meet.jitsi/bbbb93df
jicofo_1   | Jicofo 2022-09-15 04:12:19.736 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] JitsiMeetConferenceImpl.onSessionAcceptInternal#1290: Accepted initial sources from bbbb93df: {sincerestrandsshelteraccidentally@muc.meet.jitsi/bbbb93df=[audio=[3473223323], video=[36677177, 2398672355, 4011246264, 3991743109, 1696434384, 3703985320], groups=[FID[36677177, 2398672355], SIM[36677177, 4011246264, 3991743109], FID[4011246264, 1696434384], FID[3991743109, 3703985320]]]}
jicofo_1   | Jicofo 2022-09-15 04:12:19.738 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] ColibriV2SessionManager.updateParticipant#473: Updating Participant[sincerestrandsshelteraccidentally@muc.meet.jitsi/bbbb93df]@854901477 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@270bbab5, sources={sincerestrandsshelteraccidentally@muc.meet.jitsi/bbbb93df=[audio=[3473223323], video=[36677177, 2398672355, 4011246264, 3991743109, 1696434384, 3703985320], groups=[FID[36677177, 2398672355], SIM[36677177, 4011246264, 3991743109], FID[4011246264, 1696434384], FID[3991743109, 3703985320]]]}
jvb_1      | JVB 2022-09-15 04:12:19.742 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
jvb_1      | JVB 2022-09-15 04:12:19.742 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34] IceTransport.startConnectivityEstablishment#198: Starting the Agent without remote candidates.
jvb_1      | JVB 2022-09-15 04:12:19.744 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
jvb_1      | JVB 2022-09-15 04:12:19.744 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.initCheckLists#972: Init checklist for stream stream-bbbb93df
jvb_1      | JVB 2022-09-15 04:12:19.745 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.setState#946: ICE state changed from Waiting to Running.
jvb_1      | JVB 2022-09-15 04:12:19.746 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34] IceTransport.iceStateChanged#341: ICE state changed old=Waiting new=Running
jvb_1      | JVB 2022-09-15 04:12:19.746 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.startConnectivityEstablishment#758: Trigger checks for pairs that were received before running state
jvb_1      | JVB 2022-09-15 04:12:19.747 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):
jvb_1      | 	LocalCandidate=candidate:1 1 udp 2130706431 172.21.0.6 10000 typ host
jvb_1      | 	RemoteCandidate=candidate:10000 1 udp 1853824767 103.80.211.50 52467 typ prflx
jvb_1      | JVB 2022-09-15 04:12:19.748 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
jicofo_1   | Jicofo 2022-09-15 04:12:19.751 INFO: [18] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55 participant=59d694d9] Participant.sendQueuedRemoteSources#585: Sending a queued source-add, sources:{sincerestrandsshelteraccidentally@muc.meet.jitsi/bbbb93df=[audio=[3473223323], video=[], groups=[]]}
jicofo_1   | Jicofo 2022-09-15 04:12:19.759 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55 participant=bbbb93df] Participant.sendQueuedRemoteSources#585: Sending a queued source-add, sources:{sincerestrandsshelteraccidentally@muc.meet.jitsi/59d694d9=[audio=[1657850262], video=[], groups=[]]}
jicofo_1   | Jicofo 2022-09-15 04:12:19.762 INFO: [55] [room=sincerestrandsshelteraccidentally@muc.meet.jitsi meeting_id=f5af9f73-232e-4404-b437-89f7af56ae55] ColibriV2SessionManager.updateParticipant#473: Updating Participant[sincerestrandsshelteraccidentally@muc.meet.jitsi/bbbb93df]@854901477 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@4e73933e, sources=null
jvb_1      | JVB 2022-09-15 04:12:19.781 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34 name=stream-bbbb93df componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-bbbb93df.RTP: 10.0.1.131:52467/udp
jvb_1      | JVB 2022-09-15 04:12:19.783 INFO: [162] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34 name=stream-bbbb93df componentId=1] Component.updateRemoteCandidates#482: new Pair added: 172.21.0.6:10000/udp/host -> 10.0.1.131:52467/udp/host (stream-bbbb93df.RTP).
jvb_1      | JVB 2022-09-15 04:12:19.799 INFO: [176] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.startCheckForPair#350: Could not start connectivity check: No socket found for 172.21.0.6:10000/udp->10.0.1.131:52467/udp
jvb_1      | JVB 2022-09-15 04:12:19.905 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 172.21.0.6:10000/udp/host -> 103.80.211.50:52467/udp/prflx (stream-bbbb93df.RTP).
jvb_1      | JVB 2022-09-15 04:12:19.906 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34 name=stream-bbbb93df componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 103.80.211.50:52467/udp
jvb_1      | JVB 2022-09-15 04:12:19.907 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 103.168.56.74:10000/udp/srflx -> 103.80.211.50:52467/udp/prflx (stream-bbbb93df.RTP).
jvb_1      | JVB 2022-09-15 04:12:19.908 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 103.168.56.74:10000/udp/srflx -> 103.80.211.50:52467/udp/prflx (stream-bbbb93df.RTP)
jvb_1      | JVB 2022-09-15 04:12:19.911 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.nominate#1810: verify if nominated pair answer again
jvb_1      | JVB 2022-09-15 04:12:19.912 WARNING: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34 name=stream-bbbb93df componentId=1] MergingDatagramSocket.initializeActive#599: Active socket already initialized.
jvb_1      | JVB 2022-09-15 04:12:19.913 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
jvb_1      | JVB 2022-09-15 04:12:20.048 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 103.168.56.74:10000/udp/srflx -> 103.80.211.50:52467/udp/prflx (stream-bbbb93df.RTP).
jvb_1      | JVB 2022-09-15 04:12:20.049 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 103.168.56.74:10000/udp/srflx -> 103.80.211.50:52467/udp/prflx (stream-bbbb93df.RTP).
jvb_1      | JVB 2022-09-15 04:12:20.051 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
jvb_1      | JVB 2022-09-15 04:12:20.052 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: 103.168.56.74:10000/udp/srflx -> 103.80.211.50:52467/udp/prflx (stream-bbbb93df.RTP).
jvb_1      | JVB 2022-09-15 04:12:20.053 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34 name=stream-bbbb93df] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-bbbb93df.RTP: 103.168.56.74:10000/udp/srflx -> 103.80.211.50:52467/udp/prflx (stream-bbbb93df.RTP)
jvb_1      | JVB 2022-09-15 04:12:20.054 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.checkListStatesUpdated#1901: CheckList of stream stream-bbbb93df is COMPLETED
jvb_1      | JVB 2022-09-15 04:12:20.055 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.setState#946: ICE state changed from Running to Completed.
jvb_1      | JVB 2022-09-15 04:12:20.056 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34] IceTransport.iceStateChanged#341: ICE state changed old=Running new=Completed
jvb_1      | JVB 2022-09-15 04:12:20.057 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG] Endpoint$setupIceTransport$2.connected#343: ICE connected
jvb_1      | JVB 2022-09-15 04:12:20.058 INFO: [175] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@1a587306
jvb_1      | JVB 2022-09-15 04:12:20.060 INFO: [175] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
jvb_1      | JVB 2022-09-15 04:12:20.062 INFO: [174] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.logCandTypes#2009: Harvester used for selected pair for stream-bbbb93df.RTP: srflx
jvb_1      | JVB 2022-09-15 04:12:20.201 INFO: [175] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG] Endpoint$setupDtlsTransport$3.handshakeComplete#387: DTLS handshake complete
jvb_1      | JVB 2022-09-15 04:12:20.378 INFO: [175] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] Conference.recentSpeakersChanged#509: Recent speakers changed: [bbbb93df], dominant speaker changed: true
jvb_1      | JVB 2022-09-15 04:12:22.519 INFO: [163] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.setState#946: ICE state changed from Completed to Terminated.
jvb_1      | JVB 2022-09-15 04:12:22.521 INFO: [163] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f] IceTransport.iceStateChanged#341: ICE state changed old=Completed new=Terminated
jvb_1      | JVB 2022-09-15 04:12:22.524 WARNING: [168] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:23.064 INFO: [163] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34 ufrag=2gd6j1gcvlhu34] Agent.setState#946: ICE state changed from Completed to Terminated.
jvb_1      | JVB 2022-09-15 04:12:23.065 INFO: [163] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=bbbb93df stats_id=Ruth-VOG local_ufrag=2gd6j1gcvlhu34] IceTransport.iceStateChanged#341: ICE state changed old=Completed new=Terminated
jvb_1      | JVB 2022-09-15 04:12:25.525 WARNING: [177] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:25.827 INFO: [21] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] EndpointConnectionStatusMonitor.monitorEndpointActivity#107: 59d694d9 is considered disconnected.  No activity for PT3.087867S
jvb_1      | JVB 2022-09-15 04:12:28.527 WARNING: [177] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:29.833 INFO: [21] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] EndpointConnectionStatusMonitor.monitorEndpointActivity#111: 59d694d9 has reconnected
jvb_1      | JVB 2022-09-15 04:12:31.528 WARNING: [177] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:32.836 INFO: [21] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] EndpointConnectionStatusMonitor.monitorEndpointActivity#107: 59d694d9 is considered disconnected.  No activity for PT3.023306S
jvb_1      | JVB 2022-09-15 04:12:34.339 INFO: [21] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] EndpointConnectionStatusMonitor.monitorEndpointActivity#111: 59d694d9 has reconnected
jvb_1      | JVB 2022-09-15 04:12:34.529 WARNING: [177] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:37.343 INFO: [21] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] EndpointConnectionStatusMonitor.monitorEndpointActivity#107: 59d694d9 is considered disconnected.  No activity for PT3.433238S
jvb_1      | JVB 2022-09-15 04:12:37.531 WARNING: [177] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!
jvb_1      | JVB 2022-09-15 04:12:37.846 INFO: [21] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi] EndpointConnectionStatusMonitor.monitorEndpointActivity#111: 59d694d9 has reconnected
jvb_1      | JVB 2022-09-15 04:12:40.532 WARNING: [177] [confId=b1056a1d12e68bd9 conf_name=sincerestrandsshelteraccidentally@muc.meet.jitsi epId=59d694d9 local_ufrag=ac021gcvlhu0f ufrag=ac021gcvlhu0f] Agent.generateLocalUserName#1083: Remote ufrag of IceMediaStream with name stream-59d694d9 is null!

damencho · September 15, 2022, 5:29am

s0d · September 15, 2022, 6:30am

Thank you for your response.

I am using PJSIP for asterisk. From what I’ve found, I believe

nat=force_rport,comedia

is equivalent to the following in my environment:

rtp_symmetric=yes
force_rport=yes

source: https://wiki.asterisk.org/wiki/display/AST/Migrating+from+chan_sip+to+res_pjsip

So, I think I tried that option. Do you have any other suggestions?

In the asterisk log, in the Via header of the jigasi request, it says 172.21.0.5:5060. I guess that’s jigasi’s local IP, not public. Does that needs to be public IP?

s0d · September 15, 2022, 7:10am

I tried changing pjsip.conf in asterisk as following:

[jitsi]
type=endpoint
transport=transport-udp
context=from-extensions
disallow=all
allow=ulaw,alaw
auth=jitsi
aors=jitsi
direct_media=no
rtp_symmetric=yes
force_rport=yes
rewrite_contact=yes ; necessary if endpoint does not know/register public ip:port
;ice_support=yes

Now, there is this error:

jigasi_1   | 2022-09-15 07:06:29.403 INFO: [197] SipGatewaySession$SipCallStateListener.handleCallState#1428: [ctx=16632255777011311907416] Sip call IN_PROGRESS: Call: id=16632255783452064973295 peers=1
jigasi_1   | 2022-09-15 07:06:29.404 INFO: [197] SipGatewaySession$SipCallStateListener.handleCallState#1430: [ctx=16632255777011311907416] SIP call format used: rtpmap:0 PCMU/8000
jigasi_1   | 2022-09-15 07:06:29.406 INFO: [197] SipGatewaySession$CallPeerListener.peerStateChanged#1501: [ctx=16632255777011311907416] SIP peer state: Connected
jigasi_1   | 2022-09-15 07:06:29.423 INFO: [197] net.java.sip.communicator.service.protocol.media.CallPeerMediaHandler.start: Starting
jigasi_1   | 2022-09-15 07:06:29.481 INFO: [197] net.java.sip.communicator.service.protocol.media.TransportManager.sendHolePunchPacket: Send NAT hole punch packets
jvb_1      | JVB 2022-09-15 07:06:29.672 INFO: [69] [confId=bb1bcb739b0b877d conf_name=myroom@muc.meet.jitsi epId=64003519 local_ufrag=ajk561gcvvggcj ufrag=ajk561gcvvggcj] ConnectivityCheckClient.processTimeout#881: timeout for pair: 172.22.0.5:10000/udp/host -> 172.22.0.6:20003/udp/host (stream-64003519.RTP), failing.
jigasi_1   | 2022-09-15 07:06:29.900 INFO: [222] net.java.sip.communicator.impl.protocol.sip.SipLogger.logInfo: Info from the JAIN-SIP stack: Setting SIPMessage peerPacketSource to: /18.220.96.228:5060
jigasi_1   | 2022-09-15 07:06:31.406 SEVERE: [200] SipGatewaySession$ExpireMediaStream.run#1366: [ctx=16632255777011311907416] Stopped receiving RTP for Call: id=16632255783452064973295 peers=1

damencho · September 15, 2022, 11:37am

Nope, jigasi doesn’t discover its public address in anyway. The server needs to do latching - waits for media and when it receives it, starts sending it from the ip and port it was received. That’s what the nat= think does.