Canvas BigBlueButton integration cannot join the audio in breakout room
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
Hi
When testing Canvas and BigBlueButton integration, there is an issue that only happened in the Breakout Room. It does not happen if just in the main conference room from Canvas. If creating a conference from BigBlueButton server's GreenLight UI, there is no problem in the Breakout Room.
To reproduce the error, Creating 2 Breakout Rooms, click "Ask to join" or "Join Audio" > select Microphone for the question, How would you like to join the audio?
I am getting the following error message:
There was an issue with your media devices
Did you give permission for access to your microphone? Note that a dialog should appear when you try to join audio, asking for your media device permissions, please accept that in order to join the audio conference. If that is not the case, try changing your microphone permissions in your browser's settings.
Note that the audio was working in the main conference room. I did not see any meaningful error message in Canvas, /var/log/apache2/error.log or /var/canvas/log/production.log
Can someone shed some light on how to debug this?
My canvas server is ubuntu 20.04, code version of canvas is release/2024-03-27.342
BigBlueButton server spec are
BigBlueButton Server 2.7.6 (584)
Kernel version: 5.15.0-1058-aws
Distribution: Ubuntu 20.04.6 LTS (64-bit)
Memory: 16227 MB
CPU cores: 4
1) log when attempting to connect audio on BBB server, /var/log/bigbluebutton/bbb-web.log
2024-05-23T00:34:17.670Z DEBUG o.b.web.controllers.ApiController - ApiController#join
2024-05-23T00:34:17.670Z DEBUG o.b.web.controllers.ApiController - [fullName:[myemail@domain.name], isBreakout:[true], joinViaHtml5:[true], meetingID:[1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955], password:[r17yu6ao], redirect:[true], userID:[w_bcqpzbjxbgxv-1], checksum:[bb99d26f3db80481b846181d7c756b96c9984bc552c7daecf02a54f5c8534082]]
2024-05-23T00:34:17.670Z DEBUG o.b.web.controllers.ApiController - fullName=myemail%40domain.name&isBreakout=true&joinViaHtml5=true&meetingID=1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955&password=r17yu6ao&redirect=true&userID=w_bcqpzbjxbgxv-1&checksum=bb99d26f3db80481b846181d7c756b96c9984bc552c7daecf02a54f5c8534082
2024-05-23T00:34:17.670Z INFO o.b.api.service.ValidationService - Validating join request with query string fullName=myemail%40domain.name&isBreakout=true&joinViaHtml5=true&meetingID=1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955&password=r17yu6ao&redirect=true&userID=w_bcqpzbjxbgxv-1&checksum=bb99d26f3db80481b846181d7c756b96c9984bc552c7daecf02a54f5c8534082
2024-05-23T00:34:17.671Z INFO o.b.a.m.v.GetChecksumValidator - query string after checksum removed: [fullName=myemail%40domain.name&isBreakout=true&joinViaHtml5=true&meetingID=1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955&password=r17yu6ao&redirect=true&userID=w_bcqpzbjxbgxv-1]
2024-05-23T00:34:17.671Z INFO o.b.a.m.v.GetChecksumValidator - CHECKSUM=bb99d26f3db80481b846181d7c756b96c9984bc552c7daecf02a54f5c8534082 length=64
2024-05-23T00:34:17.671Z INFO o.b.a.m.v.GetChecksumValidator - SHA256 bb99d26f3db80481b846181d7c756b96c9984bc552c7daecf02a54f5c8534082
2024-05-23T00:34:17.671Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955
2024-05-23T00:34:17.671Z INFO o.b.api.service.ServiceUtils - Meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 could not be found
2024-05-23T00:34:17.671Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 may be an external ID converting to an internal ID
2024-05-23T00:34:17.672Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 converted to internal ID 7cde63d197297f85c6f8d1a51265c1738ff8d901
2024-05-23T00:34:17.672Z INFO o.b.a.m.validator.PasswordValidator - Validating password [r17yu6ao]
2024-05-23T00:34:17.672Z INFO o.b.a.m.v.MeetingExistsValidator - Validating existence of meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955
2024-05-23T00:34:17.672Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955
2024-05-23T00:34:17.672Z INFO o.b.api.service.ServiceUtils - Meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 could not be found
2024-05-23T00:34:17.674Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 may be an external ID converting to an internal ID
2024-05-23T00:34:17.674Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 converted to internal ID 7cde63d197297f85c6f8d1a51265c1738ff8d901
2024-05-23T00:34:17.674Z INFO o.b.a.m.v.JoinPasswordValidator - Validating password r17yu6ao for meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955
2024-05-23T00:34:17.674Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955
2024-05-23T00:34:17.674Z INFO o.b.api.service.ServiceUtils - Meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 could not be found
2024-05-23T00:34:17.675Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 may be an external ID converting to an internal ID
2024-05-23T00:34:17.675Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 converted to internal ID 7cde63d197297f85c6f8d1a51265c1738ff8d901
2024-05-23T00:34:17.675Z INFO o.b.a.m.v.JoinPasswordValidator - Moderator password: r17yu6ao
2024-05-23T00:34:17.675Z INFO o.b.a.m.v.JoinPasswordValidator - Attendee password: nvrzij7c
2024-05-23T00:34:17.675Z INFO o.b.a.m.v.JoinPasswordValidator - Provided password: r17yu6ao
2024-05-23T00:34:17.675Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955
2024-05-23T00:34:17.676Z INFO o.b.api.service.ServiceUtils - Meeting with ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 could not be found
2024-05-23T00:34:17.676Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 may be an external ID converting to an internal ID
2024-05-23T00:34:17.676Z INFO o.b.api.service.ServiceUtils - Provided ID 1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955 converted to internal ID 7cde63d197297f85c6f8d1a51265c1738ff8d901
2024-05-23T00:34:17.676Z DEBUG o.b.web.controllers.ApiController - Auth token: g3hra2y2hr6s
2024-05-23T00:34:17.676Z DEBUG o.b.web.controllers.ApiController - Session token: 5nts2zetjihppeg4
2024-05-23T00:34:17.676Z INFO o.b.web.controllers.ApiController - Entered users - 0. Joined users - 1
2024-05-23T00:34:17.677Z INFO o.b.web.controllers.ApiController - Session sessionToken for myemail@domain.name [5nts2zetjihppeg4]
2024-05-23T00:34:17.677Z INFO o.b.web.controllers.ApiController - Session user-token for myemail@domain.name [null]
2024-05-23T00:34:17.677Z INFO o.b.web.controllers.ApiController - Session token: 5nts2zetjihppeg4
2024-05-23T00:34:17.677Z INFO o.b.web.controllers.ApiController - --analytics-- data={"extMeetingid":"1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955","logCode":"join_api","name":"myemail@domain.name","sessionToken":"5nts2zetjihppeg4","description":"Handle JOIN API.","meetingid":"7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955","userid":"w_e0qsc8u9ijmc"}
2024-05-23T00:34:17.677Z INFO o.b.web.controllers.ApiController - Redirecting to https://my.domain.name/html5client/join?sessionToken=5nts2zetjihppeg4
2024-05-23T00:34:18.683Z DEBUG o.b.web.controllers.ApiController - ApiController#enter
2024-05-23T00:34:18.683Z INFO o.b.api.service.ValidationService - Validating enter request with query string sessionToken=5nts2zetjihppeg4
2024-05-23T00:34:18.684Z INFO o.b.a.m.v.MeetingExistsValidator - Validating existence of meeting with ID 7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955
2024-05-23T00:34:18.684Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955
2024-05-23T00:34:18.684Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955
2024-05-23T00:34:18.684Z INFO o.b.web.controllers.ApiController - Token 5nts2zetjihppeg4 is valid
2024-05-23T00:34:18.685Z INFO o.b.web.controllers.ApiController - Entered users - 0. Joined users - 1
2024-05-23T00:34:18.685Z INFO o.b.web.controllers.ApiController - User w_e0qsc8u9ijmc has entered
2024-05-23T00:34:18.685Z INFO o.b.web.controllers.ApiController - --analytics-- data={"extMeetingid":"1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955","logCode":"handle_enter_api","name":"myemail@domain.name","sessionToken":"5nts2zetjihppeg4","description":"Handling ENTER API.","meetingid":"7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955","userid":"w_e0qsc8u9ijmc"}
2024-05-23T00:34:18.841Z INFO o.bigbluebutton.api.MeetingService - --analytics-- data={"role":"MODERATOR","clientType":"HTML5","externalUserId":"w_bcqpzbjxbgxv-1","logCode":"user_joined_message","name":"BSTC 001-2 (Room 1)","description":"User joined the meeting.","meetingId":"7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955","externalMeetingId":"1583c485e643d8e2c4e0f26ebc3fb0bb9178e26d-1716424135955","guest":false,"guestStatus":"ALLOW","userId":"w_e0qsc8u9ijmc","username":"myemail@domain.name"}
2024-05-23T00:34:19.689Z DEBUG o.b.web.controllers.ApiController - ApiController#stuns
2024-05-23T00:34:19.689Z INFO o.b.api.service.ValidationService - Validating stuns request with query string sessionToken=5nts2zetjihppeg4
2024-05-23T00:34:19.690Z INFO o.b.a.m.v.MeetingExistsValidator - Validating existence of meeting with ID 7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955
2024-05-23T00:34:19.690Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955
2024-05-23T00:34:19.690Z INFO o.b.api.service.ServiceUtils - Attempting to find meeting with ID 7cde63d197297f85c6f8d1a51265c1738ff8d901-1716424135955
2024-05-23T00:34:19.690Z INFO o.b.web.controllers.ApiController - Token 5nts2zetjihppeg4 is valid
2024-05-23T00:34:19.691Z INFO o.b.w.services.turn.StunTurnService -
StunTurnService::getStunServers
2024-05-23T00:34:19.691Z INFO o.b.w.services.turn.StunTurnService -
StunTurnService::getStunAndTurnServersFor w_e0qsc8u9ijmc
2) log from /opt/freeswitch/var/log/freeswitch/freeswitch.log
07f0b23e-31c9-4b12-aaae-6b4301d5ec9f 2024-05-23 00:34:17.513058 93.40% [NOTICE] sofia.c:1065 Hangup sofia/external/w_bcqpzbjxbgxv_2-bbbID-myemail@domain.namem@my.ip.address [CS_EXECUTE] [NORMAL_CLEARING]
07f0b23e-31c9-4b12-aaae-6b4301d5ec9f 2024-05-23 00:34:17.513058 93.40% [INFO] conference_loop.c:1668 Channel leaving conference, cause: NORMAL_CLEARING
07f0b23e-31c9-4b12-aaae-6b4301d5ec9f 2024-05-23 00:34:17.513058 93.40% [NOTICE] switch_core_session.c:1762 Session 20 (sofia/external/w_bcqpzbjxbgxv_2-bbbID-myemail@domain.namem@my.ip.address) Ended
07f0b23e-31c9-4b12-aaae-6b4301d5ec9f 2024-05-23 00:34:17.513058 93.40% [NOTICE] switch_core_session.c:1766 Close Channel sofia/external/w_bcqpzbjxbgxv_2-bbbID-myemail@domain.namem@my.ip.address [CS_DESTROY]
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [NOTICE] switch_channel.c:1142 New Channel sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address [07d92e68-b1c0-4c33-ba14-2883f67a2cf0]
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [INFO] sofia.c:10459 sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address receiving invite from my.ip.address:52312 version: 1.10.11-release 64bit call-id: mrh7i2jsmtvjp5ugcoka
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [INFO] mod_dialplan_xml.c:639 Processing w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem <w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem>->000000100000000000031 in context public
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 EXECUTE [depth=0] sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address set(outside_call=true)
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 EXECUTE [depth=0] sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address export(RFC2822_DATE=Thu, 23 May 2024 00:34:20 +0000)
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 EXECUTE [depth=0] sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address set(presence_data=from_bbb-webrtc-sfu)
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 EXECUTE [depth=0] sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address set(bbb_authorized=true)
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 EXECUTE [depth=0] sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address set(rtp_manual_rtp_bugs=ACCEPT_ANY_PACKETS)
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 EXECUTE [depth=0] sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address set(jb_use_timestamps=true)
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 EXECUTE [depth=0] sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address transfer(000000100000000000031 XML default)
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [NOTICE] switch_ivr.c:2303 Transfer sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address to XML[000000100000000000031@default]
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [INFO] mod_dialplan_xml.c:639 Processing w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem <w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem>->000000100000000000031 in context default
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [INFO] switch_core_state_machine.c:306 No Route, Aborting
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address [CS_ROUTING] [NO_ROUTE_DESTINATION]
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [NOTICE] switch_core_session.c:1762 Session 21 (sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address) Ended
07d92e68-b1c0-4c33-ba14-2883f67a2cf0 2024-05-23 00:34:20.033104 92.53% [NOTICE] switch_core_session.c:1766 Close Channel sofia/external/w_e0qsc8u9ijmc_3-bbbID-myemail@domain.namem@my.ip.address [CS_DESTROY]
