Guide for setting up Jigasi with Voximplant

Hello, Just joined the community, and am loving using the product. Will be giving this a try today, and might shout for help. Cheers, Lex.

Hello @Chad_Hart Thank you for putting this together, it came just in time for me :). Also FYI i am going through it now and this is a dead link under “Conference Info Services Setup” “Swagger definition doc” 404 error: https://github.com/jitsi/jitsi-meet/blob/master/doc/cloud-api.swagger

Hello @Chad_Hart I went through the instructions and got almost everything but I got stuck at this part:
When I call in and enter the PIN the auto prompt says “connecting you to your conference please wait” and nothing happens from there, its silence.

Would greatly appreciate your help, thank you.

===== Edit:

This is the Error “1003 Failed to connect to XMPP service”

These are my /var/log/jitsi/jigasi.log:
2020-06-03 16:28:23.641 INFO: [121] org.jitsi.jigasi.JvbConference.registrationStateChanged().612 [ctx=15912161025352016511651] Registering XMPP.
2020-06-03 16:28:23.642 SEVERE: [121] impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin().1003 Failed to connect to XMPP service

===== Edit/fix to the error “1003 Failed to connect to XMPP service”:

I was able to figure out the above issue:

I use Prosody authentication so just had to add a user “jigasi” to Prosody and un-comment/add these settings in “/etc/jitsi/jigasi/sip-communicator.properties”

#If you want jigasi to perform authenticated login instead of anonymous login
#to the XMPP server, you can set the following properties.
org.jitsi.jigasi.xmpp.acc.USER_ID=jigasi@test1.MyDomainGoesHere.com
org.jitsi.jigasi.xmpp.acc.PASS=MyPasswordGoesHere
org.jitsi.jigasi.xmpp.acc.ANONYMOUS_AUTH=false

===== NO Audio Issue

I am now able to join the conference and I can see on the jitsi meeting that a phone has joined the meeting but I cannot hear any audio in or out.

Error: “Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed”

These are my /var/log/jitsi/jigasi.log:
2020-06-04 14:34:59.715 SEVERE: [343] net.sf.fmj.media.Log.error() Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
2020-06-04 14:34:59.715 SEVERE: [343] net.sf.fmj.media.Log.error() Failed to prefetch: net.sf.fmj.media.ProcessEngine@48666bee
2020-06-04 14:34:59.729 SEVERE: [341] net.sf.fmj.media.Log.error() Error: Unable to prefetch net.sf.fmj.media.ProcessEngine@48666bee

I would try commenting out all the codecs but OPUS in /etc/jitsi/jigasi/sip-communicator.properties (or wherever yours is located) - to prevent codec negotiation issues. You don’t want to use anything but OPUS. Mine is negotiating fine, but there was a point in my testing where I did remove other codecs to help pinpoint issues. The ones I left were:

net.java.sip.communicator.impl.protocol.sip.acc1.Encodings.opus/48000=1000
net.java.sip.communicator.impl.protocol.sip.acc1.Encodings.telephone-event/8000=1
net.java.sip.communicator.impl.protocol.sip.acc1.OVERRIDE_ENCODINGS=true
1 Like

yes, it looks like it was moved here: https://github.com/jitsi/jitsi-meet/blob/master/resources/cloud-api.swagger

I will ask Voximplant to fix that.

1 Like

Thank you for the reply. I did the exact settings and restart the server but still No Audio on the call.
I cannot hear anything when I call in to the conference, and when I speak in to the phone I cannot hear anything on the laptop. There is no audio being sent from the phone to jigasi/jitsi and no audio recieved from the conference.

===== Jigasi sip-communicator.properties

This is what i have enabled in /etc/jitsi/jigasi/sip-communicator.properties

org.jitsi.jigasi.DEFAULT_JVB_ROOM_NAME=siptest
net.java.sip.communicator.impl.protocol.SingleCallInProgressPolicy.enabled=false
net.java.sip.communicator.impl.neomedia.audioSystem.audiosilence.captureDevice_list=[“AudioSilenceCaptureDevice:noTransferData”]
net.java.sip.communicator.impl.neomedia.codec.audio.opus.encoder.COMPLEXITY=10
net.java.sip.communicator.packetlogging.PACKET_LOGGING_ENABLED=true
net.java.sip.communicator.impl.protocol.sip.acc1403273890647=acc1403273890647
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.ACCOUNT_UID=SIP:jigasi@jigasi-all-phone-numbers.cvlgsupport.n4.voximplant.com
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.PASSWORD=aURueHBCNng=
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.PROTOCOL_NAME=SIP
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.SERVER_ADDRESS=jigasi-all-phone-numbers.cvlgsupport.n4.voximplant.com
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.USER_ID=jigasi@jigasi-all-phone-numbers.cvlgsupport.n4.voximplant.com
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.KEEP_ALIVE_INTERVAL=25
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.KEEP_ALIVE_METHOD=OPTIONS
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.VOICEMAIL_ENABLED=false
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.Encodings.opus/48000=1000
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.Encodings.telephone-event/8000=1
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.OVERRIDE_ENCODINGS=true
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.DEFAULT_ENCRYPTION=false
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.DOMAIN_BASE=test1.MyDomain.com
net.java.sip.communicator.impl.protocol.sip.acc.USE_TRANSLATOR_IN_CONFERENCE=true
org.jitsi.jigasi.xmpp.acc.IS_SERVER_OVERRIDDEN=true
org.jitsi.jigasi.xmpp.acc.SERVER_ADDRESS=127.0.0.1
org.jitsi.jigasi.xmpp.acc.VIDEO_CALLING_DISABLED=true
org.jitsi.jigasi.xmpp.acc.JINGLE_NODES_ENABLED=false
org.jitsi.jigasi.xmpp.acc.AUTO_DISCOVER_STUN=false
org.jitsi.jigasi.xmpp.acc.IM_DISABLED=true
org.jitsi.jigasi.xmpp.acc.SERVER_STORED_INFO_DISABLED=true
org.jitsi.jigasi.xmpp.acc.IS_FILE_TRANSFER_DISABLED=true
org.jitsi.jigasi.xmpp.acc1403273890647.USE_TRANSLATOR_IN_CONFERENCE=true
org.jitsi.jigasi.xmpp.acc.USER_ID=jigasi@test1.MyDomain.com
org.jitsi.jigasi.xmpp.acc.PASS=MyPasswordGoesHere
org.jitsi.jigasi.xmpp.acc.ANONYMOUS_AUTH=false
net.java.sip.communicator.service.gui.ALWAYS_TRUST_MODE_ENABLED=true
net.java.sip.communicator.impl.protocol.sip.acc1403273890647.JITSI_MEET_ROOM_HEADER_NAME=X-Room-Name
net.java.sip.communicator.impl.protocol.sip.SKIP_REINVITE_ON_FOCUS_CHANGE_PROP=true
org.jitsi.jigasi.ENABLE_SIP_STARTMUTED=true

===== Jigasi Log ===

