Continuing the discussion from Jibri Failed to send start Jibri IQ: org.jitsi.jicofo.recording.jibri.JibriSession$StartException: Unexpected response:
We are already following this tutorial but still getting errors when we have jibri running on 2 different servers simultaneously.
Nickname is different, but the most important is, if we only running in single server , and click recording, all be fine.
But if we run in 2 server jibri, 1 server will fail, and 1 server Is recording in the same room id.
so if I have 3 rooms, we have 3 jibri running, and 3 jibri failed.
If I get stats of jibri, it return BUSY rather than IDLE, even if jibri is crash or something
JIBRI_URL="http://127.0.0.1:2222"
curl --silent "$JIBRI_URL/jibri/api/v1.0/health"| jq -j '.status'
{
"busyStatus": "BUSY",
"health": {
"healthStatus": "HEALTHY",
"details": {}
}
}
Jicofo log
root@meet:/var/log/jitsi# tail -f jicofo.log
Jicofo 2023-02-09 09:04:10.470 INFO: [996] BridgeSelectionStrategy.select#132: Selected bridge Bridge[jid=jvbbrewery@internal.auth.meet.xxxx.com/jvb-09755752229ae5ceb.meet.xxxx.com, version=2.2.69-gad606ca2, relayId=108.136.239.214, region=ap-southeast-3, stress=0.01] with stress=0.0 for participantProperties=ParticipantProperties(region=ap-southeast-1, visitor=false)
Jicofo 2023-02-09 09:04:10.470 INFO: [996] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.allocate#323: Selected jvb-09755752229ae5ceb.meet.xxxx.com, session exists: true
Jicofo 2023-02-09 09:04:11.492 INFO: [995] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=5abeb6a5] ParticipantInviteRunnable.doInviteOrReinvite#392: Sending session-initiate to: devops@conference.meet.xxxx.com/5abeb6a5 sources={jvb=[audio=[4047405235], video=[1674184931], groups=[]]}
Jicofo 2023-02-09 09:04:11.502 INFO: [996] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=85f2635d] ParticipantInviteRunnable.doInviteOrReinvite#392: Sending session-initiate to: devops@conference.meet.xxxx.com/85f2635d sources={jvb=[audio=[4047405235], video=[1674184931], groups=[]]}
Jicofo 2023-02-09 09:04:11.786 INFO: [987] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=5abeb6a5] Participant$JingleRequestHandlerImpl.onSessionOrTransportAccept#437: Received session-accept
Jicofo 2023-02-09 09:04:11.787 INFO: [987] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.updateParticipant#523: Updating 5abeb6a5 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@31778247, sources=[audio=[], video=[], groups=[]]
Jicofo 2023-02-09 09:04:11.839 INFO: [987] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=85f2635d] Participant$JingleRequestHandlerImpl.onSessionOrTransportAccept#437: Received session-accept
Jicofo 2023-02-09 09:04:11.840 INFO: [987] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.updateParticipant#523: Updating 85f2635d with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@74751a0d, sources=[audio=[], video=[], groups=[]]
Jicofo 2023-02-09 09:04:32.165 INFO: [993] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.updateParticipant#523: Updating 5abeb6a5 with transport=null, sources=[audio=[2398050608], video=[], groups=[]]
Jicofo 2023-02-09 09:04:32.166 INFO: [44] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=85f2635d] Participant.sendQueuedRemoteSources#329: Sending a queued source-add, sources={5abeb6a5=[audio=[2398050608], video=[], groups=[]]}
Jicofo 2023-02-09 09:07:16.929 INFO: [990] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] BaseJibri.handleJibriRequest#75: Accepted jibri request: <iq xmlns='jabber:client' to='focus@auth.meet.xxxx.com/focus' from='devops@conference.meet.xxxx.com/5abeb6a5' id='Zm9jdXNAYXV0aC5tZWV0LnBpbnRhci5jby9mb2N1cwA1ODcwMzFhZi1mMDBhLTQxM2MtYmY5OC04OTYzNmVkZTlmMjM6c2VuZElRAIg+8v8g0M75PbDcwY906rI=' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='start' recording_mode='file' app_data='{"file_recording_metadata":{"share":true}}'/></iq>
Jicofo 2023-02-09 09:07:16.930 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.startInternal#316: Starting session with Jibri jibribrewery@internal.auth.meet.xxxx.com/jibri-0dfcae189d79a34fb
Jicofo 2023-02-09 09:07:16.930 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.sendJibriStartIq#470: Starting Jibri jibribrewery@internal.auth.meet.xxxx.com/jibri-0dfcae189d79a34fb for stream ID: null in room: devops@conference.meet.xxxx.com
Jicofo 2023-02-09 09:07:31.931 SEVERE: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.sendJibriStartIq#514: Unexpected response to start request: null
Jicofo 2023-02-09 09:07:31.931 SEVERE: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.startInternal#322: Failed to send start Jibri IQ: org.jitsi.jicofo.jibri.JibriSession$StartException$UnexpectedResponse: Unexpected response
org.jitsi.jicofo.jibri.JibriSession$StartException$UnexpectedResponse: Unexpected response
at org.jitsi.jicofo.jibri.JibriSession.sendJibriStartIq(JibriSession.java:518)
at org.jitsi.jicofo.jibri.JibriSession.startInternal(JibriSession.java:318)
at org.jitsi.jicofo.jibri.JibriSession.start(JibriSession.java:282)
at org.jitsi.jicofo.jibri.JibriRecorder.handleStartRequest(JibriRecorder.kt:116)
at org.jitsi.jicofo.jibri.BaseJibri.doHandleIQRequest(BaseJibri.kt:170)
at org.jitsi.jicofo.jibri.BaseJibri.incomingIqQueue$lambda-0(BaseJibri.kt:56)
at org.jitsi.utils.queue.PacketQueue$HandlerAdapter.handleItem(PacketQueue.java:416)
at org.jitsi.utils.queue.AsyncQueueHandler$1.run(AsyncQueueHandler.java:136)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
Jicofo 2023-02-09 09:07:31.931 INFO: [1000] JibriDetector.instanceFailed#88: Instance failed: jibribrewery@internal.auth.meet.xxxx.com/jibri-0dfcae189d79a34fb. Will not be selected for the next PT1M
Jicofo 2023-02-09 09:07:31.931 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.startInternal#316: Starting session with Jibri jibribrewery@internal.auth.meet.xxxx.com/jibri-06b710b25167ba9c7
Jicofo 2023-02-09 09:07:31.931 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.sendJibriStartIq#470: Starting Jibri jibribrewery@internal.auth.meet.xxxx.com/jibri-06b710b25167ba9c7 for stream ID: null in room: devops@conference.meet.xxxx.com
Jicofo 2023-02-09 09:07:31.932 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.reschedulePendingTimeout#553: Rescheduling pending timeout task for room: devops@conference.meet.xxxx.com
Jicofo 2023-02-09 09:07:41.116 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.processJibriIqFromJibri#431: Updating status from JIBRI: <iq xmlns='jabber:client' to='focus@auth.meet.xxxx.com/focus' from='jibribrewery@internal.auth.meet.xxxx.com/jibri-06b710b25167ba9c7' id='MA35C-64685' type='result'><jibri xmlns='http://jitsi.org/protocol/jibri' status='pending'/></iq> for devops@conference.meet.xxxx.com
Jicofo 2023-02-09 09:07:41.116 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriSession.handleJibriStatusUpdate#619: Got Jibri status update: Jibri jibribrewery@internal.auth.meet.xxxx.com/jibri-06b710b25167ba9c7 has status pending and failure reason null, current Jibri jid is jibribrewery@internal.auth.meet.xxxx.com/jibri-06b710b25167ba9c7
Jicofo 2023-02-09 09:07:41.116 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JibriRecorder.handleStartRequest#117: Started Jibri session
Jicofo 2023-02-09 09:07:43.736 INFO: [1000] ConferenceIqHandler.handleConferenceIq#69: Focus request for room: devops@conference.meet.xxxx.com
Jicofo 2023-02-09 09:07:43.909 INFO: [990] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JitsiMeetConferenceImpl.onMemberJoined#589: Member joined:cac71f03 stats-id=jibri-0dfcae189d79a34fb region=ap-southeast-1 audioMuted=true videoMuted=true role=OWNER isJibri=true isJigasi=false
Jicofo 2023-02-09 09:07:43.910 INFO: [990] DiscoveryUtil.discoverParticipantFeatures#164: Discovered features for devops@conference.meet.xxxx.com/cac71f03 in 0 ms.
Jicofo 2023-02-09 09:07:43.910 WARNING: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=cac71f03] ParticipantInviteRunnable.lambda$doRun$0#194: Failed to convert ContentPacketExtension to Media: <content creator='initiator' name='data' senders='both'><transport xmlns='urn:xmpp:jingle:transports:ice-udp:1'><fingerprint xmlns='urn:xmpp:jingle:apps:dtls:0'/></transport><description xmlns='urn:xmpp:jingle:apps:rtp:1' media='application'/></content>
Jicofo 2023-02-09 09:07:43.910 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.allocate#277: Allocating for cac71f03
Jicofo 2023-02-09 09:07:43.911 INFO: [1000] BridgeSelectionStrategy.select#132: Selected bridge Bridge[jid=jvbbrewery@internal.auth.meet.xxxx.com/jvb-09755752229ae5ceb.meet.xxxx.com, version=2.2.69-gad606ca2, relayId=108.136.239.214, region=ap-southeast-3, stress=0.00] with stress=0.00128 for participantProperties=ParticipantProperties(region=ap-southeast-1, visitor=false)
Jicofo 2023-02-09 09:07:43.914 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.allocate#323: Selected jvb-09755752229ae5ceb.meet.xxxx.com, session exists: true
Jicofo 2023-02-09 09:07:43.942 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=cac71f03] ParticipantInviteRunnable.doInviteOrReinvite#392: Sending session-initiate to: devops@conference.meet.xxxx.com/cac71f03 sources={5abeb6a5=[audio=[2398050608], video=[], groups=[]], jvb=[audio=[4047405235], video=[1674184931], groups=[]]}
Jicofo 2023-02-09 09:07:44.623 INFO: [994] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=cac71f03] Participant$JingleRequestHandlerImpl.onSessionOrTransportAccept#437: Received session-accept
Jicofo 2023-02-09 09:07:44.623 INFO: [994] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.updateParticipant#523: Updating cac71f03 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@1d5e232e, sources=[audio=[], video=[], groups=[]]
Jicofo 2023-02-09 09:07:45.621 INFO: [1000] ConferenceIqHandler.handleConferenceIq#69: Focus request for room: devops@conference.meet.xxxx.com
Jicofo 2023-02-09 09:07:45.794 INFO: [994] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] JitsiMeetConferenceImpl.onMemberJoined#589: Member joined:55a7ce21 stats-id=jibri-06b710b25167ba9c7 region=ap-southeast-1 audioMuted=true videoMuted=true role=OWNER isJibri=true isJigasi=false
Jicofo 2023-02-09 09:07:45.794 INFO: [994] DiscoveryUtil.discoverParticipantFeatures#164: Discovered features for devops@conference.meet.xxxx.com/55a7ce21 in 0 ms.
Jicofo 2023-02-09 09:07:45.795 WARNING: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=55a7ce21] ParticipantInviteRunnable.lambda$doRun$0#194: Failed to convert ContentPacketExtension to Media: <content creator='initiator' name='data' senders='both'><transport xmlns='urn:xmpp:jingle:transports:ice-udp:1'><fingerprint xmlns='urn:xmpp:jingle:apps:dtls:0'/></transport><description xmlns='urn:xmpp:jingle:apps:rtp:1' media='application'/></content>
Jicofo 2023-02-09 09:07:45.795 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.allocate#277: Allocating for 55a7ce21
Jicofo 2023-02-09 09:07:45.796 INFO: [1000] BridgeSelectionStrategy.select#132: Selected bridge Bridge[jid=jvbbrewery@internal.auth.meet.xxxx.com/jvb-09755752229ae5ceb.meet.xxxx.com, version=2.2.69-gad606ca2, relayId=108.136.239.214, region=ap-southeast-3, stress=0.01] with stress=0.00128 for participantProperties=ParticipantProperties(region=ap-southeast-1, visitor=false)
Jicofo 2023-02-09 09:07:45.796 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.allocate#323: Selected jvb-09755752229ae5ceb.meet.xxxx.com, session exists: true
Jicofo 2023-02-09 09:07:45.821 INFO: [1000] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=55a7ce21] ParticipantInviteRunnable.doInviteOrReinvite#392: Sending session-initiate to: devops@conference.meet.xxxx.com/55a7ce21 sources={5abeb6a5=[audio=[2398050608], video=[], groups=[]], jvb=[audio=[4047405235], video=[1674184931], groups=[]]}
Jicofo 2023-02-09 09:07:46.447 INFO: [999] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947 participant=55a7ce21] Participant$JingleRequestHandlerImpl.onSessionOrTransportAccept#437: Received session-accept
Jicofo 2023-02-09 09:07:46.448 INFO: [999] [room=devops@conference.meet.xxxx.com meeting_id=c16f176d-8f10-4f47-a891-dd867568c947] ColibriV2SessionManager.updateParticipant#523: Updating 55a7ce21 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@4e2f7713, sources=[audio=[], video=[], groups=[]]
My question is:
Is jibri can’t run in multiple servers (IDLE) and if one room press the recording, it will pick 1 server rather than 2 idle servers?