pastebin

JVB 2019-12-04 08:54:13.313 INFO: [17] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 11ms. Sticky failure: false
JVB 2019-12-04 08:54:15.509 FINE: [2402991] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1681): <iq type="get" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1681"><healthcheck xmlns="http://jitsi.org/protocol/healthcheck"/></iq>
JVB 2019-12-04 08:54:15.509 FINE: [2402991] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1681).
JVB 2019-12-04 08:54:15.510 FINE: [2402991] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1681) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1681" type="result"/>
JVB 2019-12-04 08:54:19.412 FINE: [2403012] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId Q6h8H-474226): <iq id="Q6h8H-474226" type="result" to="jitsi-videobridge.conf.example.com" from="conf.example.com"/>
JVB 2019-12-04 08:54:23.313 INFO: [17] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=e85829fa142309 conf_name=null,logging=false,conf_count=3,ch_count=0,v_streams=0
JVB 2019-12-04 08:54:23.319 INFO: [17] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 6ms. Sticky failure: false
JVB 2019-12-04 08:54:25.509 FINE: [2403013] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1685): <iq type="get" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1685"><healthcheck xmlns="http://jitsi.org/protocol/healthcheck"/></iq>
JVB 2019-12-04 08:54:25.509 FINE: [2403013] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1685).
JVB 2019-12-04 08:54:25.513 FINE: [2403013] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1685) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1685" type="result"/>
JVB 2019-12-04 08:54:29.412 FINE: [2403035] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId Q6h8H-474230): <iq id="Q6h8H-474230" type="result" to="jitsi-videobridge.conf.example.com" from="conf.example.com"/>
JVB 2019-12-04 08:54:29.598 FINE: [2403014] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1698): <iq type="set" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1698"><conference xmlns="http://jitsi.org/protocol/colibri" name="test" gid="ff9f3a"><content name="audio"><channel channel-bundle-id="5b2c6ac0" initiator="true" last-n="-1" endpoint="5b2c6ac0"><payload-type name="opus" clockrate="48000" id="111" channels="2"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="10" name="minptime"/><parameter value="1" name="useinbandfec"/></payload-type><payload-type id="103" name="ISAC" clockrate="16000"/><payload-type id="104" name="ISAC" clockrate="32000"/><payload-type id="126" name="telephone-event" clockrate="8000"/><rtp-hdrext id="1" uri="urn:ietf:params:rtp-hdrext:ssrc-audio-level"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/></channel></content><content name="video"><channel channel-bundle-id="5b2c6ac0" initiator="true" last-n="-1" endpoint="5b2c6ac0"><payload-type id="100" name="VP8" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="800" name="x-google-start-bitrate"/></payload-type><payload-type id="107" name="H264" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="800" name="x-google-start-bitrate"/><parameter value="42e01f;level-asymmetry-allowed=1;packetization-mode=1;" name="profile-level-id"/></payload-type><payload-type id="101" name="VP9" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="800" name="x-google-start-bitrate"/></payload-type><payload-type id="96" name="rtx" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><parameter value="100" name="apt"/></payload-type><payload-type id="99" name="rtx" clockrate="90000"><parameter value="107" name="apt"/></payload-type><payload-type id="97" name="rtx" clockrate="90000"><parameter value="101" name="apt"/></payload-type><rtp-hdrext id="3" uri="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/></channel></content><content name="data"><sctpconnection channel-bundle-id="5b2c6ac0" port="5000" initiator="true" endpoint="5b2c6ac0"/></content><channel-bundle id="5b2c6ac0"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1"><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" required="false"/></transport></channel-bundle><endpoint id="5b2c6ac0" stats-id="Abe-g7h"/></conference></iq>
JVB 2019-12-04 08:54:29.599 FINE: [2403014] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1698).
JVB 2019-12-04 08:54:29.599 INFO: [2403014] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=1bbb46f194563048 conf_name=test,logging=true,conf_count=3,ch_count=0,v_streams=0
JVB 2019-12-04 08:54:29.599 INFO: [2403014] org.jitsi.videobridge.Conference.log() CAT=stat create_content,conf_id=1bbb46f194563048,content=audio conf_count=3,ch_count=0,v_streams=0
JVB 2019-12-04 08:54:29.599 INFO: [2403014] org.ice4j.ice.Agent.log() Gathering candidates for component stream.RTP. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.599 INFO: [2403014] org.jitsi.videobridge.Conference.log() CAT=stat create_ice_tm,conf_id=1bbb46f194563048 ufrag=48sqf1dr7r0kmv,bundle=5b2c6ac0,initiator=true
JVB 2019-12-04 08:54:29.600 INFO: [2403014] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=1bbb46f194563048,content=audio,ch_id=b080fb94e40086c3,endp_id=null,stream=94339278
JVB 2019-12-04 08:54:29.600 INFO: [2403014] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=1bbb46f194563048,content=audio,ch_id=b080fb94e40086c3,endp_id=null,stream=94339278 transport=default,bundle=5b2c6ac0,initiator=true,media_type=audio,relay_type=null
JVB 2019-12-04 08:54:29.602 INFO: [2403014] org.jitsi.videobridge.Conference.log() CAT=stat create_content,conf_id=1bbb46f194563048,content=video conf_count=3,ch_count=1,v_streams=0
JVB 2019-12-04 08:54:29.602 INFO: [2403014] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=1bbb46f194563048,content=video,ch_id=30ea285bdf74db68,endp_id=null,stream=27268261
JVB 2019-12-04 08:54:29.602 INFO: [2403014] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=1bbb46f194563048,content=video,ch_id=30ea285bdf74db68,endp_id=null,stream=27268261 transport=default,bundle=5b2c6ac0,initiator=true,media_type=video,relay_type=null
JVB 2019-12-04 08:54:29.607 INFO: [2403014] org.jitsi.videobridge.Conference.log() CAT=stat create_content,conf_id=1bbb46f194563048,content=data conf_count=3,ch_count=2,v_streams=1
JVB 2019-12-04 08:54:29.608 FINE: [2403014] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1698) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1698" type="result"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test"><content name="audio"><channel endpoint="5b2c6ac0" expire="60" id="b080fb94e40086c3" initiator="true" channel-bundle-id="5b2c6ac0" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="386879879"></source></channel></content><content name="video"><channel endpoint="5b2c6ac0" expire="60" id="30ea285bdf74db68" initiator="true" channel-bundle-id="5b2c6ac0" last-n="-1" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="3564733957"></source></channel></content><content name="data"><sctpconnection endpoint="5b2c6ac0" expire="60" id="b3001c6882699bf8" initiator="true" channel-bundle-id="5b2c6ac0" port="5000"/></content><channel-bundle id="5b2c6ac0"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" pwd="1233a93orqbippca0ickbb915c" ufrag="48sqf1dr7r0kmv"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-1" setup="actpass">17:94:A8:84:5E:81:72:6D:23:EF:A5:25:0E:BE:A1:0B:76:FA:7F:FB</fingerprint><candidate component="1" foundation="1" generation="0" id="1bbb46f194563048b587fd544a28af5062a6b1d1" network="0" priority="2130706431" protocol="ssltcp" tcptype="passive" type="host" ip="111.111.111.111" port="4443"/><candidate component="1" foundation="2" generation="0" id="1bbb46f194563048b587fd544a28af5062a6c786" network="0" priority="2130706431" protocol="udp" type="host" ip="111.111.111.111" port="10000"/></transport></channel-bundle><endpoint id="5b2c6ac0" stats-id="Abe-g7h"/></conference></iq>
JVB 2019-12-04 08:54:29.934 FINE: [2403036] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1710): <iq type="set" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1710"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test" gid="ff9f3a"><channel-bundle id="5b2c6ac0"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" ufrag="Awfd" pwd="xp94GTKv7kgaxKu15vBcOTLs"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-256" required="true">05:4F:0C:36:8B:32:53:5F:62:EA:FB:B5:93:CE:62:3A:10:F4:85:DF:E0:65:2E:39:96:55:D4:73:0B:DC:91:DB</fingerprint><candidate type="host" protocol="udp" id="5c9k1vf200" ip="10.22.11.192" component="1" port="54020" foundation="658445078" generation="0" priority="2122260223" network="1"/></transport></channel-bundle></conference></iq>
JVB 2019-12-04 08:54:29.934 FINE: [2403036] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1710).
JVB 2019-12-04 08:54:29.934 FINE: [2403057] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1708): <iq type="set" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1708"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test" gid="ff9f3a"><content name="audio"><channel id="b080fb94e40086c3"><payload-type name="opus" clockrate="48000" id="111" channels="2"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="10" name="minptime"/><parameter value="1" name="useinbandfec"/></payload-type><payload-type name="ISAC" clockrate="16000" id="103" channels="1"/><payload-type name="ISAC" clockrate="32000" id="104" channels="1"/><payload-type name="telephone-event" clockrate="8000" id="126" channels="1"/><rtp-hdrext id="1" uri="urn:ietf:params:rtp-hdrext:ssrc-audio-level"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="1435694204"><parameter value="33XOeW1bIqoF1HOV-5" name="cname"/><parameter value="cfc75958-dd90-4da4-8545-ec238f513ce2-5 12a57bb7-5448-4a8b-9b4a-71a04b7cf1f6-5" name="msid"/><ssrc-info xmlns="http://jitsi.org/jitmeet" owner="test@conference.conf.example.com/5b2c6ac0"/></source></channel></content><content name="video"><channel id="30ea285bdf74db68"><payload-type name="H264" clockrate="90000" id="107" channels="1"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><parameter value="1" name="level-asymmetry-allowed"/><parameter value="1" name="packetization-mode"/><parameter value="42e01f" name="profile-level-id"/></payload-type><payload-type name="VP8" clockrate="90000" id="100" channels="1"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/></payload-type><payload-type name="VP9" clockrate="90000" id="101" channels="1"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><parameter value="0" name="profile-id"/></payload-type><payload-type name="rtx" clockrate="90000" id="96" channels="1"><parameter value="100" name="apt"/></payload-type><payload-type name="rtx" clockrate="90000" id="99" channels="1"><parameter value="107" name="apt"/></payload-type><payload-type name="rtx" clockrate="90000" id="97" channels="1"><parameter value="101" name="apt"/></payload-type><rtp-hdrext id="3" uri="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="891587888"><parameter value="33XOeW1bIqoF1HOV-5" name="cname"/><parameter value="9d7a8f15-3104-4198-ac99-2c9ffe7aeb85-5 e19b9c61-7452-4c6b-a65f-72edc910801e-5" name="msid"/><ssrc-info xmlns="http://jitsi.org/jitmeet" owner="test@conference.conf.example.com/5b2c6ac0"/></source><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="193042366"><parameter value="33XOeW1bIqoF1HOV-5" name="cname"/><parameter value="9d7a8f15-3104-4198-ac99-2c9ffe7aeb85-5 e19b9c61-7452-4c6b-a65f-72edc910801e-5" name="msid"/><ssrc-info xmlns="http://jitsi.org/jitmeet" owner="test@conference.conf.example.com/5b2c6ac0"/></source><ssrc-group xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" semantics="FID"><source ssrc="891587888"/><source ssrc="193042366"/></ssrc-group></channel></content><channel-bundle id="5b2c6ac0"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" ufrag="Awfd" pwd="xp94GTKv7kgaxKu15vBcOTLs"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-256" setup="passive" required="false">05:4F:0C:36:8B:32:53:5F:62:EA:FB:B5:93:CE:62:3A:10:F4:85:DF:E0:65:2E:39:96:55:D4:73:0B:DC:91:DB</fingerprint></transport></channel-bundle></conference></iq>
JVB 2019-12-04 08:54:29.934 FINE: [2403057] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1708).
JVB 2019-12-04 08:54:29.936 INFO: [2403036] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 10.22.11.192:54020/udp/host
JVB 2019-12-04 08:54:29.936 INFO: [2403036] org.jitsi.videobridge.IceUdpTransportManager.log() We have remote candidates for all ICE components. Starting the ICE agent.
JVB 2019-12-04 08:54:29.936 INFO: [2403036] org.ice4j.ice.Agent.log() Start ICE connectivity establishment. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.936 INFO: [2403036] org.ice4j.ice.Agent.log() Init checklist for stream stream
JVB 2019-12-04 08:54:29.936 INFO: [2403036] org.ice4j.ice.Agent.log() ICE state changed from Waiting to Running. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.936 INFO: [2403036] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=1bbb46f194563048,content=data,ch_id=b3001c6882699bf8,endp_id=5b2c6ac0 old_state=Waiting,new_state=Running
JVB 2019-12-04 08:54:29.936 INFO: [2403036] org.ice4j.ice.Agent.log() Trigger checks for pairs that were received before running state
JVB 2019-12-04 08:54:29.937 INFO: [2403036] org.ice4j.ice.Agent.log() Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):
	LocalCandidate=candidate:2 1 udp 2130706431 111.111.111.111 10000 typ host
	RemoteCandidate=candidate:10000 1 udp 1853824767 85.217.189.11 54020 typ prflx