2020-06-04 17:29:03.862 INFO: [69] org.jitsi.jigasi.SipGateway.incomingCallReceived().216 [ctx=1591306143855135090301] Incoming call received…
2020-06-04 17:29:04.870 INFO: [70] org.jitsi.jigasi.SipGatewaySession.run().1490 [ctx=1591306143855135090301] Wait thread cancelled
2020-06-04 17:29:04.890 INFO: [69] org.jitsi.jigasi.JvbConference.start().430 [ctx=1591306143855135090301] Starting JVB conference room: testcall4@conference.test1.MyDomain.com
2020-06-04 17:29:04.992 INFO: [69] org.jitsi.jigasi.JvbConference.setXmppProvider().561 [ctx=1591306143855135090301] Using ProtocolProviderServiceJabberImpl(Jabber:3ef2298d@test1.MyDomain.com/3ef2298d)
2020-06-04 17:29:05.288 INFO: [72] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().127 Jingle : ON
2020-06-04 17:29:05.288 INFO: [72] org.jitsi.jigasi.JvbConference.registrationStateChanged().612 [ctx=1591306143855135090301] Registering XMPP.
2020-06-04 17:29:05.803 INFO: [72] impl.protocol.jabber.ProtocolProviderServiceJabberImpl.authenticated().2535 Authenticated: false
2020-06-04 17:29:05.851 INFO: [72] org.jitsi.jigasi.JvbConference.joinConferenceRoom().700 [ctx=1591306143855135090301] Joining JVB conference room: testcall4@conference.test1.MyDomain.com
2020-06-04 17:29:05.971 SEVERE: [72] org.jitsi.jigasi.JvbConference.inviteFocus().1441 [ctx=1591306143855135090301] Could not invite the focus to the conference
org.jivesoftware.smack.XMPPException$XMPPErrorException: XMPP error reply received from focus.test1.MyDomain.com: XMPPError: not-acceptable - modify
at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:132)
at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:263)
at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:214)
at org.jitsi.jigasi.JvbConference.inviteFocus(JvbConference.java:1435)
at org.jitsi.jigasi.JvbConference.joinConferenceRoom(JvbConference.java:773)
at org.jitsi.jigasi.JvbConference.registrationStateChanged(JvbConference.java:591)
at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:187)
at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:141)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:1389)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:970)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.initializeConnectAndLogin(ProtocolProviderServiceJabberImpl.java:795)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.register(ProtocolProviderServiceJabberImpl.java:500)
at org.jitsi.jigasi.util.RegisterThread.run(RegisterThread.java:59)
2020-06-04 17:29:06.045 INFO: [78] impl.protocol.jabber.ChatRoomJabberImpl.joined().1256 testcall4@conference.test1.MyDomain.com/focus has joined the testcall4@conference.test1.MyDomain.com chat room.
2020-06-04 17:29:06.049 INFO: [78] impl.protocol.jabber.ChatRoomJabberImpl.joined().1256 testcall4@conference.test1.MyDomain.com/2f5e539b has joined the testcall4@conference.test1.MyDomain.com chat room.
2020-06-04 17:29:07.003 INFO: [103] impl.protocol.jabber.IceUdpTransportManager.createIceAgent().346 End gathering harvester within 572 ms
2020-06-04 17:29:08.683 INFO: [103] impl.protocol.jabber.CallPeerMediaHandlerJabberImpl.harvestCandidates().1198 End candidate harvest within 1201 ms
2020-06-04 17:29:08.752 INFO: [103] org.jitsi.jigasi.JvbConference.incomingCallReceived().1077 [ctx=1591306143855135090301] Got invite from focus
2020-06-04 17:29:09.010 INFO: [115] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 126=rtpmap:-1 telephone-event/8000; 111=rtpmap:-1 opus/48000/2 fmtp:useinbandfec=1;minptime=10; 103=rtpmap:-1 un$
2020-06-04 17:29:09.011 INFO: [115] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides [103->104 ]
2020-06-04 17:29:09.156 INFO: [115] service.protocol.media.CallPeerMediaHandler.start().1961 Starting
2020-06-04 17:29:09.787 INFO: [114] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 101=rtpmap:-1 telephone-event/8000; 114=rtpmap:-1 opus/48000/2; 100=rtpmap:-1 iLBC/8000;
2020-06-04 17:29:09.787 INFO: [114] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides
2020-06-04 17:29:09.857 INFO: [114] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1457 [ctx=1591306143855135090301] SIP peer state: Connecting*
2020-06-04 17:29:09.967 INFO: [134] org.jitsi.jigasi.SipGatewaySession.handleCallState().1374 [ctx=1591306143855135090301] Sip call IN_PROGRESS: Call: id=1591306143781239872663 peers=1
2020-06-04 17:29:09.968 INFO: [134] org.jitsi.jigasi.SipGatewaySession.handleCallState().1383 [ctx=1591306143855135090301] SIP call format used: rtpmap:0 PCMU/8000
2020-06-04 17:29:09.981 INFO: [134] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1457 [ctx=1591306143855135090301] SIP peer state: Connected
2020-06-04 17:29:10.022 INFO: [134] service.protocol.media.CallPeerMediaHandler.start().1961 Starting
2020-06-04 17:29:10.591 INFO: [115] org.jitsi.jigasi.JvbConference.callStateChanged().1182 [ctx=1591306143855135090301] JVB conference call IN_PROGRESS.
2020-06-04 17:29:10.607 INFO: [134] service.protocol.media.TransportManager.sendHolePunchPacket().552 Send NAT hole punch packets
2020-06-04 17:29:11.176 SEVERE: [188] net.sf.fmj.media.Log.error() Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
2020-06-04 17:29:11.176 SEVERE: [188] net.sf.fmj.media.Log.error() Failed to prefetch: net.sf.fmj.media.ProcessEngine@70f8eaa6
2020-06-04 17:29:11.179 SEVERE: [186] net.sf.fmj.media.Log.error() Error: Unable to prefetch net.sf.fmj.media.ProcessEngine@70f8eaa6
2020-06-04 17:29:38.914 INFO: [78] org.jitsi.jigasi.SipGatewaySession.mute().1250 [ctx=1591306143855135090301] Sending mute request
2020-06-04 17:30:18.036 INFO: [230] org.jitsi.jigasi.SipGatewaySession.handleCallState().1391 [ctx=1591306143855135090301] SIP call ended: CallPeerChangeEvent: type=CallPeerStatusChange oldV=net.java.sip.communicator.service.protocol.Ca$
2020-06-04 17:30:18.040 INFO: [230] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1457 [ctx=1591306143855135090301] SIP peer state: Disconnected
2020-06-04 17:30:23.048 INFO: [231] org.jitsi.jigasi.SipGatewaySession.sipCallEnded().584 [ctx=1591306143855135090301] Sip call ended: Call: id=1591306143781239872663 peers=0
2020-06-04 17:30:23.063 INFO: [96] org.jitsi.jigasi.JvbConference.memberPresenceChanged().986 [ctx=1591306143855135090301] Member left : GUEST jigasi@test1.MyDomain.com
2020-06-04 17:30:23.064 INFO: [96] org.jitsi.jigasi.JvbConference.memberPresenceChanged().986 [ctx=1591306143855135090301] Member left : OWNER focus@auth.test1.MyDomain.com/focus13308581037
2020-06-04 17:30:23.064 INFO: [96] org.jitsi.jigasi.JvbConference.memberPresenceChanged().994 [ctx=1591306143855135090301] Focus left! - stopping
2020-06-04 17:30:23.081 INFO: [231] org.jitsi.jigasi.AbstractGateway.notifyCallEnded().128 [ctx=1591306143855135090301] Removed session for call. Sessions:0
2020-06-04 17:30:23.085 SEVERE: [96] org.jitsi.jigasi.JvbConference.stop().491 [ctx=1591306143855135090301] Already stopped !
2020-06-04 17:30:23.099 SEVERE: [117] org.jitsi.impl.neomedia.RTPConnectorOutputStream.log() Failed to send a packet to target /10.51.225.11:10000:java.io.IOException: No active socket.
2020-06-04 17:30:23.110 SEVERE: [233] org.jitsi.impl.neomedia.RTPConnectorOutputStream.log() Failed to send a packet to target /10.51.225.11:10000:java.io.IOException: No active socket.
2020-06-04 17:30:23.137 INFO: [233] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().134 Jingle : OFF
2020-06-04 17:30:23.138 INFO: [233] org.jitsi.jigasi.CallManager$HangupCallThread.run().597 [ctx=1591306143855135090301] Removing account Jabber:3ef2298d@test1.MyDomain.com/3ef2298d
2020-06-04 17:30:23.139 INFO: [233] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().134 Jingle : OFF

===== JVB logs.

