Docker jibri: sip video cannot bridge to web

tifcty1 · February 17, 2023, 10:12am

Hello everyone,
I want to build docker-jitsi-meet with multiple jibri sip video gateway instances,
it almost done.

I can invite sip user to the meet.
Display 0(/dev/video0) could show the meeting with two participants (I used screen capture to see it)
Display 1 (/dev/video1) could also show the sip video.
The Sip user(linphone desktop 5.0.8 vp8) could see the two participants meeting.

The problem is, the remote video cannot see in the web, it only shows a dark avater with “Fellow Jister”, the memberslist couldn’t see this user.

The SDP shows the video was droped by later negotiation (m=video 0, pt=100 vp8)

jvb logs:

JVB 2023-02-17 09:44:27.525 INFO: [88] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703] EndpointConnectionStatusMonitor.start#58: Starting connection status monitor                                                
JVB 2023-02-17 09:44:27.530 INFO: [88] Videobridge.createConference#282: create_conf, id=fc230a6ae36f59e1 meetingId=6b184703-1362-41e2-bff5-0cfd4945a6ed                                                                                    
JVB 2023-02-17 09:44:27.533 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.gatherCandidates#647: Gathering candidates for componen
t stream-5e188029.RTP.                                                                                                                                                                                                                      
JVB 2023-02-17 09:44:27.545 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029] Endpoint.<init>#328: Created new endpoint isUsingSourceNames=true, iceControlling=true                       
JVB 2023-02-17 09:44:27.558 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.gatherCandidates#647: Gathering candidates for component 
stream-3fc43959.RTP.                                                                                                                                                                                                                        JVB 2023-02-17 09:44:27.566 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959] Endpoint.<init>#328: Created new endpoint isUsingSourceNames=true, iceControlling=true                       
JVB 2023-02-17 09:44:27.774 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act a
s server                                                                                                                                                                                                                                    
JVB 2023-02-17 09:44:27.774 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd] IceTransport.startConnectivityEstablishment#199: Starting the A
gent without remote candidates.                                                                                                                                                                                                             
JVB 2023-02-17 09:44:27.774 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.startConnectivityEstablishment#736: 
Start ICE connectivity establishment.                                                                                                                                                                                                       
JVB 2023-02-17 09:44:27.774 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.initCheckLists#972: Init checklist f
or stream stream-5e188029                                                                                                                                                                                                                   
JVB 2023-02-17 09:44:27.774 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.setState#946: ICE state changed from
 Waiting to Running.                                                                                                                                                                                                                        
 JVB 2023-02-17 09:44:27.774 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd] IceTransport.iceStateChanged#342: ICE state changed old=Waitin
g                                                                                                                                                                                                                                           
  new=Running                                                                                                                                                                                                                               
                                                                                                                                                                                                                                            
  JVB 2023-02-17 09:44:27.775 INFO: [217] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.startChecks#147:
 S                                                                                                                                                                                                                                          
  tart connectivity checks.                                                                                                                                                                                                                                                                                                                                                                                                                                                             
  JVB 2023-02-17 09:44:27.776 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.triggerCheck#1737: Add peer Candid
at                                                                                                                                                                                                                                            ePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):           
                                                                                                                                                                                                                                            
          LocalCandidate=candidate:1 1 udp 2130706431 172.18.0.4 10001 typ host                                                                                                                                                             
                                                                                                                                                                                                                                            
                  RemoteCandidate=candidate:10000 1 udp 1853824767 172.16.31.83 43046 typ prflx                                                                                                                                             
                                                                                                                                                                                                                                            
                  JVB 2023-02-17 09:44:27.801 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.
processSuccessResp                                                                                                                                                                                                                          
                  onse#649: Pair succeeded: 172.18.0.4:10001/udp/host -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).                                                                                                                
                                                                                                                                                                                                                                            
                  JVB 2023-02-17 09:44:27.802 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd name=stream-5e188029 comp
onentId=1] Compone                                                                                                                                                                                                                          
                  ntSocket.addAuthorizedAddress#99: Adding allowed address: 172.16.31.83:43046/udp                                                                                                                                          
