Detected the following WebRTC issue: Error 1004: Failure on call (reason=SIP Failure Code).
19,392 views
Skip to first unread message
Diego Nieto
Sep 15, 2015, 4:11:19 AM9/15/15
to BigBlueButton-Setup
Hi all!,
I'm running a BBB 0.9.1 as our organization's webminar tool and i'm having problems with Chrome browsers and WebRTC. Firefox runs smoothly during the ICE negociation. Also, i've used the demo.bigbluebutton server with chrome and everything goes fine. So it has to be something with the configuration of our BBB.
I'm pasting the Chrome console output:
Also i've tried to search in the freeswitch logs and red5 logs to see if there is any error but i cannot find it, only WARN and DEBUG lines.
I've turned off the firewall to discard it and the problem still persist with no iptables rules.
I'll keep on searching for clues in the logs. In the meantime, i would be very grateful if somebody could point what the problem could be.
Thanks in advance, Diego
Diego Nieto
Sep 15, 2015, 4:22:51 AM9/15/15
to BigBlueButton-Setup
Hi:
Here the Firefox paste
I can see that in Chrome during the negociation i get a:
- sip.js?v=264:2636 Tue Sep 15 2015 09:56:41 GMT+0200 (CEST) | sip.transport | received WebSocket text message:
- SIP/2.0 502 Bad Gateway
And with Firefox i get a :
- bbb_webrtc_bridge_sip.js:314:3
- Tue Sep 15 2015 10:16:22 GMT+0200 (CEST) | sip.transport | received WebSocket text message:
- SIP/2.0 200 OK
Any clues?
Thanks in advance!!!!
Fred Dixon
Sep 15, 2015, 7:59:27 AM9/15/15
to bigbluebu...@googlegroups.com
Hi Diego,
One quick test is to connect Chrome in incognito mode -- this will ensure that all content is loaded from the server (in case there is some caching going on).
Also, for the browsers, you can also get more information from them on what's happening with WebRTC
Chrome
chrome://webrtc-internals/
FireFox
about:webrtc
The above information may help you further narrow down the source.
Regards,... Fred
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-s...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at http://groups.google.com/group/bigbluebutton-setup.
For more options, visit https://groups.google.com/d/optout.
BigBlueButton Developer
BigBlueButton on twitter: @bigbluebutton
Diego Nieto
Sep 16, 2015, 3:30:13 AM9/16/15
to BigBlueButton-Setup
Well!,
After a couple of hours reviewing logs and config files y found the problem. Simply an s that i forgot to put in a lost http url when switching from http to https. My bad!.
Anyway, thanks for the tips!
Best
El martes, 15 de septiembre de 2015, 10:11:19 (UTC+2), Diego Nieto escribió:
Fred Dixon
Sep 16, 2015, 7:00:23 AM9/16/15
to bigbluebu...@googlegroups.com
Hi Diego,
Good to hear you got it working!
Regards,... Fred
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-s...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at http://groups.google.com/group/bigbluebutton-setup.
For more options, visit https://groups.google.com/d/optout.
German Acevedo
Jul 1, 2016, 10:28:19 PM7/1/16
to BigBlueButton-Setup
Hi Fred,
What else do I need to check or enable to have RTC working?
I need your help!
After following this thread I have doubled checked all the changes in all the files in my transition of my server to use https, and everything seems Ok, but I still getting the 1004 error:
This is my result from chrome://webrtc-internals/:
Caller process id: 6320Audio ConstraintsVideo ConstraintsCaller process id: 12456Audio ConstraintsVideo ConstraintsCaller origin: https://conferencias.falcorp.netCaller process id: 12456Audio ConstraintsVideo ConstraintsCaller origin: https://conferencias.falcorp.netCaller process id: 12456Audio ConstraintsVideo ConstraintsThis is my result from about:webrtc:
(registry/INFO) insert 'ice' (registry) succeeded: ice
(registry/INFO) insert 'ice.pref' (registry) succeeded: ice.pref
(registry/INFO) insert 'ice.pref.type' (registry) succeeded: ice.pref.type
(registry/INFO) insert 'ice.pref.type.srv_rflx' (UCHAR) succeeded: 0x64
(registry/INFO) insert 'ice.pref.type.peer_rflx' (UCHAR) succeeded: 0x6e
(registry/INFO) insert 'ice.pref.type.host' (UCHAR) succeeded: 0x7e
(registry/INFO) insert 'ice.pref.type.relayed' (UCHAR) succeeded: 0x05
(registry/INFO) insert 'ice.pref.type.srv_rflx_tcp' (UCHAR) succeeded: 0x63
(registry/INFO) insert 'ice.pref.type.peer_rflx_tcp' (UCHAR) succeeded: 0x6d
(registry/INFO) insert 'ice.pref.type.host_tcp' (UCHAR) succeeded: 0x7d
(registry/INFO) insert 'ice.pref.type.relayed_tcp' (UCHAR) succeeded: 0x00
(registry/INFO) insert 'stun' (registry) succeeded: stun
(registry/INFO) insert 'stun.client' (registry) succeeded: stun.client
(registry/INFO) insert 'stun.client.maximum_transmits' (UINT4) succeeded: 7
(registry/INFO) insert 'ice.trickle_grace_period' (UINT4) succeeded: 5000
(registry/INFO) insert 'ice.tcp' (registry) succeeded: ice.tcp
(registry/INFO) insert 'ice.tcp.so_sock_count' (INT4) succeeded: 0
(registry/INFO) insert 'ice.tcp.listen_backlog' (INT4) succeeded: 10
(registry/INFO) insert 'ice.tcp.disable' (char) succeeded: \001
(ice/WARNING)
ICE(PC:1467419857099000 (id=2077
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): No
STUN servers specified
(ice/NOTICE) ICE(PC:1467419857099000
(id=2077
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): No
TURN servers specified
(ice/WARNING) ICE(PC:1467419857099000
(id=2077
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): failed
to resolve candidate
srflx(IP6:[2001:0:5ef5:79fd:24b7:4b18:4195:20f0]:63442/UDP|stun.freeswitch.org:3478).
(ice/WARNING)
ICE(PC:1467419857099000 (id=2077
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): failed
to resolve candidate
srflx(IP6:[2001:0:5ef5:79fd:24b7:4b18:4195:20f0]:56191/UDP|stun.freeswitch.org:3478).
(stun/INFO) Skipping SOURCE-ADDRESS
(stun/INFO) Skipping CHANGED-ADDRESS
(stun/INFO) Translating obsolete XOR-MAPPED-ADDRESS type
(stun/INFO) STUN-CLIENT(srflx(IP4:192.168.0.11:63441/UDP|stun.freeswitch.org:3478)): Received response; processing
(stun/INFO) Skipping SOURCE-ADDRESS
(stun/INFO) Skipping CHANGED-ADDRESS
(stun/INFO) Translating obsolete XOR-MAPPED-ADDRESS type
(stun/INFO) STUN-CLIENT(srflx(IP4:192.168.0.11:56190/UDP|stun.freeswitch.org:3478)): Received response; processing
(ice/WARNING)
ICE(PC:1467420659938000 (id=2117
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): No
STUN servers specified
(ice/NOTICE) ICE(PC:1467420659938000
(id=2117
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): No
TURN servers specified
(ice/WARNING) ICE(PC:1467420659938000
(id=2117
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): failed
to resolve candidate
srflx(IP6:[2001:0:5ef5:79fd:24b7:4b18:4195:20f0]:54029/UDP|stun.freeswitch.org:3478).
(ice/WARNING)
ICE(PC:1467420659938000 (id=2117
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): failed
to resolve candidate
srflx(IP6:[2001:0:5ef5:79fd:24b7:4b18:4195:20f0]:49600/UDP|stun.freeswitch.org:3478).
(stun/INFO) Skipping SOURCE-ADDRESS
(stun/INFO) Skipping CHANGED-ADDRESS
(stun/INFO) Translating obsolete XOR-MAPPED-ADDRESS type
(stun/INFO) STUN-CLIENT(srflx(IP4:192.168.0.11:54028/UDP|stun.freeswitch.org:3478)): Received response; processing
(stun/INFO) Skipping SOURCE-ADDRESS
(stun/INFO) Skipping CHANGED-ADDRESS
(stun/INFO) Translating obsolete XOR-MAPPED-ADDRESS type
(stun/INFO) STUN-CLIENT(srflx(IP4:192.168.0.11:49599/UDP|stun.freeswitch.org:3478)): Received response; processing
(ice/WARNING)
ICE(PC:1467426060176000 (id=2217
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): No
STUN servers specified
(ice/NOTICE) ICE(PC:1467426060176000
(id=2217
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): No
TURN servers specified
(ice/WARNING) ICE(PC:1467426060176000
(id=2217
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): failed
to resolve candidate
srflx(IP6:[2001:0:5ef5:79fd:24b7:4b18:4195:20f0]:58619/UDP|stun.freeswitch.org:3478).
(ice/WARNING)
ICE(PC:1467426060176000 (id=2217
url=https://conferencias.falcorp.net/client/BigBlueButton.html)): failed
to resolve candidate
srflx(IP6:[2001:0:5ef5:79fd:24b7:4b18:4195:20f0]:59923/UDP|stun.freeswitch.org:3478).
(stun/INFO) Skipping SOURCE-ADDRESS
(stun/INFO) Skipping CHANGED-ADDRESS
(stun/INFO) Translating obsolete XOR-MAPPED-ADDRESS type
(stun/INFO) STUN-CLIENT(srflx(IP4:192.168.0.11:58618/UDP|stun.freeswitch.org:3478)): Received response; processing
(stun/INFO) Skipping SOURCE-ADDRESS
(stun/INFO) Skipping CHANGED-ADDRESS
(stun/INFO) Translating obsolete XOR-MAPPED-ADDRESS type
(stun/INFO) STUN-CLIENT(srflx(IP4:192.168.0.11:59922/UDP|stun.freeswitch.org:3478)): Received response; processing
+++++++ END ++++++++
This is the output of sudo bbb-conf --check:
login as: rootro...@192.3.107.105's password:Welcome to Ubuntu 14.04.4 LTS (GNU/Linux 2.6.32-042stab112.15 x86_64)
* Documentation: https://help.ubuntu.com/Last login: Fri Jul 1 22:14:14 2016 from 190.106.223.15root@server1:~# clearroot@server1:~# sudo bbb-conf --check
BigBlueButton Server 1.0.0 (1176) Kernel version: 2.6.32-042stab112.15 Distribution: Ubuntu 14.04.4 LTS (64-bit) Memory: 8192 MB
/var/www/bigbluebutton/client/conf/config.xml (bbb-client) Port test (tunnel): conferencias.falcorp.net Red5: conferencias.falcorp.net useWebrtcIfAvailable: true
/opt/freeswitch/conf/sip_profiles/external.xml (FreeSWITCH) websocket port: 5066 WebRTC enabled: true
/etc/nginx/sites-available/bigbluebutton (nginx) server name: conferencias.falcorp.net port: 80 port: 443 ssl bbb-client dir: /var/www/bigbluebutton
/var/lib/tomcat7/webapps/bigbluebutton/WEB-INF/classes/bigbluebutton.properties (bbb-web) bbb-web host: conferencias.falcorp.net
/var/lib/tomcat7/webapps/demo/bbb_api_conf.jsp (API demos)
/var/www/bigbluebutton/check/conf/config.xml (client check) client check: conferencias.falcorp.net
/usr/share/red5/webapps/bigbluebutton/WEB-INF/red5-web.xml (red5) voice conference: FreeSWITCH capture video: true capture desktop: true
/usr/local/bigbluebutton/core/scripts/bigbluebutton.yml (record and playback) playback host: conferencias.falcorp.net
** Potential problems described below **# IP does not match:# IP from ifconfig: 192.3.107.105# /etc/nginx/sites-available/bigbluebutton: conferencias.falcorp.net# Warning: API URL IPs do not match host:## IP from ifconfig: 192.3.107.105# /var/lib/tomcat7/webapps/demo/bbb_api_conf.jsp: conferencias.falcorp.net
# Warning: The API demos are installed and accessible from:### These API demos allow anyone to access your server without authentication# to create/manage meetings and recordings. They are for testing purposes only.# If you are running a production system, remove them by running:## sudo apt-get purge bbb-demo
# Warning: The client self check is installed and accessible from:##
root@server1:~#
What else do I need to check or enable to have RTC working?
Thanks for your help!
Fred Dixon
Jul 1, 2016, 11:08:09 PM7/1/16
to bigbluebu...@googlegroups.com
Hi German,
Looking at the developer console when trying to join WebRTC on your server, we see the following error
call progress: SIP/2.0 100 TryingVia: SIP/2.0/WSS 27u4718lgiqe.invalid;branch=z9hG4bK9480260;received=192.3.107.105;rport=41169From: "urxuldcol154_2-bbbID-Fred" <sip:urxuldcol154...@conferencias.falcorp.net>;tag=4s3k8mj46qCall-ID: hplov6deskrpqv51pl5sCSeq: 4916 INVITEUser-Agent: FreeSWITCH-mod_sofia/1.4.21+git~20150826T145759Z~87a059bafc~64bitContent-Length: 0sip.js?v=419:2636 Fri Jul 01 2016 23:00:17 GMT-0400 (EDT) | sip.transport | received WebSocket text message:SIP/2.0 502 Bad Gateway
Which suggests that nginx is correctly routing the call to FreeSWITCH (from incoming port 443 to port 7443 to FreeSWITCH), but that FreeSWITCH is not accepting the call.
Can you output the FreeSWITCH log when you try making a WebRTC call. It should have some clues as to why the call is not completing.
Regards,... Fred
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-s...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at https://groups.google.com/group/bigbluebutton-setup.
German Acevedo
Jul 1, 2016, 11:12:25 PM7/1/16
to BigBlueButton-Setup
Hi Fred,
Firs of all thanks for your help!
How do I get the "the FreeSWITCH log when you try making a WebRTC call.?
Fred Dixon
Jul 1, 2016, 11:14:39 PM7/1/16
to bigbluebu...@googlegroups.com
Hi German,
Do the following
sudo su - root
cd /opt/freeswitch/bin
./fs_cli
Then make the call. You'll see all the FreeSWITCH log events in real-time. You'll also find the log file here
/opt/freeswitch/log/freeswitch.log
Regards,... Fred
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-s...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at https://groups.google.com/group/bigbluebutton-setup.
For more options, visit https://groups.google.com/d/optout.
German Acevedo
Jul 1, 2016, 11:20:10 PM7/1/16
to BigBlueButton-Setup
Hi Fred,
this is the output in real time:
login as: rootro...@192.3.107.105's password:Welcome to Ubuntu 14.04.4 LTS (GNU/Linux 2.6.32-042stab112.15 x86_64)
* Documentation: https://help.ubuntu.com/Last login: Fri Jul 1 22:25:56 2016 from 190.106.223.15root@server1:~# clearroot@server1:~# sudo su - rootroot@server1:~# cd /opt/freeswitch/binroot@server1:/opt/freeswitch/bin# ./fs_cli.=======================================================.| _____ ____ ____ _ ___ || | ___/ ___| / ___| | |_ _| || | |_ \___ \ | | | | | | || | _| ___) | | |___| |___ | | || |_| |____/ \____|_____|___| || |.=======================================================.| Anthony Minessale II, Ken Rice, || Michael Jerris, Travis Cross || FreeSWITCH (http://www.freeswitch.org) || Paypal Donations Appreciated: pay...@freeswitch.org || Brought to you by ClueCon http://www.cluecon.com/ |.=======================================================.
.=======================================================================================================.| ____ _ ____ || / ___| |_ _ ___ / ___|___ _ __ || | | | | | | |/ _ \ | / _ \| '_ \ || | |___| | |_| | __/ |__| (_) | | | | || \____|_|\__,_|\___|\____\___/|_| |_| || || _____ _ _ ____ __ || |_ _|__| | ___ _ __ | |__ ___ _ __ _ _ / ___|___ _ __ / _| ___ _ __ ___ _ __ ___ ___ || | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | | / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \ || | | __/ | __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | | _| __/ | | __/ | | | (_| __/ || |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, | \____\___/|_| |_|_| \___|_| \___|_| |_|\___\___| || |_| |___/ || _____ _ _ || | ____|_ _____ _ __ _ _ / \ _ _ __ _ _ _ ___| |_ || | _| \ \ / / _ \ '__| | | | / _ \| | | |/ _` | | | / __| __| || | |___ \ V / __/ | | |_| | / ___ \ |_| | (_| | |_| \__ \ |_ || |_____| \_/ \___|_| \__, | /_/ \_\__,_|\__, |\__,_|___/\__| || |___/ |___/ || ____ _ ____ || __ ____ ____ __ / ___| |_ _ ___ / ___|___ _ __ ___ ___ _ __ ___ || \ \ /\ / /\ \ /\ / /\ \ /\ / / | | | | | | |/ _ \ | / _ \| '_ \ / __/ _ \| '_ ` _ \ || \ V V / \ V V / \ V V / _ | |___| | |_| | __/ |__| (_) | | | | _ | (_| (_) | | | | | | || \_/\_/ \_/\_/ \_/\_/ (_) \____|_|\__,_|\___|\____\___/|_| |_| (_) \___\___/|_| |_| |_| || |.=======================================================================================================.
Type /help <enter> to see a list of commands
+OK log level [7]2016-07-01 23:18:21.241654 [NOTICE] switch_channel.c:1075 New Channel sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [a10df486-4003-11e6-9920-99b986f5a593]2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_NEW2016-07-01 23:18:21.241654 [DEBUG] sofia.c:8919 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net receiving invite from 192.3.107.105:34300 version: 1.4.21 git 87a059b 2015-08-26 14:57:59Z 64bit2016-07-01 23:18:21.241654 [DEBUG] sofia.c:10184 Setting NAT mode based on websockets2016-07-01 23:18:21.241654 [DEBUG] sofia.c:6634 Channel sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net entering state [received][100]2016-07-01 23:18:21.241654 [DEBUG] sofia.c:6644 Remote SDP:v=0o=- 1092116068965127922 2 IN IP4 127.0.0.1s=-t=0 0a=group:BUNDLE audioa=msid-semantic: WMS BnZqc6QwCe2LEX8P8iLMyJQQsjpBfOPgbJ34m=audio 49956 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 126c=IN IP4 190.106.223.15a=rtpmap:111 opus/48000/2a=fmtp:111 minptime=10;useinbandfec=1a=rtpmap:103 ISAC/16000a=rtpmap:104 ISAC/32000a=rtpmap:9 G722/8000a=rtpmap:0 PCMU/8000a=rtpmap:8 PCMA/8000a=rtpmap:106 CN/32000a=rtpmap:105 CN/16000a=rtpmap:13 CN/8000a=rtpmap:126 telephone-event/8000a=rtcp:49957 IN IP4 190.106.223.15a=candidate:3735410398 1 udp 2122255103 2001::5ef5:79fd:24b7:4b18:4195:20f0 58543 typ host generation 0 network-id 4a=candidate:2178013618 1 udp 2122194687 192.168.33.1 58544 typ host generation 0 network-id 3a=candidate:2999745851 1 udp 2122129151 192.168.56.1 58545 typ host generation 0 network-id 2a=candidate:3988902457 1 udp 2122063615 192.168.0.11 58546 typ host generation 0 network-id 1a=candidate:3735410398 2 udp 2122255102 2001::5ef5:79fd:24b7:4b18:4195:20f0 58547 typ host generation 0 network-id 4a=candidate:2178013618 2 udp 2122194686 192.168.33.1 58548 typ host generation 0 network-id 3a=candidate:2999745851 2 udp 2122129150 192.168.56.1 58549 typ host generation 0 network-id 2a=candidate:3988902457 2 udp 2122063614 192.168.0.11 58550 typ host generation 0 network-id 1a=candidate:2418420270 1 tcp 1518275327 2001::5ef5:79fd:24b7:4b18:4195:20f0 9 typ host tcptype active generation 0 network-id 4a=candidate:3478267202 1 tcp 1518214911 192.168.33.1 9 typ host tcptype active generation 0 network-id 3a=candidate:4233069003 1 tcp 1518149375 192.168.56.1 9 typ host tcptype active generation 0 network-id 2a=candidate:2739023561 1 tcp 1518083839 192.168.0.11 9 typ host tcptype active generation 0 network-id 1a=candidate:2418420270 2 tcp 1518275326 2001::5ef5:79fd:24b7:4b18:4195:20f0 9 typ host tcptype active generation 0 network-id 4a=candidate:3478267202 2 tcp 1518214910 192.168.33.1 9 typ host tcptype active generation 0 network-id 3a=candidate:4233069003 2 tcp 1518149374 192.168.56.1 9 typ host tcptype active generation 0 network-id 2a=candidate:2739023561 2 tcp 1518083838 192.168.0.11 9 typ host tcptype active generation 0 network-id 1a=candidate:466725869 1 udp 1685855999 190.106.223.15 49956 typ srflx raddr 192.168.0.11 rport 58546 generation 0 network-id 1a=candidate:466725869 2 udp 1685855998 190.106.223.15 49957 typ srflx raddr 192.168.0.11 rport 58550 generation 0 network-id 1a=ice-ufrag:NHtX8x+jsB+qpkkoa=ice-pwd:XJUAG7aaGLmQze2mti2m9Znka=fingerprint:sha-256 6D:D2:37:DA:92:E4:CD:90:9B:D9:72:97:AF:D8:75:AC:AE:A0:B3:B2:BD:8A:20:B2:56:C4:36:C0:AD:8E:CC:82a=setup:actpassa=mid:audioa=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-levela=rtcp-muxa=rtcp-fb:111 transport-cca=maxptime:60a=ssrc:973142168 cname:VW/CxiO726qUSCWea=ssrc:973142168 msid:BnZqc6QwCe2LEX8P8iLMyJQQsjpBfOPgbJ34 2af6eb22-f38c-4ff3-bb87-7defb7522bbda=ssrc:973142168 mslabel:BnZqc6QwCe2LEX8P8iLMyJQQsjpBfOPgbJ34a=ssrc:973142168 label:2af6eb22-f38c-4ff3-bb87-7defb7522bbd
2016-07-01 23:18:21.241654 [DEBUG] sofia.c:6910 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State Change CS_NEW -> CS_INIT2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:491 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State NEW2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_INIT2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:512 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State INIT2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:87 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SOFIA INIT2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:40 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard INIT2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:48 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State Change CS_INIT -> CS_ROUTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:512 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State INIT going to sleep2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_ROUTING2016-07-01 23:18:21.241654 [DEBUG] switch_channel.c:2204 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Callstate Change DOWN -> RINGING2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:528 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State ROUTING2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:123 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SOFIA ROUTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:166 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard ROUTING2016-07-01 23:18:21.241654 [INFO] mod_dialplan_xml.c:635 Processing ikxz7qllh1it_2-bbbID-German <ikxz7qllh1it_2-bbbID-German>->919678143 in context publicDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net parsing [public->unloop] continue=falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net parsing [public->outside_call] continue=trueDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Absolute Condition [outside_call]Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action set(outside_call=true)Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net parsing [public->bbb_sp_call] continue=trueDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (PASS) [bbb_sp_call] network_addr(192.3.107.105) =~ /192.3.107.105/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action set(bbb_authorized=true)Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action transfer(${destination_number} XML default)Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net parsing [public->bbb_webrtc_call] continue=trueDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (PASS) [bbb_webrtc_call] ${sip_via_protocol}(wss) =~ /^wss?$/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action set(bbb_authorized=true)Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action transfer(${destination_number} XML default)2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:216 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State Change CS_ROUTING -> CS_EXECUTE2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:528 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State ROUTING going to sleep2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_EXECUTE2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:535 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State EXECUTE2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:178 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SOFIA EXECUTE2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:258 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard EXECUTEEXECUTE sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net set(outside_call=true)2016-07-01 23:18:21.241654 [DEBUG] mod_dptools.c:1477 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SET [outside_call]=[true]EXECUTE sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net export(RFC2822_DATE=Fri, 01 Jul 2016 23:18:21 -0400)2016-07-01 23:18:21.241654 [DEBUG] switch_channel.c:1267 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 01 Jul 2016 23:18:21 -0400]EXECUTE sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net set(bbb_authorized=true)2016-07-01 23:18:21.241654 [DEBUG] mod_dptools.c:1477 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SET [bbb_authorized]=[true]EXECUTE sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net transfer(919678143 XML default)2016-07-01 23:18:21.241654 [DEBUG] switch_ivr.c:1856 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State Change CS_EXECUTE -> CS_ROUTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:912 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [NOTICE] switch_ivr.c:1863 Transfer sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net to XML[919678143@default]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:535 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State EXECUTE going to sleep2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_ROUTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:528 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State ROUTING2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:123 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SOFIA ROUTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:166 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard ROUTING2016-07-01 23:18:21.241654 [INFO] mod_dialplan_xml.c:635 Processing ikxz7qllh1it_2-bbbID-German <ikxz7qllh1it_2-bbbID-German>->919678143 in context defaultDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net parsing [default->unloop] continue=falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net parsing [default->bbb_conferences] continue=falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (PASS) [bbb_conferences] ${bbb_authorized}(true) =~ /true/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (FAIL) [bbb_conferences] destination_number(919678143) =~ /^(\d{5})$/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net parsing [default->bbb_echo_test_direct] continue=falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (PASS) [bbb_echo_test_direct] ${bbb_authorized}(true) =~ /true/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Regex (PASS) [bbb_echo_test_direct] destination_number(919678143) =~ /^9196$|^9196(\d{5})$/ break=on-falseDialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action set(vbridge=78143)Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action answer()Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action bind_digit_action(direct_from_echo,1,exec:execute_extension,${vbridge} XML default)Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action sleep(1500)Dialplan: sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Action echo()2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:216 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State Change CS_ROUTING -> CS_EXECUTE2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:528 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State ROUTING going to sleep2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_EXECUTE2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:535 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State EXECUTE2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:178 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SOFIA EXECUTE2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:258 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard EXECUTEEXECUTE sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net set(vbridge=78143)2016-07-01 23:18:21.241654 [DEBUG] mod_dptools.c:1477 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SET [vbridge]=[78143]EXECUTE sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net answer()2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[opus:116:48000:20:0:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[SPEEX:99:16000:20:42200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[SPEEX:99:8000:20:24600:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[G722:9:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMU:0:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMA:8:8000:20:64000:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[GSM:3:8000:20:13200:1]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3596 Set telephone-event payload to 1262016-07-01 23:18:21.241654 [DEBUG] mod_opus.c:289 Opus encoder set bitrate to local settings [-1000bps]2016-07-01 23:18:21.241654 [DEBUG] mod_opus.c:289 Opus encoder set bitrate to local settings [-1000bps]2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2507 Set Codec sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net opus/48000 20 ms 960 samples 0 bits 1 channels2016-07-01 23:18:21.241654 [DEBUG] switch_core_codec.c:111 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Original read codec set to opus:1162016-07-01 23:18:21.241654 [WARNING] switch_core_media.c:2791 NO candidate ACL defined, Defaulting to wan.auto2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 1 proto: udp type: host addr: 2001::5ef5:79fd:24b7:4b18:4195:20f0:585432016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2824 Choose audio Candidate cid: 1 proto: udp type: host addr: 2001::5ef5:79fd:24b7:4b18:4195:20f0:585432016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 1 proto: udp type: host addr: 192.168.33.1:585442016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.33.1:585442016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 1 proto: udp type: host addr: 192.168.56.1:585452016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.56.1:585452016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 1 proto: udp type: host addr: 192.168.0.11:585462016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.11:585462016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 2 proto: udp type: host addr: 2001::5ef5:79fd:24b7:4b18:4195:20f0:585472016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2824 Choose audio Candidate cid: 2 proto: udp type: host addr: 2001::5ef5:79fd:24b7:4b18:4195:20f0:585472016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 2 proto: udp type: host addr: 192.168.33.1:585482016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.33.1:585482016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 2 proto: udp type: host addr: 192.168.56.1:585492016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.56.1:585492016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 2 proto: udp type: host addr: 192.168.0.11:585502016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.0.11:585502016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 1 proto: udp type: srflx addr: 190.106.223.15:499562016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 1 proto: udp type: srflx addr: 190.106.223.15:499562016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 2 proto: udp type: srflx addr: 190.106.223.15:499572016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 2 proto: udp type: srflx addr: 190.106.223.15:499572016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2953 setting remote audio ice addr to 2001::5ef5:79fd:24b7:4b18:4195:20f0:58543 based on candidate2016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:2981 Asked by candidate to set remote rtcp audio addr to 2001::5ef5:79fd:24b7:4b18:4195:20f0:58543 but this is rtcp-mux so no thanks2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:3943 Set 2833 dtmf send/recv payload to 1262016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:5179 AUDIO RTP [sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net] 192.3.107.105 port 21774 -> 2001::5ef5:79fd:24b7:4b18:4195:20f0 port 58543 codec: 111 ms: 202016-07-01 23:18:21.241654 [DEBUG] switch_rtp.c:3579 Starting timer [soft] 960 bytes per 20ms2016-07-01 23:18:21.241654 [ERR] switch_core_media.c:5844 AUDIO RTP REPORTS ERROR: [Remote Address Error!]2016-07-01 23:18:21.241654 [NOTICE] switch_core_media.c:5845 Hangup sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]2016-07-01 23:18:21.241654 [DEBUG] switch_channel.c:3242 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [KILL]2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:2902 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:535 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State EXECUTE going to sleep2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_HANGUP2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:735 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Callstate Change RINGING -> HANGUP2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:737 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State HANGUP2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:413 Channel sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net hanging up, cause: DESTINATION_OUT_OF_ORDER2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:549 Responding to INVITE with: 5022016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:60 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard HANGUP, cause: DESTINATION_OUT_OF_ORDER2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:737 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State HANGUP going to sleep2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:504 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State Change CS_HANGUP -> CS_REPORTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:472 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_REPORTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:823 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State REPORTING2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:104 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard REPORTING, cause: DESTINATION_OUT_OF_ORDER2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:823 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State REPORTING going to sleep2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:498 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State Change CS_REPORTING -> CS_DESTROY2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [BREAK]2016-07-01 23:18:21.241654 [DEBUG] switch_core_session.c:1623 Session 19 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Locked, Waiting on external entities2016-07-01 23:18:21.241654 [NOTICE] switch_core_session.c:1641 Session 19 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Ended2016-07-01 23:18:21.241654 [NOTICE] switch_core_session.c:1645 Close Channel sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net [CS_DESTROY]2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:626 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) Running State Change CS_DESTROY2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:636 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State DESTROY2016-07-01 23:18:21.241654 [DEBUG] mod_sofia.c:323 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net SOFIA DESTROY2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:111 sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net Standard DESTROY2016-07-01 23:18:21.241654 [DEBUG] switch_core_state_machine.c:636 (sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net) State DESTROY going to sleepfreeswitch@internal>
El viernes, 1 de julio de 2016, 21:14:39 (UTC-6), Fred Dixon escribió:
Hi German,Do the followingsudo su - rootcd /opt/freeswitch/bin./fs_cliThen make the call. You'll see all the FreeSWITCH log events in real-time. You'll also find the log file here/opt/freeswitch/log/freeswitch.logRegards,... Fred
On Fri, Jul 1, 2016 at 11:12 PM, German Acevedo <acevedo...@gmail.com> wrote:
Hi Fred,Firs of all thanks for your help!How do I get the "the FreeSWITCH log when you try making a WebRTC call.?
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-setup+unsub...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at https://groups.google.com/group/bigbluebutton-setup.
For more options, visit https://groups.google.com/d/optout.
Fred Dixon
Jul 1, 2016, 11:40:16 PM7/1/16
to bigbluebu...@googlegroups.com
Hi German,
The error is occurring here
2016-07-01 23:18:21.241654 [DEBUG] switch_core_media.c:5179 AUDIO RTP [sofia/external/ikxz7qllh1it_...@conferencias.falcorp.net] 192.3.107.105 port 21774 -> 2001::5ef5:79fd:24b7:4b18:4195:20f0 port 58543 codec: 111 ms: 20
2016-07-01 23:18:21.241654 [DEBUG] switch_rtp.c:3579 Starting timer [soft] 960 bytes per 20ms
2016-07-01 23:18:21.241654 [ERR] switch_core_media.c:5844 AUDIO RTP REPORTS ERROR: [Remote Address Error!]
This is what it should look like (this is taken from our demo server).
2016-07-01 23:30:57.210473 [DEBUG] switch_core_media.c:5178 AUDIO RTP [sofia/external/1psurzg8bxhv...@demo.bigbluebutton.org] 72.55.184.150 port 22376 -> 2607:fea8:bcdf:fdb5:223:dfff:fefe:3a4c port 56574 codec: 109 ms: 20
2016-07-01 23:30:57.210473 [DEBUG] switch_rtp.c:3608 Starting timer [soft] 960 bytes per 20ms
2016-07-01 23:30:57.210473 [INFO] switch_core_media.c:5352 Activating Audio ICE
2016-07-01 23:30:57.210473 [NOTICE] switch_rtp.c:4058 Activating RTP audio ICE: 3d085027:VgTovE7fjS1LZA7Z 2607:fea8:bcdf:fdb5:223:dfff:fefe:3a4c:56574
2016-07-01 23:30:57.210473 [INFO] switch_core_media.c:5395 Activating RTCP PORT 56574
2016-07-01 23:30:57.210473 [DEBUG] switch_rtp.c:3958 RTCP send rate is: 10000 and packet rate is: 20000 Remote Port: 56574
2016-07-01 23:30:57.210473 [DEBUG] switch_rtp.c:2378 Setting RTCP remote addr to :56574
2016-07-01 23:30:57.210473 [INFO] switch_core_media.c:5403 Skipping RTCP ICE (Same as RTP)
2016-07-01 23:30:57.210473 [INFO] switch_rtp.c:3133 Activate RTP/RTCP audio DTLS client
A few questions..
1. Are their any firewalls on the server?
2. Can you try connecting to http://demo.bigbluebutton.org/ with same browser/computer and let us know if the WebRTC calls connects?
The answer to (2) will help us rule out whether the there is a firewall on your computer/internet connection that is preventing an external FreeSWITCH server from making a UDP connection.
Regards,... Fred
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-s...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at https://groups.google.com/group/bigbluebutton-setup.
For more options, visit https://groups.google.com/d/optout.
--BigBlueButton Developer@bigbluebutton
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-s...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at https://groups.google.com/group/bigbluebutton-setup.
For more options, visit https://groups.google.com/d/optout.
German Acevedo
Jul 1, 2016, 11:51:06 PM7/1/16
to BigBlueButton-Setup
A few questions..
1. Are their any firewalls on the server?
I asked this to the hosting provider of my server and they said that "There is no firewall on the node blocking any ports on your VM. If your ports are being blocked, then it is a block on the server itself. Try disabling iptables and trying again." It was a couple of days ago when I was trying to setup de https access. I have tested it with iptables -s and seems to be clean:
login as: rootro...@192.3.107.105's password:Welcome to Ubuntu 14.04.4 LTS (GNU/Linux 2.6.32-042stab112.15 x86_64)
* Documentation: https://help.ubuntu.com/Last login: Fri Jul 1 23:16:56 2016 from 190.106.223.15root@server1:~# sudo iptables -S-P INPUT ACCEPT-P FORWARD ACCEPT-P OUTPUT ACCEPTroot@server1:~#
2. Can you try connecting to http://demo.bigbluebutton.org/ with same browser/computer and let us know if the WebRTC calls connects?
Yes, I was able to connect by WebRTC audio to the demo server
German Acevedo
Jul 2, 2016, 1:17:20 AM7/2/16
to BigBlueButton-Setup
One strange thing:
HTML5 client on Chrome in my Android phone connects WebRTC audio without any trouble, this is the output of the freeswith log when making a call from my phone:
HTML5 client on Chrome in my Android phone connects WebRTC audio without any trouble, this is the output of the freeswith log when making a call from my phone:
login as: root
root@192.3.107.105's password:
Welcome to Ubuntu 14.04.4 LTS (GNU/Linux 2.6.32-042stab112.15 x86_64)
* Documentation: https://help.ubuntu.com/
Last login: Sat Jul 2 01:00:00 2016 from 190.106.223.15
root@server1:~# clear
root@server1:~# sudo su - root
root@server1:~# cd /opt/freeswitch/bin
root@server1:/opt/freeswitch/bin# ./fs_cli
.=======================================================.
| _____ ____ ____ _ ___ |
| | ___/ ___| / ___| | |_ _| |
| | |_ \___ \ | | | | | | |
| | _| ___) | | |___| |___ | | |
| |_| |____/ \____|_____|___| |
| |
.=======================================================.
| Anthony Minessale II, Ken Rice, |
| Michael Jerris, Travis Cross |
| FreeSWITCH (http://www.freeswitch.org) |
| Paypal Donations Appreciated: pay...@freeswitch.org |
| Brought to you by ClueCon http://www.cluecon.com/ |
.=======================================================.
.===============================================================.
| _ |
| ___| |_ _ ___ ___ ___ _ __ ___ ___ _ __ ___ |
| / __| | | | |/ _ \/ __/ _ \| '_ \ / __/ _ \| '_ ` _ \ |
| | (__| | |_| | __/ (_| (_) | | | | _ | (_| (_) | | | | | | |
| \___|_|\__,_|\___|\___\___/|_| |_| (_) \___\___/|_| |_| |_| |
| |
.===============================================================.
Type /help <enter> to see a list of commands
[This app Best viewed at 160x60 or more..]
+OK log level [7]
freeswitch@internal>
2016-07-02 01:14:38.444030 [NOTICE] switch_channel.c:1075 New Channel sofia/external/6j7yppofyqua...@conferencias.falcorp.net [dfc9e4d6-4013-11e6-9a36-b1cb34dcc9bf]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:472 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Running State Change CS_NEW
2016-07-02 01:14:38.444030 [DEBUG] sofia.c:8919 sofia/external/6j7yppofyqua...@conferencias.falcorp.net receiving invite from 192.3.107.105:40673 version: 1.4.21 git 87a059b 2015-08-26 14:57:59Z 64bit
2016-07-02 01:14:38.444030 [DEBUG] sofia.c:10184 Setting NAT mode based on websockets
2016-07-02 01:14:38.444030 [DEBUG] sofia.c:6634 Channel sofia/external/6j7yppofyqua...@conferencias.falcorp.net entering state [received][100]
2016-07-02 01:14:38.444030 [DEBUG] sofia.c:6644 Remote SDP:
v=0
o=- 7804687746046904624 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS 85kcPpHaVs5YSsvxxhh6lBD4aKlJAGRZejDW
m=audio 51204 UDP/TLS/RTP/SAVPF 111 103 9 0 8 106 105 13 126
c=IN IP4 190.106.223.15
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:51208 IN IP4 190.106.223.15
a=candidate:3100903175 1 udp 2122260223 192.168.0.22 47184 typ host generation 0 network-id 1
a=candidate:3100903175 2 udp 2122260222 192.168.0.22 42996 typ host generation 0 network-id 1
a=candidate:4132961271 1 tcp 1518280447 192.168.0.22 9 typ host tcptype active generation 0 network-id 1
a=candidate:4132961271 2 tcp 1518280446 192.168.0.22 9 typ host tcptype active generation 0 network-id 1
a=candidate:1321500371 2 udp 1686052606 190.106.223.15 51208 typ srflx raddr 192.168.0.22 rport 42996 generation 0 network-id 1
a=candidate:1321500371 1 udp 1686052607 190.106.223.15 51204 typ srflx raddr 192.168.0.22 rport 47184 generation 0 network-id 1
a=ice-ufrag:l0l6sa/aJXQlcfdw
a=ice-pwd:cW4h6R3K9A2OeUcwuzNn5vuE
a=fingerprint:sha-256 02:55:D1:60:96:5C:FE:5D:E8:7A:48:FE:EF:E5:05:EB:A3:BD:B8:42:16:CF:FF:8B:C6:D9:26:4C:28:5F:FB:67
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=rtcp-fb:111 transport-cc
a=maxptime:60
a=ssrc:2034415593 cname:LJUErf4AYd+LKCvT
a=ssrc:2034415593 msid:85kcPpHaVs5YSsvxxhh6lBD4aKlJAGRZejDW d709e83a-fe94-4ad3-ab8c-e6ec567a9e7d
a=ssrc:2034415593 mslabel:85kcPpHaVs5YSsvxxhh6lBD4aKlJAGRZejDW
a=ssrc:2034415593 label:d709e83a-fe94-4ad3-ab8c-e6ec567a9e7d
2016-07-02 01:14:38.444030 [DEBUG] sofia.c:6910 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State Change CS_NEW -> CS_INIT
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:491 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State NEW
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:472 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Running State Change CS_INIT
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:512 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State INIT
2016-07-02 01:14:38.444030 [DEBUG] mod_sofia.c:87 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SOFIA INIT
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:40 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Standard INIT
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:48 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State Change CS_INIT -> CS_ROUTING
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:512 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State INIT going to sleep
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:472 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Running State Change CS_ROUTING
2016-07-02 01:14:38.444030 [DEBUG] switch_channel.c:2204 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Callstate Change DOWN -> RINGING
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:528 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State ROUTING
2016-07-02 01:14:38.444030 [DEBUG] mod_sofia.c:123 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SOFIA ROUTING
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:166 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Standard ROUTING
2016-07-02 01:14:38.444030 [INFO] mod_dialplan_xml.c:635 Processing 6j7yppofyqua-bbbID-German <6j7yppofyqua-bbbID-German>->71233 in context public
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net parsing [public->unloop] continue=false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net parsing [public->outside_call] continue=true
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Absolute Condition [outside_call]
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action set(outside_call=true)
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net parsing [public->bbb_sp_call] continue=true
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (PASS) [bbb_sp_call] network_addr(192.3.107.105) =~ /192.3.107.105/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action set(bbb_authorized=true)
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action transfer(${destination_number} XML default)
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net parsing [public->bbb_webrtc_call] continue=true
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (PASS) [bbb_webrtc_call] ${sip_via_protocol}(wss) =~ /^wss?$/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action set(bbb_authorized=true)
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action transfer(${destination_number} XML default)
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:216 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State Change CS_ROUTING -> CS_EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:528 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State ROUTING going to sleep
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:472 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Running State Change CS_EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:535 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] mod_sofia.c:178 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SOFIA EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:258 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Standard EXECUTE
EXECUTE sofia/external/6j7yppofyqua...@conferencias.falcorp.net set(outside_call=true)
2016-07-02 01:14:38.444030 [DEBUG] mod_dptools.c:1477 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SET [outside_call]=[true]
EXECUTE sofia/external/6j7yppofyqua...@conferencias.falcorp.net export(RFC2822_DATE=Sat, 02 Jul 2016 01:14:38 -0400)
2016-07-02 01:14:38.444030 [DEBUG] switch_channel.c:1267 EXPORT (export_vars) [RFC2822_DATE]=[Sat, 02 Jul 2016 01:14:38 -0400]
EXECUTE sofia/external/6j7yppofyqua...@conferencias.falcorp.net set(bbb_authorized=true)
2016-07-02 01:14:38.444030 [DEBUG] mod_dptools.c:1477 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SET [bbb_authorized]=[true]
EXECUTE sofia/external/6j7yppofyqua...@conferencias.falcorp.net transfer(71233 XML default)
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr.c:1856 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State Change CS_EXECUTE -> CS_ROUTING
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:912 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [NOTICE] switch_ivr.c:1863 Transfer sofia/external/6j7yppofyqua...@conferencias.falcorp.net to XML[71233@default]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:535 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State EXECUTE going to sleep
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:472 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Running State Change CS_ROUTING
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:528 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State ROUTING
2016-07-02 01:14:38.444030 [DEBUG] mod_sofia.c:123 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SOFIA ROUTING
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:166 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Standard ROUTING
2016-07-02 01:14:38.444030 [INFO] mod_dialplan_xml.c:635 Processing 6j7yppofyqua-bbbID-German <6j7yppofyqua-bbbID-German>->71233 in context default
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net parsing [default->unloop] continue=false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net parsing [default->bbb_conferences] continue=false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (PASS) [bbb_conferences] ${bbb_authorized}(true) =~ /true/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Regex (PASS) [bbb_conferences] destination_number(71233) =~ /^(\d{5})$/ break=on-false
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action set(jitterbuffer_msec=20:400)
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action answer()
Dialplan: sofia/external/6j7yppofyqua...@conferencias.falcorp.net Action conference(71233@cdquality)
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:216 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State Change CS_ROUTING -> CS_EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:528 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State ROUTING going to sleep
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:472 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Running State Change CS_EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:535 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) State EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] mod_sofia.c:178 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SOFIA EXECUTE
2016-07-02 01:14:38.444030 [DEBUG] switch_core_state_machine.c:258 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Standard EXECUTE
EXECUTE sofia/external/6j7yppofyqua...@conferencias.falcorp.net set(jitterbuffer_msec=20:400)
2016-07-02 01:14:38.444030 [DEBUG] mod_dptools.c:1477 sofia/external/6j7yppofyqua...@conferencias.falcorp.net SET [jitterbuffer_msec]=[20:400]
EXECUTE sofia/external/6j7yppofyqua...@conferencias.falcorp.net answer()
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[opus:116:48000:20:0:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[SPEEX:99:16000:20:42200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[SPEEX:99:8000:20:24600:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[G722:9:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMU:0:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMA:8:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [opus:111:48000:60:0:1]/[GSM:3:8000:20:13200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[SPEEX:99:16000:20:42200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[SPEEX:99:8000:20:24600:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[G722:9:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[GSM:3:8000:20:13200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[opus:116:48000:20:0:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[SPEEX:99:16000:20:42200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[SPEEX:99:8000:20:24600:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[G722:9:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [G722:9:8000:60:64000:1]/[GSM:3:8000:20:13200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[opus:116:48000:20:0:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[SPEEX:99:16000:20:42200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[SPEEX:99:8000:20:24600:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[G722:9:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[GSM:3:8000:20:13200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[opus:116:48000:20:0:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[SPEEX:99:16000:20:42200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[SPEEX:99:8000:20:24600:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[G722:9:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3735 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[GSM:3:8000:20:13200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[opus:116:48000:20:0:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[SPEEX:99:16000:20:42200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[SPEEX:99:8000:20:24600:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[G722:9:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMU:0:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMA:8:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:105:16000:60:0:1]/[GSM:3:8000:20:13200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[opus:116:48000:20:0:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[SPEEX:99:16000:20:42200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[SPEEX:99:8000:20:24600:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[G722:9:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMU:0:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMA:8:8000:20:64000:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3680 Audio Codec Compare [CN:13:8000:60:0:1]/[GSM:3:8000:20:13200:1]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3596 Set telephone-event payload to 126
2016-07-02 01:14:38.444030 [DEBUG] mod_opus.c:289 Opus encoder set bitrate to local settings [-1000bps]
2016-07-02 01:14:38.444030 [DEBUG] mod_opus.c:289 Opus encoder set bitrate to local settings [-1000bps]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:2507 Set Codec sofia/external/6j7yppofyqua...@conferencias.falcorp.net opus/48000 20 ms 960 samples 0 bits 1 channels
2016-07-02 01:14:38.444030 [DEBUG] switch_core_codec.c:111 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Original read codec set to opus:116
2016-07-02 01:14:38.444030 [WARNING] switch_core_media.c:2791 NO candidate ACL defined, Defaulting to wan.auto
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 1 proto: udp type: host addr: 192.168.0.22:47184
2016-07-02 01:14:38.444030 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.22:47184
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 2 proto: udp type: host addr: 192.168.0.22:42996
2016-07-02 01:14:38.444030 [NOTICE] switch_core_media.c:2829 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.0.22:42996
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 2 proto: udp type: srflx addr: 190.106.223.15:51208
2016-07-02 01:14:38.444030 [NOTICE] switch_core_media.c:2824 Choose audio Candidate cid: 2 proto: udp type: srflx addr: 190.106.223.15:51208
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:2815 Checking Candidate cid: 1 proto: udp type: srflx addr: 190.106.223.15:51204
2016-07-02 01:14:38.444030 [NOTICE] switch_core_media.c:2824 Choose audio Candidate cid: 1 proto: udp type: srflx addr: 190.106.223.15:51204
2016-07-02 01:14:38.444030 [NOTICE] switch_core_media.c:2953 setting remote audio ice addr to 190.106.223.15:51204 based on candidate
2016-07-02 01:14:38.444030 [NOTICE] switch_core_media.c:2981 Asked by candidate to set remote rtcp audio addr to 190.106.223.15:51204 but this is rtcp-mux so no thanks
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:3943 Set 2833 dtmf send/recv payload to 126
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:5179 AUDIO RTP [sofia/external/6j7yppofyqua...@conferencias.falcorp.net] 192.3.107.105 port 22718 -> 190.106.223.15 port 51204 codec: 111 ms: 20
2016-07-02 01:14:38.444030 [DEBUG] switch_rtp.c:3579 Starting timer [soft] 960 bytes per 20ms
2016-07-02 01:14:38.444030 [INFO] switch_core_media.c:5353 Activating Audio ICE
2016-07-02 01:14:38.444030 [NOTICE] switch_rtp.c:4029 Activating RTP audio ICE: l0l6sa/aJXQlcfdw:OruVhuOUyS1mYm0f 190.106.223.15:51204
2016-07-02 01:14:38.444030 [INFO] switch_core_media.c:5396 Activating RTCP PORT 51204
2016-07-02 01:14:38.444030 [DEBUG] switch_rtp.c:3929 RTCP send rate is: 10000 and packet rate is: 20000 Remote Port: 51204
2016-07-02 01:14:38.444030 [DEBUG] switch_rtp.c:2349 Setting RTCP remote addr to 190.106.223.15:51204
2016-07-02 01:14:38.444030 [INFO] switch_core_media.c:5404 Skipping RTCP ICE (Same as RTP)
2016-07-02 01:14:38.444030 [INFO] switch_rtp.c:3104 Activate RTP/RTCP audio DTLS client
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:1748 Setting Jitterbuffer to 20ms (1 frames) (20 max frames) (0 max drift)
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:5477 Set 2833 dtmf send payload to 126
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:5483 Set 2833 dtmf receive payload to 126
2016-07-02 01:14:38.444030 [DEBUG] switch_core_media.c:5505 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Set rtp dtmf delay to 40
2016-07-02 01:14:38.444030 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/6j7yppofyqua...@conferencias.falcorp.net!
2016-07-02 01:14:38.444030 [DEBUG] switch_channel.c:3419 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Callstate Change RINGING -> EARLY
2016-07-02 01:14:38.444030 [DEBUG] mod_sofia.c:780 Local SDP sofia/external/6j7yppofyqua...@conferencias.falcorp.net:
v=0
o=FreeSWITCH 1467413760 1467413761 IN IP4 192.3.107.105
s=FreeSWITCH
c=IN IP4 192.3.107.105
t=0 0
a=msid-semantic: WMS 0krgp7abN5HraT57knNvg0a2MddgXgvN
m=audio 22718 UDP/TLS/RTP/SAVPF 111 126
a=rtpmap:111 opus/48000/2
a=fmtp:111 useinbandfec=1; minptime=10
a=rtpmap:126 telephone-event/8000
a=ptime:20
a=sendrecv
a=fingerprint:sha-256 7C:A5:A4:2E:2B:0A:A0:3E:AD:14:AD:BD:39:82:BB:50:67:24:16:58:9D:AE:C3:39:48:9C:B0:98:9C:F4:43:9F
a=setup:active
a=rtcp-mux
a=rtcp:22718 IN IP4 192.3.107.105
a=ssrc:2743245198 cname:rNTGKUInPfFQZBO9
a=ssrc:2743245198 msid:0krgp7abN5HraT57knNvg0a2MddgXgvN a0
a=ssrc:2743245198 mslabel:0krgp7abN5HraT57knNvg0a2MddgXgvN
a=ssrc:2743245198 label:0krgp7abN5HraT57knNvg0a2MddgXgvNa0
a=ice-ufrag:OruVhuOUyS1mYm0f
a=ice-pwd:ZJMAulO9rjQgLufqLAbTVQEk
a=candidate:7114135741 1 udp 659136 192.3.107.105 22718 typ host generation 0
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:912 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/6j7yppofyqua...@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.444030 [NOTICE] mod_dptools.c:1300 Channel [sofia/external/6j7yppofyqua...@conferencias.falcorp.net] has been answered
2016-07-02 01:14:38.444030 [DEBUG] switch_channel.c:3711 (sofia/external/6j7yppofyqua...@conferencias.falcorp.net) Callstate Change EARLY -> ACTIVE
2016-07-02 01:14:38.444030 [DEBUG] sofia.c:6634 Channel sofia/external/6j7yppofyqua...@conferencias.falcorp.net entering state [completed][200]
EXECUTE sofia/external/6j7yppofyqua...@conferencias.falcorp.net conference(71233@cdquality)
2016-07-02 01:14:38.444030 [DEBUG] mod_conference.c:8991 Raw Codec Activation Success L16@48000hz 1 channel 20ms
2016-07-02 01:14:38.444030 [DEBUG] mod_conference.c:9037 Raw Codec Activation Success L16@48000hz 1 channel 20ms
2016-07-02 01:14:38.444030 [DEBUG] switch_core_codec.c:221 sofia/external/6j7yppofyqua...@conferencias.falcorp.net Push codec L16:100
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '0' to 'mute'
2016-07-02 01:14:38.444030 [INFO] switch_ivr_async.c:212 Digit parser mod_conference: Setting realm to 'conf'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101c10
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '*' to 'deaf mute'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding */conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101c40
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '9' to 'energy up'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101c70
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '8' to 'energy equ'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101ca0
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '7' to 'energy dn'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101cd0
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '3' to 'vol talk up'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101d00
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '2' to 'vol talk zero'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101d30
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '1' to 'vol talk dn'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101d60
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '6' to 'vol listen up'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101d90
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '5' to 'vol listen zero'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101dc0
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '4' to 'vol listen dn'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101df0
2016-07-02 01:14:38.444030 [INFO] mod_conference.c:10967 sofia/external/6j7yppofyqua...@conferencias.falcorp.net binding '#' to 'hangup'
2016-07-02 01:14:38.444030 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding #/conf/0 callback: 0x7f6e55fde590 data: 0x7f6e4c101e20
2016-07-02 01:14:38.444030 [DEBUG] switch_core_session.c:912 Send signal sofia/external/6j7yppofyqua-bbbID-German@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.464067 [DEBUG] mod_conference.c:4765 Setup timer soft success interval: 20 samples: 960
2016-07-02 01:14:38.804183 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/6j7yppofyqua-bbbID-German@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.804183 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/6j7yppofyqua-bbbID-German@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.804183 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/6j7yppofyqua-bbbID-German@conferencias.falcorp.net [BREAK]
2016-07-02 01:14:38.804183 [DEBUG] sofia.c:6634 Channel sofia/external/6j7yppofyqua-bbbID-German@conferencias.falcorp.net entering state [ready][200]
2016-07-02 01:14:39.964032 [INFO] switch_rtp.c:2924 Changing audio DTLS state from HANDSHAKE to SETUP
2016-07-02 01:14:39.964032 [INFO] switch_rtp.c:2832 audio Fingerprint Verified.
2016-07-02 01:14:39.964032 [INFO] switch_rtp.c:3394 Activating Audio Secure RTP SEND
2016-07-02 01:14:39.964032 [INFO] switch_rtp.c:3372 Activating Audio Secure RTP RECV
2016-07-02 01:14:39.964032 [INFO] switch_rtp.c:2872 Changing audio DTLS state from SETUP to READY
2016-07-02 01:14:39.964032 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
2016-07-02 01:14:39.964032 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
2016-07-02 01:14:40.004032 [DEBUG] switch_rtp.c:1937 rtcp_stats_init: ssrc[2034415593] base_seq[32397]
freeswitch@internal>
Diego Nieto
Jul 2, 2016, 4:53:26 AM7/2/16
to bigbluebu...@googlegroups.com
Hi did you change the protocol from ws to wss in the file webrtc bridge config file /var/www/bigbluebutton/client/lib/bbb_webrtc_bridge_sip.js
It should look like this:
wsServers: 'wss://' + server + '/ws',
Good luck!
--
You received this message because you are subscribed to a topic in the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this topic, visit https://groups.google.com/d/topic/bigbluebutton-setup/F2MlW6Voj-0/unsubscribe.
To unsubscribe from this group and all its topics, send an email to bigbluebutton-s...@googlegroups.com.
German Acevedo
Jul 3, 2016, 12:48:07 PM7/3/16
to BigBlueButton-Setup
Hi Diego,
I have reviewed and my /var/www/bigbluebutton/client/lib/bbb_webrtc_bridge_sip.js file was already showing as you said, but still failing to connect by WebRTC from Chrome or Firefox on desktop, but working fine from html5 on Android.
Any other suggestion?
Regards,
German
German Acevedo
Jul 4, 2016, 1:53:53 PM7/4/16
to BigBlueButton-Setup
Finally got it working!
I have seen that there was an update for Freeswitch for BBB, so I did a sudo apt-get update and then sudo bbb-conf --clean in my server, and everything started to work as expected.
Thanks for helping me!
Regards,
German
I have seen that there was an update for Freeswitch for BBB, so I did a sudo apt-get update and then sudo bbb-conf --clean in my server, and everything started to work as expected.
Thanks for helping me!
Regards,
German
Fred Dixon
Jul 4, 2016, 2:12:28 PM7/4/16
to bigbluebu...@googlegroups.com
Hi German,
Great to hear! Here's more information on the recent update to FreeSWITCH
Regards,... Fred
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-s...@googlegroups.com.
To post to this group, send email to bigbluebu...@googlegroups.com.
Visit this group at https://groups.google.com/group/bigbluebutton-setup.
For more options, visit https://groups.google.com/d/optout.
Vishnu N
Aug 10, 2016, 3:19:10 AM8/10/16
to BigBlueButton-Setup
Hi,
I was setting up BBB v1 on a new server and faced this issue. Couldn't find a solution in the forum or documentation and took one whole day to figure out what the solution is. Posting it here in case some one else is facing the same issue.
Problem:
When I connect using FF or Chrome, I would get "Error 1004: Failure on call (reason=SIP Failure Code)" in BBB and on the same system if i use FF Dev edition, I get no WebRTC error.
For this error i get these entries in the freeswitch log:
...
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx port 57555 codec: 109 ms: 20
...
switch_core_media.c:5843 AUDIO RTP REPORTS ERROR: [Remote Address Error!]
...
When I connect using FF Dev edition, the first line above would change to:
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xx.xx.xx.xx port 57555 codec: 109 ms: 20
So connection breaks when communication is between ipv4 and ipv6. If both are ipv4, there would not be any problem. It turns out that my server does not support ipv6 and in such cases ipv6 should be disabled in SIP profile.
Solution:
Add to sip-profiles:
Vishnu N
I was setting up BBB v1 on a new server and faced this issue. Couldn't find a solution in the forum or documentation and took one whole day to figure out what the solution is. Posting it here in case some one else is facing the same issue.
Problem:
When I connect using FF or Chrome, I would get "Error 1004: Failure on call (reason=SIP Failure Code)" in BBB and on the same system if i use FF Dev edition, I get no WebRTC error.
For this error i get these entries in the freeswitch log:
...
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx port 57555 codec: 109 ms: 20
...
switch_core_media.c:5843 AUDIO RTP REPORTS ERROR: [Remote Address Error!]
...
When I connect using FF Dev edition, the first line above would change to:
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xx.xx.xx.xx port 57555 codec: 109 ms: 20
So connection breaks when communication is between ipv4 and ipv6. If both are ipv4, there would not be any problem. It turns out that my server does not support ipv6 and in such cases ipv6 should be disabled in SIP profile.
Solution:
Add to sip-profiles:
<param name="apply-candidate-acl" value="ipv4"/>
Add to acl.conf.xml: <list name="ipv4" default="deny"> <node type="allow" cidr="0.0.0.0/0"/> </list>
Vishnu N
Fred Dixon
Aug 10, 2016, 6:52:08 AM8/10/16
to bigbluebu...@googlegroups.com
Hi Vishnu,
> Posting it here in case some one else is facing the same issue.
Thanks for sharing this! Your post will definitely help others who are facing the same issue.
Regards,... Fred
On Wed, Aug 10, 2016 at 3:19 AM, Vishnu N <vis...@softwebers.com> wrote:
Hi,
I was setting up BBB v1 on a new server and faced this issue. Couldn't find a solution in the forum or documentation and took one whole day to figure out what the solution is. Posting it here in case some one else is facing the same issue.
Problem:
When I connect using FF or Chrome, I would get "Error 1004: Failure on call (reason=SIP Failure Code)" in BBB and on the same system if i use FF Dev edition, I get no WebRTC error.
For this error i get these entries in the freeswitch log:
...
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm_2-bbbI...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx port 57555 codec: 109 ms: 20
...
switch_core_media.c:5843 AUDIO RTP REPORTS ERROR: [Remote Address Error!]
...
When I connect using FF Dev edition, the first line above would change to:
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm_2-bbbI...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xx.xx.xx.xx port 57555 codec: 109 ms: 20
So connection breaks when communication is between ipv4 and ipv6. If both are ipv4, there would not be any problem. It turns out that my server does not support ipv6 and in such cases ipv6 should be disabled in SIP profile.
Solution:
Add to sip-profiles:<param name="apply-candidate-acl" value="ipv4"/>Add to acl.conf.xml:<list name="ipv4" default="deny"> <node type="allow" cidr="0.0.0.0/0"/> </list>
Vishnu N
--
You received this message because you are subscribed to the Google Groups "BigBlueButton-Setup" group.
To unsubscribe from this group and stop receiving emails from it, send an email to bigbluebutton-setup+unsub...@googlegroups.com.
To post to this group, send email to bigbluebutton-setup@googlegroups.com.
Visit this group at https://groups.google.com/group/bigbluebutton-setup.
German Acevedo
Aug 10, 2016, 11:24:22 AM8/10/16
to BigBlueButton-Setup
Hi Vishnu,
Can you explain where exactly do I have to make the modifications on sip-profiles? in external.xml maybe?
Regards,
German
Can you explain where exactly do I have to make the modifications on sip-profiles? in external.xml maybe?
Regards,
German
El miércoles, 10 de agosto de 2016, 1:19:10 (UTC-6), Vishnu N escribió:
Hi,
I was setting up BBB v1 on a new server and faced this issue. Couldn't find a solution in the forum or documentation and took one whole day to figure out what the solution is. Posting it here in case some one else is facing the same issue.
Problem:
When I connect using FF or Chrome, I would get "Error 1004: Failure on call (reason=SIP Failure Code)" in BBB and on the same system if i use FF Dev edition, I get no WebRTC error.
For this error i get these entries in the freeswitch log:
...
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm_2-bbbI...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx port 57555 codec: 109 ms: 20
...
switch_core_media.c:5843 AUDIO RTP REPORTS ERROR: [Remote Address Error!]
...
When I connect using FF Dev edition, the first line above would change to:
switch_core_media.c:5178 AUDIO RTP [sofia/external/vc32mutrncdm_2-bbbI...@xxx.xxxxx.xxx] xx.xx.xx.xx port 32350 -> xx.xx.xx.xx port 57555 codec: 109 ms: 20
Vishnu N
Aug 11, 2016, 3:03:36 AM8/11/16
to BigBlueButton-Setup
Hi,
I added it to both internal & external.xml. May be, just adding it to external.xml works.
Vishnu N
I added it to both internal & external.xml. May be, just adding it to external.xml works.
Vishnu N
German Acevedo
Aug 11, 2016, 9:30:44 PM8/11/16
to BigBlueButton-Setup
Thanks Vishnu,
I have added to both, external and internal and now it's working the WebRTC audio withour errors.
Again, thanks a lot!
Regards,
German
I have added to both, external and internal and now it's working the WebRTC audio withour errors.
Again, thanks a lot!
Regards,
German
Reply all
Reply to author
Forward
0 new messages