2020-06-04 17:29:01.298 INFO: [21] Videobridge.createConference#320: create_conf, id=e18e0fb690febd6f gid=null logging=false
2020-06-04 17:29:01.312 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.015S. Sticky failure: false
2020-06-04 17:29:06.165 INFO: [29] Videobridge.createConference#320: create_conf, id=84447447925b9203 gid=ffafb5 logging=true
2020-06-04 17:29:06.193 INFO: [29] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.gatherCandidates#662: Gathering candidates for component stream-3ef2298d.RTP.
2020-06-04 17:29:06.235 INFO: [34] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4] Conference.dominantSpeakerChanged#446: ds_change ds_id=3ef2298d
2020-06-04 17:29:06.289 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint.lambda$setTransportInfo$8#1112: Ignoring empty DtlsFingerprint extension:
2020-06-04 17:29:06.291 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint.describe#1295: Transport description:
22:DE:5C:24:8B:DD:BB:3E:93:61:AD:E0:34:2A:31:51:6D:F5:01:93:5F:62:97:0A:6C:8A:C6:6E:94:C0:8A:83
2020-06-04 17:29:06.293 WARNING: [29] XmppCommon.handleIQRequest#264: Took 129 ms to handle IQ:
2020-06-04 17:29:06.699 INFO: [29] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 conf_name=testcall4 ufrag=a36cc1ea0jpksa] Agent.gatherCandidates#662: Gathering candidates for component stream-2f5e539b.RTP.
2020-06-04 17:29:06.705 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 conf_name=testcall4] Endpoint.lambda$setTransportInfo$8#1112: Ignoring empty DtlsFingerprint extension:
2020-06-04 17:29:06.706 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 conf_name=testcall4] Endpoint.describe#1295: Transport description:
22:DE:5C:24:8B:DD:BB:3E:93:61:AD:E0:34:2A:31:51:6D:F5:01:93:5F:62:97:0A:6C:8A:C6:6E:94:C0:8A:83
2020-06-04 17:29:08.428 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS server, we’ll act as client
2020-06-04 17:29:08.475 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.1.175:64514/udp/host
2020-06-04 17:29:08.475 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.56.1:64515/udp/host
2020-06-04 17:29:08.476 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.2.15:64516/udp/host
2020-06-04 17:29:08.478 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.1.175:64514/udp/host
2020-06-04 17:29:08.478 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.56.1:64515/udp/host
2020-06-04 17:29:08.478 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.2.15:64516/udp/host
2020-06-04 17:29:08.479 INFO: [57] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] IceTransport.startConnectivityEstablishment#176: Starting the agent with remote candidates.
2020-06-04 17:29:08.479 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.startConnectivityEstablishment#753: Start ICE connectivity establishment.
2020-06-04 17:29:08.479 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.initCheckLists#996: Init checklist for stream stream-2f5e539b
2020-06-04 17:29:08.486 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.1.175:64514/udp/host
2020-06-04 17:29:08.489 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.setState#963: ICE state changed from Waiting to Running.
2020-06-04 17:29:08.490 INFO: [57] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] IceTransport.iceStateChanged#321: ICE state changed old=Waiting new=Running
2020-06-04 17:29:08.490 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.startConnectivityEstablishment#775: Trigger checks for pairs that were received before running state
2020-06-04 17:29:08.490 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.triggerCheck#1773: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7961553801087811583):
LocalCandidate=candidate:1 1 udp 2130706431 10.51.225.11 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1853693695 70.49.226.157 64516 typ prflx
2020-06-04 17:29:08.490 INFO: [57] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.startChecks#142: Start connectivity checks.
2020-06-04 17:29:08.491 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.56.1:64515/udp/host
2020-06-04 17:29:08.491 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.2.15:64516/udp/host
2020-06-04 17:29:08.492 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.1.175:64514/udp/host
2020-06-04 17:29:08.492 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.56.1:64515/udp/host
2020-06-04 17:29:08.492 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addRemoteCandidate#330: Add remote candidate for stream-2f5e539b.RTP: 192.168.2.15:64516/udp/host
2020-06-04 17:29:08.492 INFO: [56] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] IceTransport.startConnectivityEstablishment#176: Starting the agent with remote candidates.
2020-06-04 17:29:08.496 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.startConnectivityEstablishment#753: Start ICE connectivity establishment.
2020-06-04 17:29:08.497 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.initCheckLists#996: Init checklist for stream stream-2f5e539b
2020-06-04 17:29:08.499 INFO: [29] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-2f5e539b.RTP: 192.168.1.175:64514/udp
2020-06-04 17:29:08.499 INFO: [29] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addUpdateRemoteCandidates#369: Not adding duplicate remote candidate: 192.168.1.175:64514/udp
2020-06-04 17:29:08.499 INFO: [29] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-2f5e539b.RTP: 192.168.56.1:64515/udp
2020-06-04 17:29:08.499 INFO: [29] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addUpdateRemoteCandidates#369: Not adding duplicate remote candidate: 192.168.56.1:64515/udp
2020-06-04 17:29:08.499 INFO: [29] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-2f5e539b.RTP: 192.168.2.15:64516/udp
2020-06-04 17:29:08.499 INFO: [29] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Component.addUpdateRemoteCandidates#369: Not adding duplicate remote candidate: 192.168.2.15:64516/udp
2020-06-04 17:29:08.500 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint.describe#1295: Transport description:
22:DE:5C:24:8B:DD:BB:3E:93:61:AD:E0:34:2A:31:51:6D:F5:01:93:5F:62:97:0A:6C:8A:C6:6E:94:C0:8A:83
2020-06-04 17:29:08.501 WARNING: [29] XmppCommon.handleIQRequest#264: Took 108 ms to handle IQ: 1E:69:10:45:78:8C:0E:45:E8:47:0D:14:03:A4:4D:66:3F:CB:0D:11:99:97:79:33:E3:C1:4E:F9:6C:84:B6:A7
2020-06-04 17:29:08.501 INFO: [57] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint.describe#1295: Transport description:
22:DE:5C:24:8B:DD:BB:3E:93:61:AD:E0:34:2A:31:51:6D:F5:01:93:5F:62:97:0A:6C:8A:C6:6E:94:C0:8A:83
2020-06-04 17:29:08.526 INFO: [56] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.startChecks#142: Start connectivity checks.
2020-06-04 17:29:08.526 INFO: [56] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint.describe#1295: Transport description:
22:DE:5C:24:8B:DD:BB:3E:93:61:AD:E0:34:2A:31:51:6D:F5:01:93:5F:62:97:0A:6C:8A:C6:6E:94:C0:8A:83
2020-06-04 17:29:08.549 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.1.175:64514/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.560 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.56.1:64515/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.570 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.2.15:64516/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.573 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.processSuccessResponse#627: Pair succeeded: 10.51.225.11:10000/udp/host -> 70.49.226.157:64516/udp/prflx (stream-2f5e539b.RTP).
2020-06-04 17:29:08.573 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 70.49.226.157:64516/udp
2020-06-04 17:29:08.574 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.processSuccessResponse#636: Pair validated: 158.106.69.221:10000/udp/srflx -> 70.49.226.157:64516/udp/prflx (stream-2f5e539b.RTP).
2020-06-04 17:29:08.574 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] DefaultNominator.strategyNominateFirstValid#142: Nominate (first valid): 158.106.69.221:10000/udp/srflx -> 70.49.226.157:64516/udp/prflx (stream-2f5e539b.RTP).
2020-06-04 17:29:08.574 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.nominate#1846: verify if nominated pair answer again
2020-06-04 17:29:08.575 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.processSuccessResponse#705: IsControlling: true USE-CANDIDATE:false.
2020-06-04 17:29:08.603 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.1.175:64514/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.611 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.1.175:64514/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.624 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.1.175:64514/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.632 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.56.1:64515/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.645 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.56.1:64515/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.666 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.56.1:64515/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.678 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.2.15:64516/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.681 INFO: [29] [confId=84447447925b9203 gid=ffafb5 componentId=1 conf_name=testcall4 ufrag=dufk51ea0jpkbr name=stream-3ef2298d epId=3ef2298d local_ufrag=dufk51ea0jpkbr] Component.addRemoteCandidate#330: Add remote candidate for stream-3ef2298d.RTP: 10.51.225.11:10001/udp/host
2020-06-04 17:29:08.681 INFO: [29] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4] IceTransport.startConnectivityEstablishment#176: Starting the agent with remote candidates.
2020-06-04 17:29:08.681 INFO: [29] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.startConnectivityEstablishment#753: Start ICE connectivity establishment.
2020-06-04 17:29:08.682 INFO: [29] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.initCheckLists#996: Init checklist for stream stream-3ef2298d
2020-06-04 17:29:08.682 INFO: [29] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.setState#963: ICE state changed from Waiting to Running.
2020-06-04 17:29:08.682 INFO: [29] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4] IceTransport.iceStateChanged#321: ICE state changed old=Waiting new=Running
2020-06-04 17:29:08.682 INFO: [29] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.startChecks#142: Start connectivity checks.
2020-06-04 17:29:08.682 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint.describe#1295: Transport description:
22:DE:5C:24:8B:DD:BB:3E:93:61:AD:E0:34:2A:31:51:6D:F5:01:93:5F:62:97:0A:6C:8A:C6:6E:94:C0:8A:83
2020-06-04 17:29:08.703 INFO: [59] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.2.15:64516/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.703 INFO: [61] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 192.168.2.15:64516/udp/host (stream-2f5e539b.RTP)
2020-06-04 17:29:08.706 INFO: [59] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient$PaceMaker.run#919: Pair failed: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP)
2020-06-04 17:29:08.744 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.processSuccessResponse#627: Pair succeeded: 158.106.69.221:10000/udp/srflx -> 70.49.226.157:64516/udp/prflx (stream-2f5e539b.RTP).
2020-06-04 17:29:08.744 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.processSuccessResponse#636: Pair validated: 158.106.69.221:10000/udp/srflx -> 70.49.226.157:64516/udp/prflx (stream-2f5e539b.RTP).
2020-06-04 17:29:08.744 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.processSuccessResponse#705: IsControlling: true USE-CANDIDATE:true.
2020-06-04 17:29:08.744 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] ConnectivityCheckClient.processSuccessResponse#720: Nomination confirmed for pair: 158.106.69.221:10000/udp/srflx -> 70.49.226.157:64516/udp/prflx (stream-2f5e539b.RTP).
2020-06-04 17:29:08.744 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-2f5e539b.RTP: 158.106.69.221:10000/udp/srflx -> 70.49.226.157:64516/udp/prflx (stream-2f5e539b.RTP)
2020-06-04 17:29:08.754 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.checkListStatesUpdated#1937: CheckList of stream stream-2f5e539b is COMPLETED
2020-06-04 17:29:08.754 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.setState#963: ICE state changed from Running to Completed.
2020-06-04 17:29:08.754 INFO: [54] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] IceTransport.iceStateChanged#321: ICE state changed old=Running new=Completed
2020-06-04 17:29:08.755 INFO: [54] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint$3.connected#401: ICE connected
2020-06-04 17:29:08.765 INFO: [54] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.logCandTypes#2045: Harvester used for selected pair for stream-2f5e539b.RTP: srflx
2020-06-04 17:29:08.766 INFO: [66] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] DtlsTransport.startDtlsHandshake#98: Starting DTLS handshake
2020-06-04 17:29:08.780 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processSuccessResponse#627: Pair succeeded: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP).
2020-06-04 17:29:08.781 INFO: [54] [confId=84447447925b9203 gid=ffafb5 componentId=1 conf_name=testcall4 ufrag=dufk51ea0jpkbr name=stream-3ef2298d epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 10.51.225.11:10001/udp
2020-06-04 17:29:08.781 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processSuccessResponse#636: Pair validated: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP).
2020-06-04 17:29:08.781 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] DefaultNominator.strategyNominateFirstValid#142: Nominate (first valid): 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP).
2020-06-04 17:29:08.781 INFO: [54] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.nominate#1846: verify if nominated pair answer again
2020-06-04 17:29:08.781 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processSuccessResponse#705: IsControlling: true USE-CANDIDATE:false.
2020-06-04 17:29:08.809 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processSuccessResponse#627: Pair succeeded: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP).
2020-06-04 17:29:08.809 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processSuccessResponse#705: IsControlling: true USE-CANDIDATE:true.
2020-06-04 17:29:08.809 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processSuccessResponse#720: Nomination confirmed for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP).
2020-06-04 17:29:08.809 INFO: [54] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr name=stream-3ef2298d epId=3ef2298d local_ufrag=dufk51ea0jpkbr] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-3ef2298d.RTP: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP)
2020-06-04 17:29:08.812 INFO: [54] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.checkListStatesUpdated#1937: CheckList of stream stream-3ef2298d is COMPLETED
2020-06-04 17:29:08.812 INFO: [54] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.setState#963: ICE state changed from Running to Completed.
2020-06-04 17:29:08.813 INFO: [54] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4] IceTransport.iceStateChanged#321: ICE state changed old=Running new=Completed
2020-06-04 17:29:08.813 INFO: [54] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint$3.connected#401: ICE connected
2020-06-04 17:29:08.814 INFO: [54] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.logCandTypes#2045: Harvester used for selected pair for stream-3ef2298d.RTP: host
2020-06-04 17:29:08.824 INFO: [69] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] DtlsTransport.startDtlsHandshake#98: Starting DTLS handshake
2020-06-04 17:29:08.824 WARNING: [69] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] DtlsTransport.startDtlsHandshake#100: Staring the DTLS stack before it knows its role
2020-06-04 17:29:08.935 INFO: [34] PartitionedByteBufferPool.#88: Initialized a new PartitionedByteBufferPool with 8 partitions.
2020-06-04 17:29:08.935 INFO: [34] PartitionedByteBufferPool.#88: Initialized a new PartitionedByteBufferPool with 8 partitions.
2020-06-04 17:29:08.936 INFO: [34] PartitionedByteBufferPool.#88: Initialized a new PartitionedByteBufferPool with 8 partitions.
2020-06-04 17:29:08.942 INFO: [66] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] TlsClientImpl.notifyServerVersion#165: Negotiated DTLS version DTLS 1.2
2020-06-04 17:29:09.211 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] DtlsTransport.setRemoteFingerprints#143: Assume that the remote side is Jigasi, we’ll act as server
2020-06-04 17:29:09.261 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] TlsServerImpl.notifyClientVersion#193: Negotiated DTLS version DTLS 1.0
2020-06-04 17:29:09.296 INFO: [66] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint$6.handshakeComplete#445: DTLS handshake complete
2020-06-04 17:29:09.348 INFO: [69] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint.lambda$acceptSctpConnection$6#944: Attempting to establish SCTP socket connection
2020-06-04 17:29:09.443 INFO: [66] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4] Conference.dominantSpeakerChanged#446: ds_change ds_id=2f5e539b
2020-06-04 17:29:09.564 INFO: [70] OpenSslWrapperLoader.#46: jitsisrtp successfully loaded
2020-06-04 17:29:10.014 INFO: [19] VideobridgeExpireThread.expire#144: Running expire()
2020-06-04 17:29:10.485 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint$6.handshakeComplete#445: DTLS handshake complete
2020-06-04 17:29:10.485 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint.describe#1295: Transport description:
22:DE:5C:24:8B:DD:BB:3E:93:61:AD:E0:34:2A:31:51:6D:F5:01:93:5F:62:97:0A:6C:8A:C6:6E:94:C0:8A:83
2020-06-04 17:29:10.486 WARNING: [29] XmppCommon.handleIQRequest#264: Took 1275 ms to handle IQ: BE:50:12:19:2A:F7:F8:B5:E6:D6:A9:C9:01:4F:61:AD:E2:DE:91:39
2020-06-04 17:29:11.297 INFO: [21] Videobridge.createConference#320: create_conf, id=df59e4923a7bdd24 gid=null logging=false
2020-06-04 17:29:11.324 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.027S. Sticky failure: false
2020-06-04 17:29:11.766 INFO: [58] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4 ufrag=a36cc1ea0jpksa epId=2f5e539b local_ufrag=a36cc1ea0jpksa] Agent.setState#963: ICE state changed from Completed to Terminated.
2020-06-04 17:29:11.766 INFO: [58] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] IceTransport.iceStateChanged#321: ICE state changed old=Completed new=Terminated
2020-06-04 17:29:11.814 INFO: [58] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr] Agent.setState#963: ICE state changed from Completed to Terminated.
2020-06-04 17:29:11.815 INFO: [58] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4] IceTransport.iceStateChanged#321: ICE state changed old=Completed new=Terminated
Got sctp association state update: 1
sctp is now up. was ready? false
2020-06-04 17:29:12.481 INFO: [69] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint$7.onReady#884: SCTP connection is ready, creating the Data channel stack
2020-06-04 17:29:12.493 INFO: [66] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] DataChannelStack.onIncomingDataChannelPacket#62: Received data channel open message
2020-06-04 17:29:12.494 INFO: [66] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint$7.lambda$onReady$1#892: Remote side opened a data channel.
2020-06-04 17:29:12.497 INFO: [69] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint$7.onReady#911: Will wait for the remote side to open the data channel.
2020-06-04 17:29:14.022 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:17.031 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:20.029 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:21.299 INFO: [21] Videobridge.createConference#320: create_conf, id=7bf222615b2dad50 gid=null logging=false
2020-06-04 17:29:21.321 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.024S. Sticky failure: false
2020-06-04 17:29:23.034 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:26.035 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:31.298 INFO: [21] Videobridge.createConference#320: create_conf, id=e1b50c972882ce26 gid=null logging=false
2020-06-04 17:29:31.318 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.021S. Sticky failure: false
2020-06-04 17:29:38.904 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Transceiver.forceMuteAudio#275: Muting incoming audio
2020-06-04 17:29:40.485 SEVERE: [30] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint.lambda$scheduleEndpointMessageTransportTimeout$7#984: EndpointMessageTransport still not connected.
2020-06-04 17:29:41.051 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:41.297 INFO: [21] Videobridge.createConference#320: create_conf, id=3a3a63cee09650d0 gid=null logging=false
2020-06-04 17:29:41.319 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.022S. Sticky failure: false
2020-06-04 17:29:44.055 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:50.065 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:51.298 INFO: [21] Videobridge.createConference#320: create_conf, id=f763c9bb2ec28105 gid=null logging=false
2020-06-04 17:29:51.319 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.022S. Sticky failure: false
2020-06-04 17:29:51.485 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Transceiver.forceMuteAudio#276: Unmuting incoming audio
2020-06-04 17:29:53.065 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:56.068 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:29:59.071 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:30:00.867 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Transceiver.forceMuteAudio#275: Muting incoming audio
2020-06-04 17:30:00.877 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Transceiver.forceMuteAudio#276: Unmuting incoming audio
2020-06-04 17:30:01.298 INFO: [21] Videobridge.createConference#320: create_conf, id=551f4eb6244bbe30 gid=null logging=false
2020-06-04 17:30:01.321 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.023S. Sticky failure: false
2020-06-04 17:30:08.078 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:30:10.014 INFO: [19] VideobridgeExpireThread.expire#144: Running expire()
2020-06-04 17:30:11.299 INFO: [21] Videobridge.createConference#320: create_conf, id=d886d378a34333f1 gid=null logging=false
2020-06-04 17:30:11.319 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.02S. Sticky failure: false
2020-06-04 17:30:14.088 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:30:20.098 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:30:21.299 INFO: [21] Videobridge.createConference#320: create_conf, id=4fed432539f825c6 gid=null logging=false
2020-06-04 17:30:21.316 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.017S. Sticky failure: false
2020-06-04 17:30:23.104 INFO: [62] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4 ufrag=dufk51ea0jpkbr epId=3ef2298d local_ufrag=dufk51ea0jpkbr] ConnectivityCheckClient.processTimeout#857: timeout for pair: 10.51.225.11:10000/udp/host -> 10.51.225.11:10001/udp/host (stream-3ef2298d.RTP), failing.
2020-06-04 17:30:23.141 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] AbstractEndpoint.expire#303: Expiring.
2020-06-04 17:30:23.141 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Transceiver.teardown#315: Tearing down
2020-06-04 17:30:23.142 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] RtpReceiverImpl.tearDown#287: Tearing down
2020-06-04 17:30:23.147 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] RtpSenderImpl.tearDown#263: Tearing down
2020-06-04 17:30:23.155 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] DtlsTransport.stop#180: Stopping
2020-06-04 17:30:23.156 INFO: [29] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4] IceTransport.stop#235: Stopping
2020-06-04 17:30:23.157 INFO: [29] [confId=84447447925b9203 gid=ffafb5 componentId=1 conf_name=testcall4 ufrag=dufk51ea0jpkbr name=stream-3ef2298d epId=3ef2298d local_ufrag=dufk51ea0jpkbr] MergingDatagramSocket.close#142: Closing.
2020-06-04 17:30:23.157 INFO: [68] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4] IceTransport.startReadingData#201: Socket closed, stopping reader
2020-06-04 17:30:23.158 INFO: [68] [confId=84447447925b9203 epId=3ef2298d local_ufrag=dufk51ea0jpkbr gid=ffafb5 conf_name=testcall4] IceTransport.startReadingData#213: No longer running, stopped reading packets
2020-06-04 17:30:23.158 INFO: [64] [confId=84447447925b9203 gid=ffafb5 componentId=1 conf_name=testcall4 ufrag=dufk51ea0jpkbr name=stream-3ef2298d epId=3ef2298d local_ufrag=dufk51ea0jpkbr] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
2020-06-04 17:30:23.158 INFO: [29] [confId=84447447925b9203 epId=3ef2298d gid=ffafb5 conf_name=testcall4] Endpoint.expire#811: Expired.
2020-06-04 17:30:31.300 INFO: [21] Videobridge.createConference#320: create_conf, id=90f6e98d6f85c209 gid=null logging=false
2020-06-04 17:30:31.314 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.015S. Sticky failure: false
2020-06-04 17:30:41.300 INFO: [21] Videobridge.createConference#320: create_conf, id=8fea69ae2876b01c gid=null logging=false
2020-06-04 17:30:41.317 INFO: [21] AbstractHealthCheckService.run#171: Performed a successful health check in PT0.018S. Sticky failure: false
2020-06-04 17:30:43.153 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] AbstractEndpoint.expire#303: Expiring.
2020-06-04 17:30:43.154 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Transceiver.teardown#315: Tearing down
2020-06-04 17:30:43.154 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] RtpReceiverImpl.tearDown#287: Tearing down
2020-06-04 17:30:43.154 INFO: [69] [confId=84447447925b9203 gid=ffafb5 conf_name=testcall4] Conference.dominantSpeakerChanged#446: ds_change ds_id=null
2020-06-04 17:30:43.165 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] RtpSenderImpl.tearDown#263: Tearing down
2020-06-04 17:30:43.166 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] DtlsTransport.stop#180: Stopping
2020-06-04 17:30:43.166 INFO: [29] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] IceTransport.stop#235: Stopping
2020-06-04 17:30:43.167 INFO: [34] [confId=84447447925b9203 epId=2f5e539b local_ufrag=a36cc1ea0jpksa gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] IceTransport.startReadingData#213: No longer running, stopped reading packets
2020-06-04 17:30:43.169 INFO: [53] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
2020-06-04 17:30:43.169 WARNING: [53] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] MergingDatagramSocket.doRemove#349: Removing the active socket. Won’t be able to send until a new one is elected.
2020-06-04 17:30:43.169 INFO: [29] [confId=84447447925b9203 gid=ffafb5 stats_id=Carol-p5w componentId=1 conf_name=testcall4 ufrag=a36cc1ea0jpksa name=stream-2f5e539b epId=2f5e539b local_ufrag=a36cc1ea0jpksa] MergingDatagramSocket.close#142: Closing.
2020-06-04 17:30:43.171 INFO: [29] [confId=84447447925b9203 epId=2f5e539b gid=ffafb5 stats_id=Carol-p5w conf_name=testcall4] Endpoint.expire#811: Expired.