JVB 2023-02-17 09:44:27.802 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd name=stream-5e188029 componentId=1] [57/88]
ntSocket.addAuthorizedAddress#99: Adding allowed address: 172.16.31.83:43046/udp                                                                                                                                                            
JVB 2023-02-17 09:44:27.802 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#658: Pair validated: 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).                                                                                                                               
JVB 2023-02-17 09:44:27.802 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] DefaultNominator.strategyNominateFirstHost
OrReflexiveValid#268: Nominate (first highest valid): 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP)                                                                                                    
JVB 2023-02-17 09:44:27.802 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.nominate#1810: verify if nominated p
air answer again                                                                                                                                                                                                                            
JVB 2023-02-17 09:44:27.806 WARNING: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd name=stream-5e188029 componentId=1] Merg
ingDatagramSocket.initializeActive#599: Active socket already initialized.                                                                                                                                                                  
JVB 2023-02-17 09:44:27.807 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#727: IsControlling: true USE-CANDIDATE:false.                                                                                                                                                                                          
JVB 2023-02-17 09:44:27.808 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#649: Pair succeeded: 172.18.0.4:10001/udp/host -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).                                                                                                                                  
JVB 2023-02-17 09:44:27.808 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#658: Pair validated: 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).                                                                                                                               
JVB 2023-02-17 09:44:27.808 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
JVB 2023-02-17 09:44:27.808 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#658: Pair validated: 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).                                                                                                                               
JVB 2023-02-17 09:44:27.808 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#727: IsControlling: true USE-CANDIDATE:false.                             
JVB 2023-02-17 09:44:27.822 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#649: Pair succeeded: 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).
JVB 2023-02-17 09:44:27.823 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#658: Pair validated: 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).
JVB 2023-02-17 09:44:27.823 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#727: IsControlling: true USE-CANDIDATE:true.     
JVB 2023-02-17 09:44:27.823 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] ConnectivityCheckClient.processSuccessResp
onse#742: Nomination confirmed for pair: 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP).
JVB 2023-02-17 09:44:27.823 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd name=stream-5e188029] CheckList.handleNomin
ationConfirmed#406: Selected pair for stream stream-5e188029.RTP: 172.16.29.45:10001/udp/srflx -> 172.16.31.83:43046/udp/prflx (stream-5e188029.RTP)
JVB 2023-02-17 09:44:27.823 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.checkListStatesUpdated#1901: CheckLi
st of stream stream-5e188029 is COMPLETED                                                                                                                                                                                                   
JVB 2023-02-17 09:44:27.823 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.setState#946: ICE state changed from
 Running to Completed.                                                                                                                                                                                                                      
JVB 2023-02-17 09:44:27.823 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd] IceTransport.iceStateChanged#342: ICE state changed old=Running
 new=Completed                               
JVB 2023-02-17 09:44:27.824 INFO: [230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ] Endpoint$setupIceTransport[.connected#375: ICE connected                                
JVB 2023-02-17 09:44:27.824 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.Dtl
sServer@268cbc56                                           
JVB 2023-02-17 09:44:27.825 INFO:
[230] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.logCandTypes#2009: Harvester used fo
r selected pair for stream-5e188029.RTP: srflx                                                                                                                                                                                              
JVB 2023-02-17 09:44:27.826 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2                   
JVB 2023-02-17 09:44:27.844 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ] Endpoint$setupDtlsTransport{.handshakeComplete#419: DTLS handshake complete
JVB 2023-02-17 09:44:28.027 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd name=stream-5e188029 componentId=1] Compone
ntSocket.addAuthorizedAddress#99: Adding allowed address: 172.16.31.83:50667/udp
JVB 2023-02-17 09:44:28.094 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll
 act as server                                                                                                                                                                                                                              
 JVB 2023-02-17 09:44:28.095 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2] IceTransport.startConnectivityEstablishment#199: Starting
 the Agent without remote candidates.                                               
 JVB 2023-02-17 09:44:28.096 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.startConnectivityEstablishment#
736: Start ICE connectivity establishment.
 JVB 2023-02-17 09:44:28.096 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.initCheckLists#972: Init checkl
ist for stream stream-3fc43959
 JVB 2023-02-17 09:44:28.098 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.setState#946: ICE state changed
 from Waiting to Running.     
 JVB 2023-02-17 09:44:28.098 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2] IceTransport.iceStateChanged#342: ICE state changed old=W
$iting new=Running     
 JVB 2023-02-17 09:44:28.099 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.startConnectivityEstablishment#