JVB 2019-12-04 08:54:29.937 INFO: [2403036] org.ice4j.ice.ConnectivityCheckClient.log() Start connectivity checks. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.937 FINE: [2403036] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1710) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1710" type="result"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test"><channel-bundle id="5b2c6ac0"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" pwd="1233a93orqbippca0ickbb915c" ufrag="48sqf1dr7r0kmv"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-1" setup="actpass">17:94:A8:84:5E:81:72:6D:23:EF:A5:25:0E:BE:A1:0B:76:FA:7F:FB</fingerprint><candidate component="1" foundation="1" generation="0" id="1bbb46f194563048b587fd544a28af5062a6b1d1" network="0" priority="2130706431" protocol="ssltcp" tcptype="passive" type="host" ip="111.111.111.111" port="4443"/><candidate component="1" foundation="2" generation="0" id="1bbb46f194563048b587fd544a28af5062a6c786" network="0" priority="2130706431" protocol="udp" type="host" ip="111.111.111.111" port="10000"/></transport></channel-bundle><endpoint id="5b2c6ac0" stats-id="Abe-g7h"/></conference></iq>
JVB 2019-12-04 08:54:29.938 FINE: [2403058] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1713): <iq type="set" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1713"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test" gid="ff9f3a"><content name="audio"><channel channel-bundle-id="32744561" initiator="true" last-n="-1" endpoint="32744561"><payload-type name="opus" clockrate="48000" id="111" channels="2"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="10" name="minptime"/><parameter value="1" name="useinbandfec"/></payload-type><payload-type id="103" name="ISAC" clockrate="16000"/><payload-type id="104" name="ISAC" clockrate="32000"/><payload-type id="126" name="telephone-event" clockrate="8000"/><rtp-hdrext id="1" uri="urn:ietf:params:rtp-hdrext:ssrc-audio-level"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/></channel></content><content name="video"><channel channel-bundle-id="32744561" initiator="true" last-n="-1" endpoint="32744561"><payload-type id="100" name="VP8" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="800" name="x-google-start-bitrate"/></payload-type><payload-type id="107" name="H264" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="800" name="x-google-start-bitrate"/><parameter value="42e01f;level-asymmetry-allowed=1;packetization-mode=1;" name="profile-level-id"/></payload-type><payload-type id="101" name="VP9" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="800" name="x-google-start-bitrate"/></payload-type><payload-type id="96" name="rtx" clockrate="90000"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/><parameter value="100" name="apt"/></payload-type><payload-type id="99" name="rtx" clockrate="90000"><parameter value="107" name="apt"/></payload-type><payload-type id="97" name="rtx" clockrate="90000"><parameter value="101" name="apt"/></payload-type><rtp-hdrext id="3" uri="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/></channel></content><content name="data"><sctpconnection channel-bundle-id="32744561" port="5000" initiator="true" endpoint="32744561"/></content><channel-bundle id="32744561"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1"><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" required="false"/></transport></channel-bundle><endpoint id="32744561"/></conference></iq>
JVB 2019-12-04 08:54:29.938 FINE: [2403058] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1713).
JVB 2019-12-04 08:54:29.942 INFO: [2403058] org.ice4j.ice.Agent.log() Gathering candidates for component stream.RTP. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:29.942 INFO: [2403058] org.jitsi.videobridge.Conference.log() CAT=stat create_ice_tm,conf_id=1bbb46f194563048 ufrag=da3dq1dr7r0l1m,bundle=32744561,initiator=true
JVB 2019-12-04 08:54:29.942 INFO: [2403058] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=1bbb46f194563048,content=audio,ch_id=afd8abdfb2fd3e3b,endp_id=null,stream=1936041944
JVB 2019-12-04 08:54:29.942 INFO: [2403058] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=1bbb46f194563048,content=audio,ch_id=afd8abdfb2fd3e3b,endp_id=null,stream=1936041944 transport=default,bundle=32744561,initiator=true,media_type=audio,relay_type=null
JVB 2019-12-04 08:54:29.942 INFO: [2403058] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=1bbb46f194563048,content=video,ch_id=81224f2df3fb722c,endp_id=null,stream=2002402668
JVB 2019-12-04 08:54:29.942 INFO: [2403058] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=1bbb46f194563048,content=video,ch_id=81224f2df3fb722c,endp_id=null,stream=2002402668 transport=default,bundle=32744561,initiator=true,media_type=video,relay_type=null
JVB 2019-12-04 08:54:29.943 FINE: [2403057] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1708) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1708" type="result"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test"><content name="audio"><channel endpoint="5b2c6ac0" expire="60" id="b080fb94e40086c3" initiator="true" channel-bundle-id="5b2c6ac0" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="386879879"></source><ssrc>1435694204</ssrc></channel></content><content name="video"><channel endpoint="5b2c6ac0" expire="60" id="30ea285bdf74db68" initiator="true" channel-bundle-id="5b2c6ac0" last-n="-1" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="3564733957"></source><ssrc>891587888</ssrc><ssrc>193042366</ssrc></channel></content><channel-bundle id="5b2c6ac0"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" pwd="1233a93orqbippca0ickbb915c" ufrag="48sqf1dr7r0kmv"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-1" setup="active">17:94:A8:84:5E:81:72:6D:23:EF:A5:25:0E:BE:A1:0B:76:FA:7F:FB</fingerprint><candidate component="1" foundation="1" generation="0" id="1bbb46f194563048b587fd544a28af5062a6b1d1" network="0" priority="2130706431" protocol="ssltcp" tcptype="passive" type="host" ip="111.111.111.111" port="4443"/><candidate component="1" foundation="2" generation="0" id="1bbb46f194563048b587fd544a28af5062a6c786" network="0" priority="2130706431" protocol="udp" type="host" ip="111.111.111.111" port="10000"/></transport></channel-bundle><endpoint id="5b2c6ac0" stats-id="Abe-g7h"/><endpoint id="32744561"/></conference></iq>
JVB 2019-12-04 08:54:29.944 FINE: [2403058] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1713) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1713" type="result"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test"><content name="audio"><channel endpoint="32744561" expire="60" id="afd8abdfb2fd3e3b" initiator="true" channel-bundle-id="32744561" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="386879879"></source></channel></content><content name="video"><channel endpoint="32744561" expire="60" id="81224f2df3fb722c" initiator="true" channel-bundle-id="32744561" last-n="-1" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="3564733957"></source></channel></content><content name="data"><sctpconnection endpoint="32744561" expire="60" id="fe1eefee39da4896" initiator="true" channel-bundle-id="32744561" port="5000"/></content><channel-bundle id="32744561"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" pwd="4416fbek4tc267a46l5gfoianl" ufrag="da3dq1dr7r0l1m"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-1" setup="actpass">17:94:A8:84:5E:81:72:6D:23:EF:A5:25:0E:BE:A1:0B:76:FA:7F:FB</fingerprint><candidate component="1" foundation="1" generation="0" id="1bbb46f1945630487da2561c3258ade30ffffffffb2feed70" network="0" priority="2130706431" protocol="ssltcp" tcptype="passive" type="host" ip="111.111.111.111" port="4443"/><candidate component="1" foundation="2" generation="0" id="1bbb46f1945630487da2561c3258ade30ffffffffb2ff0325" network="0" priority="2130706431" protocol="udp" type="host" ip="111.111.111.111" port="10000"/></transport></channel-bundle><endpoint id="5b2c6ac0" stats-id="Abe-g7h"/><endpoint id="32744561"/></conference></iq>
JVB 2019-12-04 08:54:29.962 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 111.111.111.111:10000/udp/host -> 85.217.189.11:54020/udp/prflx (stream.RTP). Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.962 INFO: [2407092] org.ice4j.socket.MergingDatagramSocket.log() Adding allowed address: 85.217.189.11:54020/udp
JVB 2019-12-04 08:54:29.962 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 111.111.111.111:10000/udp/host -> 85.217.189.11:54020/udp/prflx (stream.RTP). Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.962 INFO: [2407092] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 111.111.111.111:10000/udp/host -> 85.217.189.11:54020/udp/prflx (stream.RTP). Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.962 INFO: [2407092] org.ice4j.ice.Agent.log() verify if nominated pair answer again
JVB 2019-12-04 08:54:29.963 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:false. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 111.111.111.111:10000/udp/host -> 85.217.189.11:54020/udp/prflx (stream.RTP). Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:true. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Nomination confirmed for pair: 111.111.111.111:10000/udp/host -> 85.217.189.11:54020/udp/prflx (stream.RTP). Loal ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.ice4j.ice.CheckList.log() Selected pair for stream stream.RTP: 111.111.111.111:10000/udp/host -> 85.217.189.11:54020/udp/prflx (stream.RTP)
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.ice4j.ice.Agent.log() CheckList of stream stream is COMPLETED
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.ice4j.ice.Agent.log() ICE state changed from Running to Completed. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=1bbb46f194563048,content=data,ch_id=b3001c6882699bf8,endp_id=5b2c6ac0 old_state=Running,new_state=Completed
JVB 2019-12-04 08:54:29.983 INFO: [2407092] org.ice4j.ice.Agent.log() Harvester used for selected pair for stream.RTP (local ufrag 48sqf1dr7r0kmv): host
JVB 2019-12-04 08:54:29.984 INFO: [2407089] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=1bbb46f194563048,content=audio,ch_id=b080fb94e40086c3,endp_id=5b2c6ac0,stream=94339278
JVB 2019-12-04 08:54:29.992 INFO: [2407089] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=1bbb46f194563048,content=video,ch_id=30ea285bdf74db68,endp_id=5b2c6ac0,stream=27268261
JVB 2019-12-04 08:54:29.994 INFO: [2407089] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=1bbb46f194563048,content=data,ch_id=b3001c6882699bf8,endp_id=5b2c6ac0
JVB 2019-12-04 08:54:29.999 WARNING: [2407099] org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.log() Unknown DTLS handshake message type: -20
JVB 2019-12-04 08:54:30.036 INFO: [2406761] org.jitsi.videobridge.Conference.log() CAT=stat ds_change,conf_id=1bbb46f194563048 ds_id=5b2c6ac0
JVB 2019-12-04 08:54:30.036 WARNING: [2406761] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 5b2c6ac0 not ready yet.
JVB 2019-12-04 08:54:30.037 WARNING: [2406761] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message
JVB 2019-12-04 08:54:30.037 WARNING: [2406761] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 32744561 not ready yet.
JVB 2019-12-04 08:54:30.037 WARNING: [2406761] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message
JVB 2019-12-04 08:54:30.037 INFO: [2406761] org.jitsi.videobridge.cc.BitrateController.log() new track projection for  subjective_quality=0,primary_ssrc=891587888,secondary_ssrcs={193042366=rtx},temporal_id=-1,spatial_id=-1
JVB 2019-12-04 08:54:30.037 WARNING: [2406761] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 32744561 not ready yet.
JVB 2019-12-04 08:54:30.037 WARNING: [2406761] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message
JVB 2019-12-04 08:54:30.049 INFO: [2407103] net.sf.fmj.media.Log.info() Resetting queue, last seq added: 9223372036854775806, current seq: 4415
JVB 2019-12-04 08:54:30.055 INFO: [2407114] net.sf.fmj.media.Log.info() Resetting queue, last seq added: 9223372036854775806, current seq: 1244
JVB 2019-12-04 08:54:30.100 INFO: [2407121] org.jitsi.videobridge.SctpConnection.log() SCTP socket accepted on conf_id=1bbb46f194563048,content=data,ch_id=b3001c6882699bf8,endp_id=5b2c6ac0
JVB 2019-12-04 08:54:30.101 INFO: [2407121] org.jitsi.videobridge.EndpointMessageTransport.log() WebRTC data channel established for conf_id=1bbb46f194563048,content=data,ch_id=b3001c6882699bf8,endp_id=5b2c6ac0
JVB 2019-12-04 08:54:30.210 WARNING: [2407129] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 32744561 not ready yet.
JVB 2019-12-04 08:54:30.211 WARNING: [2407129] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message
JVB 2019-12-04 08:54:30.373 FINE: [2403080] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1723): <iq type="set" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1723"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test" gid="ff9f3a"><content name="audio"><channel id="afd8abdfb2fd3e3b"><payload-type name="opus" clockrate="48000" id="111" channels="2"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><parameter value="10" name="minptime"/><parameter value="1" name="useinbandfec"/></payload-type><payload-type name="ISAC" clockrate="16000" id="103" channels="1"/><payload-type name="ISAC" clockrate="32000" id="104" channels="1"/><payload-type name="telephone-event" clockrate="8000" id="126" channels="1"/><rtp-hdrext id="1" uri="urn:ietf:params:rtp-hdrext:ssrc-audio-level"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="3530961863"><parameter value="FW6kJsiKscBv0zlt-9" name="cname"/><parameter value="4954a5b7-fcce-4786-8147-56042a88ce02-9 5e621e12-90b6-4bf6-9a63-f5c33e6492ce-9" name="msid"/><ssrc-info xmlns="http://jitsi.org/jitmeet" owner="test@conference.conf.example.com/32744561"/></source></channel></content><content name="video"><channel id="81224f2df3fb722c"><payload-type name="VP8" clockrate="90000" id="100" channels="1"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/></payload-type><payload-type name="VP9" clockrate="90000" id="101" channels="1"><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="transport-cc"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="ccm" subtype="fir"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack"/><rtcp-fb xmlns="urn:xmpp:jingle:apps:rtp:rtcp-fb:0" type="nack" subtype="pli"/></payload-type><payload-type name="rtx" clockrate="90000" id="96" channels="1"><parameter value="100" name="apt"/></payload-type><payload-type name="rtx" clockrate="90000" id="97" channels="1"><parameter value="101" name="apt"/></payload-type><rtp-hdrext id="3" uri="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time"/><rtp-hdrext id="5" uri="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01"/><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="3539721617"><parameter value="FW6kJsiKscBv0zlt-9" name="cname"/><parameter value="a167ca85-5a9c-415c-847a-f1245f615540-9 524186cd-d3a0-4578-9913-5e9e185ef4b2-9" name="msid"/><ssrc-info xmlns="http://jitsi.org/jitmeet" owner="test@conference.conf.example.com/32744561"/></source><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="3589880365"><parameter value="FW6kJsiKscBv0zlt-9" name="cname"/><parameter value="a167ca85-5a9c-415c-847a-f1245f615540-9 524186cd-d3a0-4578-9913-5e9e185ef4b2-9" name="msid"/><ssrc-info xmlns="http://jitsi.org/jitmeet" owner="test@conference.conf.example.com/32744561"/></source><ssrc-group xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" semantics="FID"><source ssrc="3539721617"/><source ssrc="3589880365"/></ssrc-group></channel></content><channel-bundle id="32744561"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" ufrag="xXUx" pwd="HjY+q2ebg9jhPHVbIMgGKL8D"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-256" setup="passive" required="false">4C:38:DB:22:CB:70:D6:D2:9F:96:79:BF:BF:BD:29:1A:26:27:66:23:EB:A0:65:86:C0:18:38:E8:1B:D9:1A:87</fingerprint></transport></channel-bundle></conference></iq>
JVB 2019-12-04 08:54:30.373 FINE: [2403080] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1723).
JVB 2019-12-04 08:54:30.374 INFO: [2403080] org.jitsi.videobridge.cc.BitrateController.log() new track projection for  subjective_quality=0,primary_ssrc=3539721617,secondary_ssrcs={3589880365=rtx},temporal_id=-1,spatial_id=-1
JVB 2019-12-04 08:54:30.375 INFO: [2403080] org.jitsi.videobridge.IceUdpTransportManager.log() Starting ICE agent without remote candidates.
JVB 2019-12-04 08:54:30.375 INFO: [2403080] org.ice4j.ice.Agent.log() Start ICE connectivity establishment. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.375 INFO: [2403080] org.ice4j.ice.Agent.log() Init checklist for stream stream
JVB 2019-12-04 08:54:30.375 INFO: [2403080] org.ice4j.ice.Agent.log() ICE state changed from Waiting to Running. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.376 INFO: [2403080] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=1bbb46f194563048,content=data,ch_id=fe1eefee39da4896,endp_id=32744561 old_state=Waiting,new_state=Running
JVB 2019-12-04 08:54:30.376 INFO: [2403080] org.ice4j.ice.Agent.log() Trigger checks for pairs that were received before running state
JVB 2019-12-04 08:54:30.376 INFO: [2403080] org.ice4j.ice.Agent.log() Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):
	LocalCandidate=candidate:2 1 udp 2130706431 111.111.111.111 10000 typ host
	RemoteCandidate=candidate:10000 1 udp 1853824767 85.217.189.11 53830 typ prflx