@sor2000- do you have port 10000 open in your firewall configuration?

Have you tried connecting with Jitsi Desktop instead of Jigasi? Its the same SIP stack without all the other complexities in Jigasi, so it can be easier to debug.

You could also email support@voximplant.com with your issue. If it isn’t something obvious, they can setup a trace to see why the the connection is failing. They will ask you to to share a link from the VoxEngine call logs.

Hey so I’ve gone through this, first time I tried to use it, it didn’t work because we’ve set up with Docker and it uses an internal domain (muc.meet.jitsi)

I attempted to add it and it still didn’t work so I’ve trawled the internet and found some inbound code that did work with Docker here: https://community.jitsi.org/t/working-voximplant-example-code-for-jigasi-sip-dial-in/

I’ve merged this one and the one above to get this: https://github.com/ktechmidas/jitsi-docker-vox/blob/master/inbound_with_mute.js which is working fine for inbound calls… however muting…

Muting is giving me logs, and pretending it’s working. It says “Press *6” to mute or unmute, but nothing actually happens.

How does the muting work?

2020-06-05 11:23:05 DEBUG: unMuteToggle IVR input was *6
2020-06-05 11:23:05 DEBUG: Sending mute request: {“type”:“muteRequest”,“id”:“6ba6be9505424149a696afdd4734e902”,“data”:{“audio”:false}}
2020-06-05 11:23:05 Executing JS command: SendInfo with params [{body = {“type”:“muteRequest”,“id”:“6ba6be9505424149a696afdd4734e902”,“data”:{“audio”:false}} ; headers = NULL ; id = EGUTxyVrTaqOcCJiVBFVU iKi TADkm6vIJGwrSzEtE ; subtype = json ; type = application ; } ; ]
2020-06-05 11:23:05 Sent event to JS onPhoneEvent with params [{body = {“data”:{“audio”:false},“id”:“6ba6be9505424149a696afdd4734e902”,“type”:“muteResponse”,“status”:“OK”} ; headers = {} ; id = EGUTxyVrTaqOcCJiVBFVU iKi TADkm6vIJGwrSzEtE ; mimeType = application/json ; name = Call.InfoReceived ; } ; ]
2020-06-05 11:23:05 DEBUG::handleReceivedInfo
2020-06-05 11:23:05 DEBUG: muteResponse with 6ba6be9505424149a696afdd4734e902 succeeded. audioMuted state is true.