$}]
58: Trigger checks for pairs that were received before running state                                                                                                                                                                  [0/87]
JVB 2023-02-17 09:44:28.100 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.triggerCheck#1737: Add peer Cand
idatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7926369428998979583):
        LocalCandidate=candidate:1 1 udp 2130706431 172.18.0.4 10001 typ host
                RemoteCandidate=candidate:10000 1 udp 1845501695 172.18.0.6 51198 typ prflx
                JVB 2023-02-17 09:44:28.101 INFO: [221] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.startChecks#147: Start connectivity checks.
                JVB 2023-02-17 09:44:28.123 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#649: Pair succeeded: 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP).
                JVB 2023-02-17 09:44:28.123 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2 name=stream-3fc43959 co
mponentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 172.18.0.6:51198/udp
                JVB 2023-02-17 09:44:28.123 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#658: Pair validated: 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP).
 JVB 2023-02-17 09:44:28.123 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#658: Pair validated: 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP).
                JVB 2023-02-17 09:44:28.123 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] DefaultNominator.strat
egyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP)                                                                                     
                
                JVB 2023-02-17 09:44:28.124 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.nominate#1810: v
erify if nominated pair answer again
                JVB 2023-02-17 09:44:28.124 WARNING: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2 name=stream-3fc43959
 componentId=1]
                MergingDatagramSocket.initializeActive#599: Active socket already initialized.
                JVB 2023-02-17 09:44:28.124 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
                JVB 2023-02-17 09:44:28.125 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#649: Pair succeeded: 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP).
                JVB 2023-02-17 09:44:28.125 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
                JVB 2023-02-17 09:44:28.145 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#649: Pair succeeded: 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP).
                JVB 2023-02-17 09:44:28.145 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
                JVB 2023-02-17 09:44:28.145 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] ConnectivityCheckClien
t.processSuccessResponse#742: Nomination confirmed for pair: 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP).                                                                                                 
                
                JVB 2023-02-17 09:44:28.145 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2 name=stream-3fc43959] C
heckList.handleNominationConfirmed#406: Selected pair for stream stream-3fc43959.RTP: 172.18.0.4:10001/udp/host -> 172.18.0.6:51198/udp/prflx (stream-3fc43959.RTP)                                                                         
                
                JVB 2023-02-17 09:44:28.146 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.checkListStatesU
pdated#1901: CheckList of stream stream-3fc43959 is COMPLETED
                JVB 2023-02-17 09:44:28.147 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.setState#946: IC
E state changed
                from Running to Completed.
                JVB 2023-02-17 09:44:28.147 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2] IceTransport.iceStateChanged#342: ICE stat
e changed old=Running new=Completed
                JVB 2023-02-17 09:44:28.147 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584] Endpoint$setupIceTransport[.connected#375: ICE connected            
               
                JVB 2023-02-17 09:44:28.148 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.logCandTypes#200
9: Harvester used for selected pair for stream-3fc43959.RTP: host
                JVB 2023-02-17 09:44:28.148 INFO: [235] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=
org.jitsi.nlj.dtls.DtlsServer@3938d4a7
                JVB 2023-02-17 09:44:28.148 INFO: [235] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 
1.2            
                JVB 2023-02-17 09:44:28.158 INFO: [235] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584] Endpoint$setupDtlsTransport{.handshakeComplete#419: DTLS handshake c
omplete       
                JVB 2023-02-17 09:44:28.184 INFO: [224] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2 name=stream-3fc43959 co
mponentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 172.18.0.1:45739/udp
                JVB 2023-02-17 09:44:30.825 INFO: [226] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd ufrag=3afgb1gpfc5gkd] Agent.setState#946: ICE st
ate changed fro$ Completed to Terminated.
                JVB 2023-02-17 09:44:30.825 INFO: [226] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=5e188029 stats_id=Odell-aXQ local_ufrag=3afgb1gpfc5gkd] IceTransport.iceStateChanged#342: ICE state cha
nged old=Comple"d new=Terminated
                JVB 2023-02-17 09:44:31.148 INFO: [226] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2 ufrag=22vg1gpfc5gl2] Agent.setState#946: IC