JVB 2019-12-04 08:54:30.376 INFO: [2403080] org.ice4j.ice.ConnectivityCheckClient.log() Start connectivity checks. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.377 FINE: [2403080] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1723) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1723" type="result"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test"><content name="audio"><channel endpoint="32744561" expire="60" id="afd8abdfb2fd3e3b" initiator="true" channel-bundle-id="32744561" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="386879879"></source><ssrc>3530961863</ssrc></channel></content><content name="video"><channel endpoint="32744561" expire="60" id="81224f2df3fb722c" initiator="true" channel-bundle-id="32744561" last-n="-1" rtp-level-relay-type="translator"><source xmlns="urn:xmpp:jingle:apps:rtp:ssma:0" ssrc="3564733957"></source><ssrc>3589880365</ssrc><ssrc>3539721617</ssrc></channel></content><channel-bundle id="32744561"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" pwd="4416fbek4tc267a46l5gfoianl" ufrag="da3dq1dr7r0l1m"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-1" setup="active">17:94:A8:84:5E:81:72:6D:23:EF:A5:25:0E:BE:A1:0B:76:FA:7F:FB</fingerprint><candidate component="1" foundation="1" generation="0" id="1bbb46f1945630487da2561c3258ade30ffffffffb2feed70" network="0" priority="2130706431" protocol="ssltcp" tcptype="passive" type="host" ip="111.111.111.111" port="4443"/><candidate component="1" foundation="2" generation="0" id="1bbb46f1945630487da2561c3258ade30ffffffffb2ff0325" network="0" priority="2130706431" protocol="udp" type="host" ip="111.111.111.111" port="10000"/></transport></channel-bundle><endpoint id="5b2c6ac0" stats-id="Abe-g7h"/><endpoint id="32744561"/></conference></iq>
JVB 2019-12-04 08:54:30.378 FINE: [2402944] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1727): <iq type="set" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1727"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test" gid="ff9f3a"><channel-bundle id="32744561"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" ufrag="xXUx" pwd="HjY+q2ebg9jhPHVbIMgGKL8D"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-256" required="true">4C:38:DB:22:CB:70:D6:D2:9F:96:79:BF:BF:BD:29:1A:26:27:66:23:EB:A0:65:86:C0:18:38:E8:1B:D9:1A:87</fingerprint><candidate type="host" protocol="udp" id="jf0nkb3doh" ip="10.22.9.147" component="1" port="53830" foundation="2518818704" generation="0" priority="2122260223" network="1"/></transport></channel-bundle></conference></iq>
JVB 2019-12-04 08:54:30.378 FINE: [2402944] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1727).
JVB 2019-12-04 08:54:30.378 INFO: [2402944] org.ice4j.ice.Component.log() Update remote candidate for stream.RTP: 10.22.9.147:53830/udp
JVB 2019-12-04 08:54:30.378 INFO: [2402944] org.ice4j.ice.Component.log() new Pair added: 111.111.111.111:10000/udp/host -> 10.22.9.147:53830/udp/host (stream.RTP). Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.379 FINE: [2402944] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1727) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1727" type="result"><conference xmlns="http://jitsi.org/protocol/colibri" id="1bbb46f194563048" name="test"><channel-bundle id="32744561"><transport xmlns="urn:xmpp:jingle:transports:ice-udp:1" pwd="4416fbek4tc267a46l5gfoianl" ufrag="da3dq1dr7r0l1m"><rtcp-mux/><fingerprint xmlns="urn:xmpp:jingle:apps:dtls:0" hash="sha-1" setup="active">17:94:A8:84:5E:81:72:6D:23:EF:A5:25:0E:BE:A1:0B:76:FA:7F:FB</fingerprint><candidate component="1" foundation="1" generation="0" id="1bbb46f1945630487da2561c3258ade30ffffffffb2feed70" network="0" priority="2130706431" protocol="ssltcp" tcptype="passive" type="host" ip="111.111.111.111" port="4443"/><candidate component="1" foundation="2" generation="0" id="1bbb46f1945630487da2561c3258ade30ffffffffb2ff0325" network="0" priority="2130706431" protocol="udp" type="host" ip="111.111.111.111" port="10000"/></transport></channel-bundle><endpoint id="5b2c6ac0" stats-id="Abe-g7h"/><endpoint id="32744561"/></conference></iq>
JVB 2019-12-04 08:54:30.396 INFO: [2407095] org.ice4j.ice.ConnectivityCheckClient.log() Pair failed: 111.111.111.111:10000/udp/host -> 10.22.9.147:53830/udp/host (stream.RTP)
JVB 2019-12-04 08:54:30.399 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 111.111.111.111:10000/udp/host -> 85.217.189.11:53830/udp/prflx (stream.RTP). Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.399 INFO: [2407092] org.ice4j.socket.MergingDatagramSocket.log() Adding allowed address: 85.217.189.11:53830/udp
JVB 2019-12-04 08:54:30.399 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 111.111.111.111:10000/udp/host -> 85.217.189.11:53830/udp/prflx (stream.RTP). Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.399 INFO: [2407092] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 111.111.111.111:10000/udp/host -> 85.217.189.11:53830/udp/prflx (stream.RTP). Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.399 INFO: [2407092] org.ice4j.ice.Agent.log() verify if nominated pair answer again
JVB 2019-12-04 08:54:30.399 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:false. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.419 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 111.111.111.111:10000/udp/host -> 85.217.189.11:53830/udp/prflx (stream.RTP). Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.419 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:true. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.419 INFO: [2407092] org.ice4j.ice.ConnectivityCheckClient.log() Nomination confirmed for pair: 111.111.111.111:10000/udp/host -> 85.217.189.11:53830/udp/prflx (stream.RTP). Loal ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.419 INFO: [2407092] org.ice4j.ice.CheckList.log() Selected pair for stream stream.RTP: 111.111.111.111:10000/udp/host -> 85.217.189.11:53830/udp/prflx (stream.RTP)
JVB 2019-12-04 08:54:30.419 INFO: [2407092] org.ice4j.ice.Agent.log() CheckList of stream stream is COMPLETED
JVB 2019-12-04 08:54:30.419 INFO: [2407092] org.ice4j.ice.Agent.log() ICE state changed from Running to Completed. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:30.420 INFO: [2407092] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=1bbb46f194563048,content=data,ch_id=fe1eefee39da4896,endp_id=32744561 old_state=Running,new_state=Completed
JVB 2019-12-04 08:54:30.420 INFO: [2407092] org.ice4j.ice.Agent.log() Harvester used for selected pair for stream.RTP (local ufrag da3dq1dr7r0l1m): host
JVB 2019-12-04 08:54:30.420 INFO: [2407094] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=1bbb46f194563048,content=audio,ch_id=afd8abdfb2fd3e3b,endp_id=32744561,stream=1936041944
JVB 2019-12-04 08:54:30.425 INFO: [2407094] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=1bbb46f194563048,content=video,ch_id=81224f2df3fb722c,endp_id=32744561,stream=2002402668
JVB 2019-12-04 08:54:30.432 INFO: [2407094] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=1bbb46f194563048,content=data,ch_id=fe1eefee39da4896,endp_id=32744561
JVB 2019-12-04 08:54:30.448 INFO: [2407126] org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender.log() Sending a FIR to ssrc=891587888 remainingRetries=9
JVB 2019-12-04 08:54:30.546 INFO: [2407140] org.jitsi.videobridge.SctpConnection.log() SCTP socket accepted on conf_id=1bbb46f194563048,content=data,ch_id=fe1eefee39da4896,endp_id=32744561
JVB 2019-12-04 08:54:30.547 INFO: [2407140] org.jitsi.videobridge.EndpointMessageTransport.log() WebRTC data channel established for conf_id=1bbb46f194563048,content=data,ch_id=fe1eefee39da4896,endp_id=32744561
JVB 2019-12-04 08:54:30.549 INFO: [2407114] net.sf.fmj.media.Log.info() Resetting queue, last seq added: 9223372036854775806, current seq: 977
JVB 2019-12-04 08:54:32.984 INFO: [2407004] org.ice4j.ice.Agent.log() ICE state changed from Completed to Terminated. Local ufrag 48sqf1dr7r0kmv
JVB 2019-12-04 08:54:32.984 INFO: [2407004] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=1bbb46f194563048,content=data,ch_id=b3001c6882699bf8,endp_id=5b2c6ac0 old_state=Completed,new_state=Terminated
JVB 2019-12-04 08:54:33.319 INFO: [17] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=edaa0355b149139c conf_name=null,logging=false,conf_count=4,ch_count=6,v_streams=4
JVB 2019-12-04 08:54:33.328 INFO: [17] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 9ms. Sticky failure: false
JVB 2019-12-04 08:54:33.420 INFO: [2407004] org.ice4j.ice.Agent.log() ICE state changed from Completed to Terminated. Local ufrag da3dq1dr7r0l1m
JVB 2019-12-04 08:54:33.421 INFO: [2407004] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=1bbb46f194563048,content=data,ch_id=fe1eefee39da4896,endp_id=32744561 old_state=Completed,new_state=Terminated
JVB 2019-12-04 08:54:34.789 INFO: [2407103] net.sf.fmj.media.Log.info() Resetting queue, last seq added: 9223372036854775806, current seq: 13788
JVB 2019-12-04 08:54:35.434 INFO: [2406761] org.jitsi.videobridge.Conference.log() CAT=stat ds_change,conf_id=1bbb46f194563048 ds_id=32744561
JVB 2019-12-04 08:54:35.490 FINE: [2402922] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1735): <iq type="get" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1735"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
JVB 2019-12-04 08:54:35.490 FINE: [2402922] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1735).
JVB 2019-12-04 08:54:35.490 FINE: [2402922] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1735) with: <iq type="result" id="CZvdl-1735" from="jitsi-videobridge.conf.example.com" to="focus@auth.conf.example.com/focus1183387704963818"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="conference" name="JitsiVideobridge"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="urn:xmpp:ping"/><feature var="jabber:iq:last"/><feature var="urn:xmpp:time"/><feature var="http://jitsi.org/protocol/colibri"/><feature var="http://jitsi.org/protocol/healthcheck"/><feature var="urn:xmpp:jingle:apps:dtls:0"/><feature var="urn:xmpp:jingle:transports:ice-udp:1"/><feature var="urn:xmpp:jingle:transports:raw-udp:1"/><feature var="jabber:iq:version"/></query></iq>
JVB 2019-12-04 08:54:35.509 FINE: [2402945] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1745): <iq type="get" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1745"><healthcheck xmlns="http://jitsi.org/protocol/healthcheck"/></iq>
JVB 2019-12-04 08:54:35.509 FINE: [2402945] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1745).
JVB 2019-12-04 08:54:35.509 FINE: [2402945] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1745) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1745" type="result"/>
JVB 2019-12-04 08:54:35.751 INFO: [16] org.jitsi.videobridge.VideobridgeExpireThread.log() Running expire()
JVB 2019-12-04 08:54:35.751 INFO: [2406985] org.jitsi.videobridge.Conference.log() CAT=stat expire_conf,conf_id=8111599116fbff4e duration=388,conf_count=2,ch_count=6,v_streams=4,conf_completed=11,no_payload_ch=5,no_transport_ch=0,total_ch=46,has_failed=false,has_partially_failed=false
JVB 2019-12-04 08:54:35.751 INFO: [2406984] org.jitsi.videobridge.Conference.log() CAT=stat expire_conf,conf_id=dbc68fa1ed64033 duration=157,conf_count=1,ch_count=6,v_streams=4,conf_completed=12,no_payload_ch=5,no_transport_ch=0,total_ch=50,has_failed=false,has_partially_failed=false
JVB 2019-12-04 08:54:39.412 FINE: [2402946] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId Q6h8H-474246): <iq id="Q6h8H-474246" type="result" to="jitsi-videobridge.conf.example.com" from="conf.example.com"/>
JVB 2019-12-04 08:54:43.328 INFO: [17] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=9a4c906875aa33fb conf_name=null,logging=false,conf_count=2,ch_count=6,v_streams=4
JVB 2019-12-04 08:54:43.342 INFO: [17] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 14ms. Sticky failure: false
JVB 2019-12-04 08:54:45.509 FINE: [2402969] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId CZvdl-1750): <iq type="get" to="jitsi-videobridge.conf.example.com" from="focus@auth.conf.example.com/focus1183387704963818" id="CZvdl-1750"><healthcheck xmlns="http://jitsi.org/protocol/healthcheck"/></iq>
JVB 2019-12-04 08:54:45.509 FINE: [2402969] org.jitsi.videobridge.xmpp.ComponentImpl.processIQRequest() (serving component 'JitsiVideobridge') Processing IQ request (packetId CZvdl-1750).
JVB 2019-12-04 08:54:45.510 FINE: [2402969] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Responding to IQ (packetId CZvdl-1750) with: <iq to="focus@auth.conf.example.com/focus1183387704963818" from="jitsi-videobridge.conf.example.com" id="CZvdl-1750" type="result"/>
JVB 2019-12-04 08:54:49.412 FINE: [2402967] org.jitsi.videobridge.xmpp.ComponentImpl.processIQ() (serving component 'JitsiVideobridge') Processing IQ (packetId Q6h8H-474250): <iq id="Q6h8H-474250" type="result" to="jitsi-videobridge.conf.example.com" from="conf.example.com"/>