Also only inbound calls can mute? If I attempt to add muting to the outbound route it tells me jigasiCall and userCall are defined twice.

Thank you for such a prompt reply Chad.
Yes i do have that port open

My Ports:

To ----------------------------- Action ----------------From


22/tcp ------------------------- ALLOW ---------- Anywhere
80/tcp ------------------------- ALLOW ---------- Anywhere
443/tcp ----------------------- ALLOW ---------- Anywhere
5060/tcp --------------------- ALLOW ---------- Anywhere
10000:20000/udp ---------- ALLOW ---------- Anywhere
22/tcp (v6) ------------------- ALLOW ---------- Anywhere
80/tcp (v6) ------------------- ALLOW ---------- Anywhere (v6)
443/tcp (v6) ----------------- ALLOW ---------- Anywhere (v6)
5060/tcp (v6) --------------- ALLOW ---------- Anywhere (v6)
10000:20000/udp (v6) ---- ALLOW ---------- Anywhere (v6)

I have tried Jitsi Desktop but it just gets stuck at saying “Initiating Call” or “Connecting”
I’ve never used a SIP desktop phone, is there a Guide for Jitsi Desktop and Voximplant setup that i could test out?

Yes I can reach out to them, thank you for the suggestion.
But I think the issue is on the Jitsi/Jigasi end because I can hear all the prompts from the IVR and enter the pin and get a reply “Connecting you to your conference, please wait” but then it just go silent.