E state changed
                from Completed to Terminated.
                JVB 2023-02-17 09:44:31.148 INFO: [226] [confId=fc230a6ae36f59e1 conf_name=1@muc.meet.jitsi meeting_id=6b184703 epId=3fc43959 stats_id=jibri-565303584 local_ufrag=22vg1gpfc5gl2] IceTransport.iceStateChanged#342: ICE stat
e changed old=C$mpleted new=Terminated"}]

jibri logs is

Jibri 2023-02-17 09:52:31.612 FINE: [178] [hostname=xmpp.meet.jitsi id=xmpp.meet.jitsi] MucClient$3.handleIQRequest#504: Received an IQ with type set: IQ Stanza (jibri http://jitsi.org/protocol/jibri) [to=jibri@auth.meet.jitsi/RO4UWsTHqMAy,from=sipbrewery@internal-muc.meet.jitsi/focus,id=amlicmlAYXV0aC5tZWV0LmppdHNpL1JPNFVXc1RIcU1BeQBJM1pRWi0xODgyMgDsRCiwhipBTg==,type=set,]
Jibri 2023-02-17 09:52:31.612 INFO: [178] XmppApi.handleJibriIq#236: Received JibriIq <iq xmlns='jabber:client' to='jibri@auth.meet.jitsi/RO4UWsTHqMAy' from='sipbrewery@internal-muc.meet.jitsi/focus' id='amlicmlAYXV0aC5tZWV0LmppdHNpL1JPNFVXc1RIcU1BeQBJM1pRWi0xODgyMgDsRCiwhipBTg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='start' recording_mode='file' room='1@muc.meet.jitsi' displayname='1045' sipaddress='1045@rcs.runaiot.sh' session_id='nzmvzbihphyecups'/></iq> from environment [MucClient id=xmpp.meet.jitsi hostname=xmpp.meet.jitsi]
Jibri 2023-02-17 09:52:31.612 INFO: [178] XmppApi.handleStartJibriIq#268: Received start request, starting service
Jibri 2023-02-17 09:52:31.612 INFO: [178] XmppApi.handleStartService#379: Parsed call url info: CallUrlInfo(baseUrl=https://jitsi3.runaiot.sh, callName=1, urlParams=[])
Jibri 2023-02-17 09:52:31.612 INFO: [178] JibriManager.startSipGateway#176: Starting a SIP gateway with params: ServiceParams(usageTimeoutMinutes=0, appData=null) SipGatewayServiceParams(callParams=CallParams(callUrlInfo=CallUrlInfo(baseUrl=https://jitsi3.runaiot.sh, callName=1, urlParams=[]), email='', passcode=null, callStatsUsernameOverride=, displayName=), callLoginParams=XmppCredentials(domain=recorder.meet.jitsi, port=null, username=recorder, password=*****), sipClientParams=SipClientParams(sipAddress=1045@rcs.runaiot.sh, displayName=1045, autoAnswer=false, userName=null, password=null))
Jibri 2023-02-17 09:52:31.615 FINE: [178] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.chrome.flags' from source 'config' as type kotlin.collections.List<kotlin.String>
Jibri 2023-02-17 09:52:31.615 FINE: [178] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.chrome.flags' from source 'config' as type kotlin.collections.List<kotlin.String>
Starting ChromeDriver 109.0.5414.74 (e7c5703604daa9cc128ccf5a5d3e993513758913-refs/branch-heads/5414@{#1172}) on port 8872
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
Jibri 2023-02-17 09:52:32.009 INFO: [178] org.openqa.selenium.remote.ProtocolHandshake.createSession: Detected dialect: OSS
Jibri 2023-02-17 09:52:32.014 FINE: [178] JibriStatsDClient.incrementCounter#38: Incrementing statsd counter: start:sip_gateway
Jibri 2023-02-17 09:52:32.014 INFO: [178] JibriStatusManager$special$$inlined$observable$1.afterChange#72: Busy status has changed: IDLE -> BUSY
Jibri 2023-02-17 09:52:32.015 FINE: [178] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
Jibri 2023-02-17 09:52:32.015 INFO: [178] XmppApi.updatePresence#209: Jibri reports its status is now JibriStatus(busyStatus=BUSY, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
Jibri 2023-02-17 09:52:32.015 FINE: [178] MucClientManager.setPresenceExtension#160: Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@747902ef
Jibri 2023-02-17 09:52:32.015 FINE: [178] MucClientManager.saveExtension#185: Replacing presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@17cb5285
Jibri 2023-02-17 09:52:32.029 INFO: [178] XmppApi.handleStartJibriIq#281: Sending 'pending' response to start IQ
Jibri 2023-02-17 09:52:32.030 INFO: [176] AbstractPageObject.visit#32: Visiting url https://jitsi3.runaiot.sh
Jibri 2023-02-17 09:52:32.030 FINE: [44] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-02-17 09:52:32.033 FINE: [44] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-02-17 09:52:32.037 FINE: [44] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-02-17 09:52:32.038 FINE: [44] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-02-17 09:52:33.846 INFO: [176] AbstractPageObject.visit#38: Waited 1.816097811s for driver to load page
Jibri 2023-02-17 09:52:33.913 INFO: [176] AbstractPageObject.visit#32: Visiting url https://jitsi3.runaiot.sh/1#config.iAmRecorder=true&config.iAmSipGateway=true&config.ignoreStartMuted=true&config.analytics.disabled=true&config.enableEmailInStats=false&config.p2p.enabled=false&config.prejoinPageEnabled=false&config.prejoinConfig.enabled=false&config.requireDisplayName=false&devices.videoInput="PJSUA"
Jibri 2023-02-17 09:52:34.636 INFO: [176] AbstractPageObject.visit#38: Waited 722.994518ms for driver to load page
Jibri 2023-02-17 09:52:34.692 FINE: [176] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: Cannot read properties of undefined (reading 'isJoined')
Jibri 2023-02-17 09:52:35.197 FINE: [176] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: Cannot read properties of undefined (reading 'isJoined')
Jibri 2023-02-17 09:52:35.725 INFO: [176] CallPage.visit#71: Waited 1.088889770s to join the conference
Jibri 2023-02-17 09:52:35.726 INFO: [176] EmptyCallStatusCheck.<init>#26: Starting empty call check with a timeout of PT30M
Jibri 2023-02-17 09:52:35.726 INFO: [176] LocalParticipantKickedStatusCheck.<init>#14: Starting local participant kicked out call check
Jibri 2023-02-17 09:52:35.758 INFO: [176] JibriSelenium.onSeleniumStateChange#216: Transitioning from state Starting up to Running
Jibri 2023-02-17 09:52:35.759 INFO: [176] SipGatewayJibriService$start$2.invoke#139: Selenium joined the call, starting pjsua
Jibri 2023-02-17 09:52:35.759 FINE: [176] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.sip.outbound-prefix' from source 'config' as type kotlin.String
Jibri 2023-02-17 09:52:35.760 FINE: [176] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.sip.outbound-prefix' from source 'config' as type kotlin.String
Jibri 2023-02-17 09:52:35.760 INFO: [176] JibriSubprocess.launch#42: Starting pjsua with command /opt/jitsi/jibri/pjsua.sh --id=1045 <sip:jibri@127.0.0.1> sip:1045@rcs.runaiot.sh ([/opt/jitsi/jibri/pjsua.sh, --id=1045 <sip:jibri@127.0.0.1>, sip:1045@rcs.runaiot.sh])
Jibri 2023-02-17 09:52:40.762 FINE: [19] ProcessStatePublisher$startProcessAliveChecks$1.invoke#86: Process pjsua hasn't written in 2 seconds, publishing periodic update
Jibri 2023-02-17 09:52:40.763 INFO: [177] StatefulJibriService.onServiceStateChange#39: SIP gateway service transitioning from state Starting up to Running
Jibri 2023-02-17 09:52:40.763 INFO: [177] XmppApi$createServiceStatusHandler$1.invoke#334: Current service started up successfully, sending on iq <iq xmlns='jabber:client' to='sipbrewery@internal-muc.meet.jitsi/focus' id='V2RCN-262' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='on' sipaddress='1045@rcs.runaiot.sh'/></iq>
Jibri 2023-02-17 09:52:41.134 FINE: [19] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
Jibri 2023-02-17 09:52:42.761 FINE: [19] ProcessStatePublisher$startProcessAliveChecks$1.invoke#86: Process pjsua hasn't written in 2 seconds, publishing periodic update
Jibri 2023-02-17 09:52:44.761 FINE: [19] ProcessStatePublisher$startProcessAliveChecks$1.invoke#86: Process pjsua hasn't written in 2 seconds, publishing periodic update
Jibri 2023-02-17 09:52:46.761 FINE: [19] ProcessStatePublisher$startProcessAliveChecks$1.invoke#86: Process pjsua hasn't written in 2 seconds, publishing periodic update

Anyone who can tell me why my video losed in later sdp negotiations and how to solve it.

emrah · February 17, 2023, 10:42am

What is the device/app for your remote participant?
What is the codec between pjsua and this remote device?

tifcty1 · February 17, 2023, 10:59am

It’s linphone5.0.8 desktop vp8， I also tried h264 and vaxphone, it was the same.

tifcty1 · February 17, 2023, 11:10am

It’s linphone5.0.8 desktop vp8， I also tried h264 and vaxphone, it was the same.

emrah · February 17, 2023, 11:55am

I don’t remember the exact version but there are some problems related with H.264 in the old versions of Linphone. Can you try with 5.0.10

See my linphone notes

What is your SIP server?

tifcty1 · February 17, 2023, 12:36pm

Thank for your patiently reply. I got a lot of help from your documents and scripts.

My sip server is freeswitch 1.10.8,
I changed to Linphone 5.0.10, I tested the vp8 and h264, it was the same result.
(I configed linphone as your notes says, except the ‘h264 support’ image from your github could not load…).

I can see the chrome meeting with sip video and web video in display 0, does it means the sip process is ok, and the problem is about jvb or others?

emrah · February 17, 2023, 12:51pm

I fixed this. Thanks for the feedback.

Can you see display-1 on Jitsi side while pjsua calls the SIP client?

tifcty1 · February 17, 2023, 1:03pm

Yes, The display 0, display 1, and linphone shows as expected. (because of I have only one camera, I changed to static image in linphone. it was the same when I use cellphone for real camera.)

tifcty1 · February 17, 2023, 1:04pm

emrah · February 17, 2023, 1:07pm

Do you mean it is OK now?

tifcty1 · February 17, 2023, 1:11pm

no, I can not see the video in my PC’s browser

emrah · February 17, 2023, 1:14pm

According to images you shared everything seems OK.

“No webcam” picture is the video which comes from linphone when there is no actual camera. If your jitsi web client and linphone are on the same server, disable the camera for jitsi and connect it to linphone.

tifcty1 · February 17, 2023, 1:21pm

There are 4 terminals.

linphone is ok.
pjsua with display 1 is ok
chrome in the jibri is ok
my pc’s chrome is not ok, it showed as bellows, I can not see the remote video. (sorry for my bad description, I can only upload 1 image in 1 reply )

image1389×922 33.2 KB

emrah · February 17, 2023, 1:36pm

How do you start SIP call?

tifcty1 · February 17, 2023, 1:48pm

I followed your jibri sip video gateway script, and wanted to tansplante to the docker-jitsi-meet to have multiple jibri docker instances with docker-compose(or k8s).

To start pjsua with

/root/pjproject/pjsip-apps/bin/pjsua-x86_64-unknown-linux-gnu --config-file /etc/jitsi/jibri/pjsua.config sip:1045@xxx.xxx.x.x.x.

a problem is the pjsip should rebuild after container run(not docker build), otherwise it cannot detect asla devices

emrah · February 17, 2023, 2:03pm

Actually everything seems OK if checked separately. May there be a CPU power issue? Do all of these components run on the same machine? If you close the Jitsi camera temporary, does it affect anything?

tifcty1 · February 17, 2023, 2:14pm

No, I can see the pc’s camera open and close in the linphone.
I have 3 machine.

a docker-jitsi-meet in a remote server,
linphone and my pc’s browser
my cell phone with a sip client.(it was the same with 2)

emrah · February 17, 2023, 2:33pm

Does your Jitsi web client work with 3 participants?

Is there any error in the browser console?

tifcty1 · February 17, 2023, 2:51pm

I just tested 3 participants with 1 chrome, 1 incognito window chrome and 1 firefox, it was ok, I cannot test with different pc for now, should I test it?
The log for invite sip in the web console is bellow,

2023-02-17T14:48:09.584Z [modules/recording/RecordingManager.js] <nu._handleJibriPresence>:  Ignoring potential jibri presence due to no session id

emrah · February 17, 2023, 3:22pm

I have just checked my setup. I don’t have this message in my console. I will inform you if I find something new but for now no idea…