I found this in the documentation, is this still relevant and if so what do I put here?:

  1. Configure external component in your XMPP server. If your server is Prosody: edit /etc/prosody/prosody.cfg.lua and append following lines to your config (assuming that subdomain is ‘callcontrol’ and domain ‘meet.jit.si’):
Component "callcontrol.meet.jit.si"
    component_secret = "topsecret"

Well the good news is I got the mute working… however it doesn’t seem to work on the Jitsi side (ie muting a SIP user)

Any ideas?

Hello @Chad_Hart , I disabled secure domain and now I am able to hear audio one way.

Phone to Jigasi - NO audio
Jigasi to Phone - YES audio.

People on the phone can only listen to the meeting but not able to participate/answer.

Any suggestions of why there is one way audio only?

Also got a reply from Voximplant.

Hello!
Based on media statistics we don’t receive any voice traffic from
username = jigasi
Is it possible that there is a RTP firewall on Jigasi side?
If so these addresses should be open
http://api.voximplant.com/getMediaResources?with_mediaservers

I am not a deep expert on Jigasi’s SIP negotations, but - that line indicates you’re using G.711/PCM instead of OPUS.

Adding my complete sip-communicator.properties file for reference in case it helps. Note I haven’t cleaned this up and it has some transcription settings you don’t need for calling functionality.

root@docker-s-1vcpu-1gb-nyc1-01:~# cat /etc/jitsi/jigasi/sip-communicator.properties
#Sample config with one XMPP and one SIP account configured
# Replace {sip-pass-hash} with SIP user password hash
# as well as other account properties

# Name of default JVB room that will be joined if no special header is included
# in SIP invite
# org.jitsi.jigasi.DEFAULT_JVB_ROOM_NAME=cwh

net.java.sip.communicator.impl.protocol.SingleCallInProgressPolicy.enabled=false

# Should be enabled when using translator mode
# cwh
net.java.sip.communicator.impl.neomedia.audioSystem.audiosilence.captureDevice_list=["AudioSilenceCaptureDevice:noTransferData"]

# Adjust opus encoder complexity
net.java.sip.communicator.impl.neomedia.codec.audio.opus.encoder.COMPLEXITY=10

# Disables packet logging
net.java.sip.communicator.packetlogging.PACKET_LOGGING_ENABLED=true

net.java.sip.communicator.impl.protocol.sip.acc999999999999=acc999999999999
net.java.sip.communicator.impl.protocol.sip.acc999999999999.ACCOUNT_UID=SIP\:jigasi@jigasi-cwh.chadhart.n2.voximplant.com
net.java.sip.communicator.impl.protocol.sip.acc999999999999.PASSWORD=[redacted]
net.java.sip.communicator.impl.protocol.sip.acc999999999999.PROTOCOL_NAME=SIP
net.java.sip.communicator.impl.protocol.sip.acc999999999999.SERVER_ADDRESS=jigasi-cwh.chadhart.n2.voximplant.com
net.java.sip.communicator.impl.protocol.sip.acc999999999999.USER_ID=jigasi@jigasi-cwh.chadhart.n2.voximplant.com
net.java.sip.communicator.impl.protocol.sip.acc999999999999.KEEP_ALIVE_INTERVAL=25
net.java.sip.communicator.impl.protocol.sip.acc999999999999.KEEP_ALIVE_METHOD=OPTIONS
net.java.sip.communicator.impl.protocol.sip.acc999999999999.VOICEMAIL_ENABLED=false

# cwh: SIP security options
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.PREFERRED_TRANSPORT=TLS
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.PROXY_ADDRESS=chadhart.voximplant.com
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.PROXY_PORT=5061
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.PROXY_AUTO_CONFIG=false
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.SAVP_OPTION=1
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.DEFAULT_ENCRYPTION=true
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.ENCRYPTION_PROTOCOL.ZRTP=0
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.ENCRYPTION_PROTOCOL.SDES=1
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.ENCRYPTION_PROTOCOL.DTLS-SRTP=2
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.ENCRYPTION_PROTOCOL_STATUS.ZRTP=false
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.ENCRYPTION_PROTOCOL_STATUS.SDES=true
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.ENCRYPTION_PROTOCOL_STATUS.DTLS-SRTP=false
# net.java.sip.communicator.impl.protocol.sip.acc999999999999.SDES_CIPER_SUITES=AES_CM_128_HMAC_SHA1_80

# I could disable all codecs but Opus
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.AMR-WB/16000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.G722/8000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.GSM/8000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.H263-1998/90000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.H264/90000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.PCMA/8000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.PCMU/8000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.SILK/12000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.SILK/16000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.SILK/24000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.SILK/8000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.VP8/90000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.iLBC/8000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.speex/16000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.speex/32000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.speex/8000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.red/90000=0
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.ulpfec/90000=0


net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.opus/48000=1000
net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.telephone-event/8000=1
net.java.sip.communicator.impl.protocol.sip.acc999999999999.OVERRIDE_ENCODINGS=true
net.java.sip.communicator.impl.protocol.sip.acc999999999999.DEFAULT_ENCRYPTION=false

# If an authenticated (hidden) domain is used to connect to a conference,
# PREVENT_AUTH_LOGIN will prevent the SIP participant from being seen as a
# hidden participant in the conference
#net.java.sip.communicator.impl.protocol.sip.acc999999999999.PREVENT_AUTH_LOGIN=FALSE

# Used when incoming calls are used in multidomain environment, used to detect subdomains
# used for constructing callResource and eventually contacting jicofo
net.java.sip.communicator.impl.protocol.sip.acc999999999999.DOMAIN_BASE=meet.cwh.consulting


# cwh for VoxEngine
net.java.sip.communicator.impl.protocol.sip.acc999999999999.JITSI_MEET_ROOM_HEADER_NAME=X-Room-Name
net.java.sip.communicator.impl.protocol.sip.SKIP_REINVITE_ON_FOCUS_CHANGE_PROP=true

# the pattern to be used as bosh url when using bosh in multidomain environment
#net.java.sip.communicator.impl.protocol.sip.acc999999999999.BOSH_URL_PATTERN=https://{host}{subdomain}/http-bind?room={roomName}

# can be enabled to disable audio mixing and use translator, jigasi will act as jvb, just forward every ssrc stream it receives.
# cwh
net.java.sip.communicator.impl.protocol.sip.acc999999999999.USE_TRANSLATOR_IN_CONFERENCE=true

# We can use the prefix org.jitsi.jigasi.xmpp.acc to override any of the
# properties that will be used for creating xmpp account for communication.

# The following two props assume we are using jigasi on the same machine as
# the xmpp server.
org.jitsi.jigasi.xmpp.acc.IS_SERVER_OVERRIDDEN=true
org.jitsi.jigasi.xmpp.acc.SERVER_ADDRESS=127.0.0.1
org.jitsi.jigasi.xmpp.acc.VIDEO_CALLING_DISABLED=true
org.jitsi.jigasi.xmpp.acc.JINGLE_NODES_ENABLED=false
org.jitsi.jigasi.xmpp.acc.IM_DISABLED=true
org.jitsi.jigasi.xmpp.acc.SERVER_STORED_INFO_DISABLED=true
org.jitsi.jigasi.xmpp.acc.IS_FILE_TRANSFER_DISABLED=true
# Or you can use bosh for the connection establishment by specifing the URL to use.
# org.jitsi.jigasi.xmpp.acc.BOSH_URL_PATTERN=https://server.com/http-bind?room={roomName}

#Used when outgoing calls are used in multidomain environment, used to detect subdomains
#org.jitsi.jigasi.xmpp.acc.DOMAIN_BASE=meet.cwh.consulting
#org.jitsi.jigasi.xmpp.acc.BOSH_URL_PATTERN=https://{host}{subdomain}/http-bind?room={roomName}

# can be enabled to disable audio mixing and use translator, jigasi will act as jvb, just forward every ssrc stream it receives.
# cwh
org.jitsi.jigasi.xmpp.acc.USE_TRANSLATOR_IN_CONFERENCE=true

# If you want jigasi to perform authenticated login instead of anonymous login
# to the XMPP server, you can set the following properties.
# cwh
org.jitsi.jigasi.xmpp.acc.USER_ID=jigasi@auth.meet.cwh.consulting
org.jitsi.jigasi.xmpp.acc.PASS=[redacted]
org.jitsi.jigasi.xmpp.acc.ANONYMOUS_AUTH=false

# If you want to use the SIP user part of the incoming/outgoing call SIP URI
# you can set the following property to true.
# org.jitsi.jigasi.USE_SIP_USER_AS_XMPP_RESOURCE=true

# Activate this property if you are using self-signed certificates or other
# type of non-trusted certicates. In this mode your service trust in the
# remote certificates always.
# cwh
net.java.sip.communicator.service.gui.ALWAYS_TRUST_MODE_ENABLED=true

# Enable this property to be able to shutdown gracefully jigasi using
# a rest command
# org.jitsi.jigasi.ENABLE_REST_SHUTDOWN=true

# Options regarding Transcription. Read the README for a detailed description
# about each property

# cwh
org.jitsi.jigasi.ENABLE_TRANSCRIPTION=true
#org.jitsi.jigasi.ENABLE_SIP=true

# whether to use the more expensive, but better performing
# "video" model when doing transcription
# org.jitsi.jigasi.transcription.USE_VIDEO_MODEL = false

# delivering final transcript
# org.jitsi.jigasi.transcription.DIRECTORY=/var/lib/jigasi/transcripts
# org.jitsi.jigasi.transcription.BASE_URL=http://localhost/
# org.jitsi.jigasi.transcription.jetty.port=-1
# org.jitsi.jigasi.transcription.ADVERTISE_URL=false

# save formats
# org.jitsi.jigasi.transcription.SAVE_JSON=true
# org.jitsi.jigasi.transcription.SAVE_TXT=true

# send formats
# org.jitsi.jigasi.transcription.SEND_JSON=true
# org.jitsi.jigasi.transcription.SEND_TXT=false

# translation
# org.jitsi.jigasi.transcription.ENABLE_TRANSLATION=false

# record audio. Currently only wav format is supported
# org.jitsi.jigasi.transcription.RECORD_AUDIO=false
# org.jitsi.jigasi.transcription.RECORD_AUDIO_FORMAT=wav

# execute one or more scripts when a transcript or recording is saved
# org.jitsi.jigasi.transcription.EXECUTE_SCRIPTS=true
# org.jitsi.jigasi.transcription.SCRIPTS_TO_EXECUTE_LIST_SEPARATOR=","
# org.jitsi.jigasi.transcription.SCRIPTS_TO_EXECUTE_LIST=script/example_handle_transcript_directory.sh

# properties for optionally sending statistics to a DataDog server
#org.jitsi.ddclient.prefix=jitsi.jigasi
#org.jitsi.ddclient.host=localhost
#org.jitsi.ddclient.port=8125

# sip health checking
# Enables sip health checking by specifying a number/uri to call
# the target just needs to auto-connect the call play some audio,
# the call must be established for less than 10 seconds
# org.jitsi.jigasi.HEALTH_CHECK_SIP_URI=healthcheck
#
# The interval between healthcheck calls, by default is 5 minutes
# org.jitsi.jigasi.HEALTH_CHECK_INTERVAL=300000
#
# The timeout of healthcheck, if there was no successful health check for
# 10 minutes (default value) we consider jigasi unhealthy
# org.jitsi.jigasi.HEALTH_CHECK_TIMEOUT=600000

# cwh
org.jitsi.jigasi.ENABLE_SIP_STARTMUTED=true

# cwh
# https://community.jitsi.org/t/transcription-in-jitsi-meet-cc-button-not-quite-working-transcriber-not-joining-hidden/18265/3
org.jitsi.jigasi.BREWERY_ENABLED=true


# XMPP account used for control
net.java.sip.communicator.impl.protocol.jabber.acc1=acc1
net.java.sip.communicator.impl.protocol.jabber.acc1.ACCOUNT_UID=Jabber:jigasi@auth.meet.cwh.consulting
net.java.sip.communicator.impl.protocol.jabber.acc1.USER_ID=jigasi@auth.meet.cwh.consulting
net.java.sip.communicator.impl.protocol.jabber.acc1.IS_SERVER_OVERRIDDEN=true
net.java.sip.communicator.impl.protocol.jabber.acc1.SERVER_ADDRESS=127.0.0.1
# net.java.sip.communicator.impl.protocol.jabber.acc1.PASSWORD=[redacted]
net.java.sip.communicator.impl.protocol.jabber.acc1.PASSWORD=[redacted]
net.java.sip.communicator.impl.protocol.jabber.acc1.AUTO_GENERATE_RESOURCE=true
net.java.sip.communicator.impl.protocol.jabber.acc1.RESOURCE_PRIORITY=30
net.java.sip.communicator.impl.protocol.jabber.acc1.IS_CARBON_DISABLED=true
net.java.sip.communicator.impl.protocol.jabber.acc1.DEFAULT_ENCRYPTION=true
net.java.sip.communicator.impl.protocol.jabber.acc1.IS_USE_ICE=true
net.java.sip.communicator.impl.protocol.jabber.acc1.IS_ACCOUNT_DISABLED=false
net.java.sip.communicator.impl.protocol.jabber.acc1.IS_PREFERRED_PROTOCOL=false
net.java.sip.communicator.impl.protocol.jabber.acc1.AUTO_DISCOVER_JINGLE_NODES=false
net.java.sip.communicator.impl.protocol.jabber.acc1.PROTOCOL=Jabber
net.java.sip.communicator.impl.protocol.jabber.acc1.IS_USE_UPNP=false
net.java.sip.communicator.impl.protocol.jabber.acc1.USE_DEFAULT_STUN_SERVER=true
net.java.sip.communicator.impl.protocol.jabber.acc1.ENCRYPTION_PROTOCOL.DTLS-SRTP=0
# net.java.sip.communicator.impl.protocol.jabber.acc1.ENCRYPTION_PROTOCOL_STATUS.DTLS-SRTP=true
net.java.sip.communicator.impl.protocol.jabber.acc1.VIDEO_CALLING_DISABLED=true
net.java.sip.communicator.impl.protocol.jabber.acc1.OVERRIDE_ENCODINGS=true
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.G722/8000=705
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.GSM/8000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.H263-1998/90000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.H264/90000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.PCMA/8000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.PCMU/8000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.SILK/12000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.SILK/16000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.SILK/24000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.SILK/8000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.VP8/90000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.iLBC/8000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.opus/48000=750
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.speex/16000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.speex/32000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.speex/8000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.Encodings.telephone-event/8000=0
net.java.sip.communicator.impl.protocol.jabber.acc1.BREWERY=JigasiBrewery@internal.auth.meet.cwh.consulting
net.java.sip.communicator.impl.protocol.jabber.acc1.DOMAIN_BASE=meet.cwh.consulting
# cwh
net.java.sip.communicator.impl.protocol.jabber.acc1.ALLOW_NON_SECURE=true

I’m not sure why that didn’t give me an error earlier in the weeks I have been using this, but it happened to me today. You can fix that by commenting out let jigasiCall, userCall;

Mute works in both directions. You should see the SIP INFO messages in the Voximplant logs. Jigasi takes this message and converts it to an XMPP message that is relayed to the conference. You could check the logs to see if that chain is happening.

Thank you Chad!! :slight_smile: , I have audio now both ways, going to implement authentication via prosody back again and see if the audio is still there.

But I have to say the logs are confusing, please see below and doesn’t make exact sense.

I did not realize that commenting out the codecs would not deactivate them.

IE:

#net.java.sip.communicator.impl.protocol.sip.acc123456789.Encodings.G722/8000=700

Based on your sip-communicator.properties i see that you did G722/8000=0 where G722/8000 and all other codecs that you want to be deactivated are equaling to 0 (zero).

IE:

net.java.sip.communicator.impl.protocol.sip.acc999999999999.Encodings.G722/8000=0

I have now applied the same settings you have for the Codecs and even though the audio works the jigasi.log still show this:

Before: when codecs were commented out:

net.java.sip.communicator.impl.protocol.sip.acc123456789.ENCRYPTED_PASSWORD=****
net.java.sip.communicator.impl.protocol.sip.acc123456789.Encodings.G722/8000=700
net.java.sip.communicator.impl.notifications.eventType1591636175165.actions.actionType1591636175165=PopupMessageAction
net.java.sip.communicator.impl.protocol.sip.acc123456789.Encodings.opus/48000=1000

1457 [ctx=15916362352411269809711] SIP peer state: Connecting*
1374 [ctx=15916362352411269809711] Sip call IN_PROGRESS: Call: id=1591636235171555843011 peers=1
1383 [ctx=15916362352411269809711] SIP call format used: rtpmap:0 PCMU/8000

After: when codecs equled to 0 (zero)

net.java.sip.communicator.impl.protocol.sip.acc123456789.ENCRYPTED_PASSWORD=****
net.java.sip.communicator.impl.protocol.sip.acc123456789.Encodings.G722/8000=0
net.java.sip.communicator.impl.notifications.eventType1591707210780.actions.actionType1591707210780.enabled=true
net.java.sip.communicator.impl.notifications.eventType1591707210780.actions.actionType1591707210780=PopupMessageAction
net.java.sip.communicator.impl.protocol.sip.acc123456789.Encodings.opus/48000=1000

1457 [ctx=1591707406992336574271] SIP peer state: Connecting*
1374 [ctx=1591707406992336574271] Sip call IN_PROGRESS: Call: id=15917074069191418073189 peers=1
1383 [ctx=1591707406992336574271] SIP call format used: rtpmap:-1 opus/48000/2

So my question is:
Why was the PCMU/8000 codec being used when it/G722/8000/other codecs were commented out SIP call format used: rtpmap:0 PCMU/8000 ?

And why it only worked in selecting SIP call format used: rtpmap:-1 opus/48000/2 codec once all unwanted codecs equaled to 0 (zero) even though the log shows it the same way? (other then it equaling to 0 (zero)) ie: G722/8000=700 vs G722/8000=0

Hello @Chad_Hart, I was able to make it work with secure domain without any issues. (posting that at the end for others to reference.

Some questions:

  1. Now that I have started optimizing it more, I hit bump with the documented muteIVR.js that I pasted into Voximplant as per documentation.
    When I call in I get reminded every 5 min that I am unmuted and to press star 6 (i was on the call 28min in this case) but when I check the MuteIVR it does not make sense. Would you please advise how I can fix it?

/*** Adjust this constants for your installation ***/
const repromptTime = 30 * 10 * 1000; // How long to wait before reminding users of their mute state

  1. I am having a half a sec to a one sec delay from the time the person says something over the phone to the time i hear it and we end up talking over each other, is there a way to reduce leg on the SIP calls?

  2. (I have asked this Voximplant and waiting for reply but adding this here in case others are interested) How many concurrent calls can be on 1 Toll Free and/Local SIP Number from Voximplant?

SIP with Secure Domain Setup

My Steps on adding SIP to a Secured Domain (in my case I use Prosody for authentication)
NOTE: I personally recommend setting up the SIP before securing the domain just to make sure everything is working and that way it will prevent you from going on a wild goose chase like I did where I had no audio.

  1. Follow this guide: Secure Domain Setup

  2. Add a user that will be used for Voximplant/Jigasi to be able to access rooms for incoming calls.

root@jitsiserver:~# prosodyctl register jigasi meet.mydomain.com
Enter new password: *** Enter Password **** (It can include Caps, Num, Special Character)
Retype new password: *** do it again ****

  1. Uncomment and add the user to jigasi’s sip-communicator.properties

root@jitsiserver:~# nano /etc/jitsi/jigasi/sip-communicator.properties

org.jitsi.jigasi.xmpp.acc.USER_ID=jigasi@meet.mydomain.com
org.jitsi.jigasi.xmpp.acc.PASS=YourAmazingPassword1234
org.jitsi.jigasi.xmpp.acc.ANONYMOUS_AUTH=false

1 Like

Note for others - Voximplant updated the guide on their Jigasi Integration Guide page to include a mention about removing all the other codecs. The repo has also been updated to fix the redeclaration error (pull request).

Thanks to @sor2000 for the comments.

Re your questions:

Now that I have started optimizing it more, I hit bump with the documented muteIVR.js that I pasted into Voximplant as per documentation.
When I call in I get reminded every 5 min that I am unmuted and to press star 6 (i was on the call 28min in this case) but when I check the MuteIVR it does not make sense. Would you please advise how I can fix it?

That is odd. I would inspect the muteIvrState = new IVRState and unMuteIvrState functions to make sure the proper value is being passed there.

I am having a half a sec to a one sec delay from the time the person says something over the phone to the time i hear it and we end up talking over each other, is there a way to reduce leg on the SIP calls?

The PSTN introduces more latency than a straight IP to IP call, but you shouldn’t be able to notice it. There is no quick fix there that I know of - those kind of issues generally involve measuring latency at at different points. Perhaps you could ask Voximplant if they have any trace tools that could help on their end.

(I have asked this Voximplant and waiting for reply but adding this here in case others are interested) How many concurrent calls can be on 1 Toll Free and/Local SIP Number from Voximplant?

Did they respond? There shouldn’t be a practical limit for you here. They have some big contact center customers that handle hundreds of simultaneous calls or more.

It would be great if there were a version of this for those of us using the official Docker images and docker-compose.yml. @Monotoko has added some useful context here: Working Voximplant example code for Jigasi SIP dial-in but I’m authenticating my users through LDAP, and even after creating an LDAP service account for jigasi, I’m still getting “Cannot load details for contact jigasi@auth.meet.jitsi : XMPPError: item-not-found - cancel” in the Jigasi logs, and all my progress seems to have stopped there.

The VoxImplant IVR (Amazon Polly, from the logs) recites the meeting room name back to me correctly, and then says that the meeting hasn’t been started (which it has been) and no further errors show up in the logs, so I’m assuming that this is all due to my inability to authenticate the Jigasi user via LDAP, somehow.