No audio with SIP calls after enabling lots of ports
by lxs602 from LinuxQuestions.org on (#524E4)
I have no audio on internet phone calls on Ubuntu 19.10.
All outgoing and incoming calls, on both Linphone and Zoiper, are affected.
I have enabled ports 5060, 7078, 9078, 11078 (in both directions, for good measure, and for tcp/udp.)
After looking at the firewall log, with gufw, linphone was also asking for 7079, 9079, 11079, (?and 8610 and 8612) and a random port at around ~36000, so I enabled them and ports 32000-65535, but with no change.
It seems to be a firewall issue: when I allow all outgoing traffic on the firewall on my laptop, the problem disappears.
Why is audio blocked when I have enabled RTP ports 32000-65535?*
The links below were helpful:
Quote:
I note the below from the logs:
Quote:
I have the linphone and ufw logs, and my .linphonerc configuration file below.
Thanks.
*Before anyone mentions it, clearly not a long-term solution for security reasons.
Ufw.log:
Quote:
Ufw rules (rule names added)
Quote:
.linphonerc:
Quote:
linphone log: (sample, truncated):
Quote:


All outgoing and incoming calls, on both Linphone and Zoiper, are affected.
I have enabled ports 5060, 7078, 9078, 11078 (in both directions, for good measure, and for tcp/udp.)
After looking at the firewall log, with gufw, linphone was also asking for 7079, 9079, 11079, (?and 8610 and 8612) and a random port at around ~36000, so I enabled them and ports 32000-65535, but with no change.
It seems to be a firewall issue: when I allow all outgoing traffic on the firewall on my laptop, the problem disappears.
Why is audio blocked when I have enabled RTP ports 32000-65535?*
The links below were helpful:
Quote:
http://info.teledynamics.com/blog/ho...-on-voip-calls https://www.zoiper.com/en/support/ho...%20way%20audio https://www.asteriskguru.com/tutoria..._asterisk.html |
Quote:
[error ] 2020-04-13 21:56:26:151 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x7f19800040b0] to 217.10.77.153:19290: Operation not permitted [1 |
Thanks.
*Before anyone mentions it, clearly not a long-term solution for security reasons.
Ufw.log:
Quote:
Apr 13 21:55:37 l-pc kernel: [ 12.273993] [UFW BLOCK] IN= OUT=wlan0 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x94 Apr 13 21:55:38 l-pc kernel: [ 13.177996] [UFW BLOCK] IN= OUT=wlan0 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x94 Apr 13 21:55:45 l-pc kernel: [ 19.565710] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=255.255.255.255 LEN=65 TOS=0x00 PREC=0x00 TTL=64 ID=3818 DF PROTO=UDP SPT=39949 DPT=1124 LEN=45 Apr 13 21:55:46 l-pc kernel: [ 20.725779] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=255.255.255.255 LEN=101 TOS=0x00 PREC=0x00 TTL=64 ID=4035 DF PROTO=UDP SPT=56038 DPT=161 LEN=81 Apr 13 21:55:46 l-pc kernel: [ 20.815567] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=255.255.255.255 LEN=43 TOS=0x00 PREC=0x00 TTL=64 ID=4055 DF PROTO=UDP SPT=51381 DPT=3289 LEN=23 Apr 13 21:55:47 l-pc kernel: [ 21.825884] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=255.255.255.255 LEN=43 TOS=0x00 PREC=0x00 TTL=64 ID=4294 DF PROTO=UDP SPT=53311 DPT=3289 LEN=23 Apr 13 21:55:48 l-pc kernel: [ 22.831821] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=255.255.255.255 LEN=65 TOS=0x00 PREC=0x00 TTL=64 ID=4396 DF PROTO=UDP SPT=42394 DPT=1124 LEN=45 Apr 13 21:56:14 l-pc kernel: [ 48.843227] [UFW BLOCK] IN=wlan0 OUT= MAC=01:00:5e:00:00:01:48:d3:43:9e:b2:30:08:00 SRC=192.168.0.1 DST=224.0.0.1 LEN=36 TOS=0x00 PREC=0xC0 TTL=1 ID=32399 PROTO=2 Apr 13 21:56:26 l-pc kernel: [ 60.455571] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=217.10.77.153 LEN=48 TOS=0x18 PREC=0xA0 TTL=64 ID=1760 DF PROTO=UDP SPT=7078 DPT=19290 LEN=28 Apr 13 21:56:26 l-pc kernel: [ 60.455669] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=217.10.77.153 LEN=48 TOS=0x18 PREC=0xA0 TTL=64 ID=1761 DF PROTO=UDP SPT=7079 DPT=19291 LEN=28 Apr 13 21:56:26 l-pc kernel: [ 60.506430] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=217.10.77.153 LEN=200 TOS=0x18 PREC=0xA0 TTL=64 ID=1767 DF PROTO=UDP SPT=7078 DPT=19290 LEN=180 Apr 13 21:56:26 l-pc kernel: [ 60.515962] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=217.10.77.153 LEN=48 TOS=0x18 PREC=0xA0 TTL=64 ID=1769 DF PROTO=UDP SPT=7078 DPT=19290 LEN=28 Apr 13 21:56:26 l-pc kernel: [ 60.516084] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=217.10.77.153 LEN=48 TOS=0x18 PREC=0xA0 TTL=64 ID=1770 DF PROTO=UDP SPT=7079 DPT=19291 LEN=28 Apr 13 21:56:26 l-pc kernel: [ 60.525557] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=217.10.77.153 LEN=200 TOS=0x18 PREC=0xA0 TTL=64 ID=1772 DF PROTO=UDP SPT=7078 DPT=19290 LEN=180 Apr 13 21:56:26 l-pc kernel: [ 60.545363] [UFW BLOCK] IN= OUT=wlan0 SRC=192.168.0.10 DST=217.10.77.153 LEN=200 TOS=0x18 PREC=0xA0 TTL=64 ID=1775 DF PROTO=UDP SPT=7078 DPT=19290 LEN=180 |
Quote:
Status: active To Action From -- ------ ---- 51413 ALLOW Anywhere - transmission-gtk 54830/udp ALLOW Anywhere - transmission-gtk 192.168.0.10 68/udp ALLOW Anywhere - Network manager 127.0.0.1 9982/tcp ALLOW 9981/tcp - tvheadend 127.0.0.1 43841/udp ALLOW 127.0.0.1 43841/udp - tvheadend 192.168.0.10 42556/udp ALLOW Anywhere 25/tcp ALLOW Anywhere 7078 ALLOW Anywhere - linphone 9078 ALLOW Anywhere - linphone 11078 ALLOW Anywhere - linphone 5060 ALLOW Anywhere - SIP 32000:65535/tcp ALLOW Anywhere - linphone_extra 8612 ALLOW Anywhere - ??? 7079 ALLOW Anywhere - linphone_extra 9079 ALLOW Anywhere - linphone_extra 11079 ALLOW Anywhere - linphone_extra 8610 ALLOW Anywhere - ??? 51413 (v6) ALLOW Anywhere (v6) - transmission-gtk 54830/udp (v6) ALLOW Anywhere (v6) - transmission-gtk 25/tcp (v6) ALLOW Anywhere (v6) - SMTP 7078 (v6) ALLOW Anywhere (v6) - linphone 9078 (v6) ALLOW Anywhere (v6) - linphone 11078 (v6) ALLOW Anywhere (v6) - linphone 5060 (v6) ALLOW Anywhere (v6) - SIP 32000:65535/tcp (v6) ALLOW Anywhere (v6) - linphone_extra 8612 (v6) ALLOW Anywhere (v6) - ??? 7079 (v6) ALLOW Anywhere (v6) - linphone_extra 9079 (v6) ALLOW Anywhere (v6) - linphone_extra 11079 (v6) ALLOW Anywhere (v6) - linphone_extra 8610 (v6) ALLOW Anywhere (v6) - ??? 80/tcp ALLOW OUT Anywhere - HTTP 443/tcp ALLOW OUT Anywhere - HTTPS 27000:27015/udp ALLOW OUT Anywhere 27015:27030/udp ALLOW OUT Anywhere 27014:27050/tcp ALLOW OUT Anywhere 4380/udp ALLOW OUT Anywhere 27031/udp ALLOW OUT Anywhere 27036 ALLOW OUT Anywhere 27037/tcp ALLOW OUT Anywhere 6881:6889/udp ALLOW OUT Anywhere - Bittorrent 6881:6889/tcp ALLOW OUT Anywhere - Bittorrent 143/tcp ALLOW OUT Anywhere - IMAP 993/tcp ALLOW OUT Anywhere - IMAPS 21/tcp ALLOW OUT Anywhere - FTP 67/udp ALLOW OUT Anywhere - DHCP 53 ALLOW OUT Anywhere - DNS 25/tcp ALLOW OUT Anywhere 465 ALLOW OUT Anywhere - SMTP 587 ALLOW OUT Anywhere - SMTP 192.168.0.0/24 22 ALLOW OUT 192.168.0.0/24 (log) - SSH 5061/tcp ALLOW OUT Anywhere - SIP TLS 3478 ALLOW OUT Anywhere - STUN 5349 ALLOW OUT Anywhere - STUN TLS 7078 ALLOW OUT Anywhere - Linphone 9078 ALLOW OUT Anywhere - Linphone 11078 ALLOW OUT Anywhere - Linphone 32000:65535/tcp ALLOW OUT Anywhere - Linphone_extra 5060 ALLOW OUT Anywhere - Linphone 8612 ALLOW OUT Anywhere - Linphone 7079 ALLOW OUT Anywhere - Linphone_extra 9079 ALLOW OUT Anywhere - Linphone_extra 11079 ALLOW OUT Anywhere - Linphone_extra 8610 ALLOW OUT Anywhere - ??? 80/tcp (v6) ALLOW OUT Anywhere (v6) 443/tcp (v6) ALLOW OUT Anywhere (v6) 27000:27015/udp (v6) ALLOW OUT Anywhere (v6) 27015:27030/udp (v6) ALLOW OUT Anywhere (v6) 27014:27050/tcp (v6) ALLOW OUT Anywhere (v6) 4380/udp (v6) ALLOW OUT Anywhere (v6) 27031/udp (v6) ALLOW OUT Anywhere (v6) 27036 (v6) ALLOW OUT Anywhere (v6) 27037/tcp (v6) ALLOW OUT Anywhere (v6) 6881:6889/udp (v6) ALLOW OUT Anywhere (v6) 6881:6889/tcp (v6) ALLOW OUT Anywhere (v6) 23390 (v6) ALLOW OUT Anywhere (v6) 143/tcp (v6) ALLOW OUT Anywhere (v6) 993/tcp (v6) ALLOW OUT Anywhere (v6) 21/tcp (v6) ALLOW OUT Anywhere (v6) 67/udp (v6) ALLOW OUT Anywhere (v6) 53 (v6) ALLOW OUT Anywhere (v6) 25/tcp (v6) ALLOW OUT Anywhere (v6) 465 (v6) ALLOW OUT Anywhere (v6) 587 (v6) ALLOW OUT Anywhere (v6) 5061/tcp (v6) ALLOW OUT Anywhere (v6) 3478 (v6) ALLOW OUT Anywhere (v6) 5349 (v6) ALLOW OUT Anywhere (v6) 7078 (v6) ALLOW OUT Anywhere (v6) 9078 (v6) ALLOW OUT Anywhere (v6) 11078 (v6) ALLOW OUT Anywhere (v6) 32000:65535/tcp (v6) ALLOW OUT Anywhere (v6) 5060 (v6) ALLOW OUT Anywhere (v6) 8612 (v6) ALLOW OUT Anywhere (v6) 7079 (v6) ALLOW OUT Anywhere (v6) 9079 (v6) ALLOW OUT Anywhere (v6) 11079 (v6) ALLOW OUT Anywhere (v6) 8610 (v6) ALLOW OUT Anywhere (v6) |
Quote:
[sip] root_ca=/etc/ssl/certs verify_server_certs=1 verify_server_cn=1 contact=redacted media_encryption=none default_proxy=0 multi_transport_migration_done=1 zrtp_cache_migration_done=1 lime=0 guess_hostname=1 inc_timeout=30 in_call_timeout=0 delayed_timeout=4 register_only_when_network_is_up=1 register_only_when_upnp_is_ok=1 ipv6_migration_done=1 use_info=0 use_ipv6=1 [net] nat_policy_ref=redacted download_bw=0 upload_bw=0 adaptive_rate_control=1 mtu=1300 [nat_policy_0] ref=redacted protocols=stun stun_server=stun.redacted.net [misc] uuid=redacted user_certificates_path=/home/l/.linphone-usr-crt friends_migration_done=1 [video] capture=1 display=1 automatically_initiate=0 automatically_accept=0 self_view=0 show_local=0 displaytype=MSX11Video size=vga [GtkUi] videoselfview=0 logs_scroll_to_end=0 advanced_ui=1 uri0= uri1= uri2= lang=C uri3= uri4=sip: uri5=sip: [sound] playback_dev_id=PulseAudio: Built-in Audio Analogue Stereo ringer_dev_id=PulseAudio: Built-in Audio Analogue Stereo capture_dev_id=PulseAudio: Built-in Audio Analogue Stereo echocancellation=1 remote_ring=/usr/share/sounds/linphone/ringback.wav playback_gain_db=0.000000 mic_gain_db=0.000000 ec_delay=36 [rtp] audio_rtp_port=7078 video_rtp_port=9078 text_rtp_port=11078 audio_jitt_comp=60 video_jitt_comp=60 nortp_timeout=30 audio_adaptive_jitt_comp_enabled=1 video_adaptive_jitt_comp_enabled=1 [audio_codec_0] mime=opus rate=48000 channels=2 enabled=1 [audio_codec_1] mime=speex rate=16000 channels=1 enabled=1 [audio_codec_2] mime=speex rate=8000 channels=1 enabled=1 [audio_codec_3] mime=PCMU rate=8000 channels=1 enabled=1 [audio_codec_4] mime=PCMA rate=8000 channels=1 enabled=1 [audio_codec_5] mime=GSM rate=8000 channels=1 enabled=0 [audio_codec_6] mime=G722 rate=8000 channels=1 enabled=0 [audio_codec_7] mime=speex rate=32000 channels=1 enabled=0 [audio_codec_8] mime=L16 rate=44100 channels=2 enabled=0 [audio_codec_9] mime=L16 rate=44100 channels=1 enabled=0 [video_codec_0] mime=VP8 rate=90000 enabled=1 [auth_info_0] passwd=redacted [auth_info_1] username=redacted userid=redacted ha1=redacted realm=sip.redacted.org domain=sip.redacted.org [auth_info_2] passwd=redacted [auth_info_3] username=redacted userid=redacted ha1=redacted realm=redacted.co.uk domain=redacted.co.uk [proxy_0] reg_proxy=sip:redacted.co.uk reg_identity=sip:redacted@redacted.co.uk quality_reporting_enabled=0 quality_reporting_interval=0 reg_expires=3600 reg_sendregister=1 publish=1 avpf=0 avpf_rr_interval=5 dial_escape_plus=0 privacy=32768 publish_expires=-1 [proxy_1] reg_proxy=sip:redacted.org reg_identity=sip:redacted@redacted.org quality_reporting_enabled=0 quality_reporting_interval=0 reg_expires=3600 reg_sendregister=1 publish=1 avpf=0 avpf_rr_interval=5 dial_escape_plus=0 privacy=32768 publish_expires=-1 |
Quote:
-------- [message] 2020-04-13 21:56:20:529 Using (r/w) config information from /home/luke/.linphonerc [message] 2020-04-13 21:56:20:530 Initializing LinphoneCore 3.12.0 [message] 2020-04-13 21:56:20:530 Core callbacks [0x55ff9802d4f0] registered on core [0x55ff980d2980] [message] 2020-04-13 21:56:20:530 Core callbacks [0x55ff9810f570] registered on core [0x55ff980d2980] [message] 2020-04-13 21:56:20:530 Linphone core [0x55ff980d2980] notified [global_state_changed] [message] 2020-04-13 21:56:20:530 oRTP-1.0.2 initialized. [message] 2020-04-13 21:56:20:530 Mediastreamer2 factory 2.16.1 (git: unknown) initialized. [message] 2020-04-13 21:56:20:530 CPU count set to 4 [message] 2020-04-13 21:56:20:530 ms_factory_init() done: platform_tags=linux,x86,desktop [message] 2020-04-13 21:56:20:530 srtp init [message] 2020-04-13 21:56:20:534 Registering all soundcard handlers [message] 2020-04-13 21:56:20:534 New PulseAudio context state: PA_CONTEXT_CONNECTING [message] 2020-04-13 21:56:20:535 New PulseAudio context state: PA_CONTEXT_AUTHORIZING [message] 2020-04-13 21:56:20:535 New PulseAudio context state: PA_CONTEXT_SETTING_NAME [message] 2020-04-13 21:56:20:535 New PulseAudio context state: PA_CONTEXT_READY [message] 2020-04-13 21:56:20:536 Card 'PulseAudio: Built-in Audio Analogue Stereo' added with capabilities [capture, playback] [message] 2020-04-13 21:56:20:556 Card 'ALSA: default device' added with capabilities [capture, playback] [message] 2020-04-13 21:56:20:556 Registering all webcam handlers [message] 2020-04-13 21:56:20:556 Webcam StaticImage: Static picture added [message] 2020-04-13 21:56:20:556 ms_factory_init_voip() done [message] 2020-04-13 21:56:20:556 Loading ms plugins from [/usr/lib/x86_64-linux-gnu/mediastreamer/plugins] [message] 2020-04-13 21:56:20:556 Cannot open directory /usr/lib/x86_64-linux-gnu/mediastreamer/plugins: No such file or directory [message] 2020-04-13 21:56:20:556 Codec opus/48000 fmtp=[useinbandfec=1] number=-1, default enablement: 1) added to the list of possible codecs. [message] 2020-04-13 21:56:20:556 Codec SILK/16000 fmtp=[] number=-1, default enablement: 1) added to the list of possible codecs. ... [message] 2020-04-13 21:56:20:598 sal_unlisten_ports done [message] 2020-04-13 21:56:20:598 Creating listening point [0x55ff980a4a70] on [sip:[::0]:5060;transport=UDP] [message] 2020-04-13 21:56:20:599 Creating listening point [0x55ff9830fa70] on [sip:[::0]:5060;transport=TCP] [message] 2020-04-13 21:56:20:599 Creating listening point [0x55ff98333990] on [sip:[::0]:-1;transport=TLS] [message] 2020-04-13 21:56:20:599 Random TCP port is 36407 [message] 2020-04-13 21:56:20:599 Root ca path set to /etc/ssl/certs ... [message] 2020-04-13 21:56:23:701 channel [0x55ff98469ff0]: received [292] new bytes from [UDP://redacted.co.uk:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 192.168.0.10:5060;received=82.40.229.249;branch=z9hG4bK.a6C4QGwMf;rport=5060 From: ;tag=w2CXj~2cO To: sip:08000363738@redacted.co.uk CSeq: 21 INVITE Call-ID: Brlf8fdLi7 Content-Length: 0 ... [message] 2020-04-13 21:56:23:702 channel [0x55ff98469ff0] [292] bytes parsed [message] 2020-04-13 21:56:23:702 Found transaction matching response. [message] 2020-04-13 21:56:23:702 Changing [client] [INVITE] transaction [0x55ff985f6d80], from state [CALLING] to [PROCEEDING] [message] 2020-04-13 21:56:23:702 op [0x55ff97eca160] : set_or_update_dialog() current=[(nil)] new=[(nil)] [message] 2020-04-13 21:56:23:702 Op [0x55ff97eca160] receiving call response [100], dialog is [(nil)] in state [BELLE_SIP_DIALOG_NULL] [message] 2020-04-13 21:56:26:015 channel [0x55ff98469ff0]: received [858] new bytes from [UDP://redacted.co.uk:5060]: SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 192.168.0.10:5060;received=82.40.229.249;branch=z9hG4bK.a6C4QGwMf;rport=5060 Record-Route: Record-Route: From: ;tag=w2CXj~2cO To: sip:08000363738@redacted.co.uk;tag=as486a9951 Call-ID: Brlf8fdLi7 CSeq: 21 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces Contact: Content-Type: application/sdp Content-Length: 295 v=0 o=root 63116917 63116917 IN IP4 217.116.117.69 s=redacted VoIP GW c=IN IP4 217.10.77.153 t=0 0 m=audio 19290 RTP/AVP 8 0 101 a=silenceSupp:off - - - - a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrecv a=rtcp:19291 a=ptime:20 ... [message] 2020-04-13 21:56:26:018 channel [0x55ff98469ff0] [563] bytes parsed [message] 2020-04-13 21:56:26:018 channel [0x55ff98469ff0] read [295] bytes of body from [redacted.co.uk:5060] [message] 2020-04-13 21:56:26:018 Found transaction matching response. [message] 2020-04-13 21:56:26:018 New client dialog [0x55ff98517830] , local tag [w2CXj~2cO], remote tag [as486a9951] [message] 2020-04-13 21:56:26:018 Dialog [0x55ff98517830]: now updated by transaction [0x55ff985f6d80]. [message] 2020-04-13 21:56:26:018 op [0x55ff97eca160] : set_or_update_dialog() current=[(nil)] new=[0x55ff98517830] [message] 2020-04-13 21:56:26:018 Op [0x55ff97eca160] receiving call response [183], dialog is [0x55ff98517830] in state [BELLE_SIP_DIALOG_EARLY] [message] 2020-04-13 21:56:26:019 Found payload PCMA/8000 fmtp= [message] 2020-04-13 21:56:26:019 Found payload PCMU/8000 fmtp= [message] 2020-04-13 21:56:26:019 Found payload telephone-event/8000 fmtp=0-16 [message] 2020-04-13 21:56:26:019 Doing SDP offer/answer process of type outgoing [message] 2020-04-13 21:56:26:019 Processing for stream 0 [warning] 2020-04-13 21:56:26:019 For payload type telephone-event, proposed number was 100 but the remote phone answered 101 [message] 2020-04-13 21:56:26:019 Adding opus/48000 for compatibility, just in case. [message] 2020-04-13 21:56:26:019 Adding speex/16000 for compatibility, just in case. [message] 2020-04-13 21:56:26:019 Adding speex/8000 for compatibility, just in case. [message] 2020-04-13 21:56:26:019 Adding telephone-event/16000 for compatibility, just in case. [message] 2020-04-13 21:56:26:019 Call 0x55ff9849ed10: moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingEarlyMedia [message] 2020-04-13 21:56:26:019 Call 0x55ff9849ed10 is locking sound resources. [message] 2020-04-13 21:56:26:020 Linphone core [0x55ff980d2980] notified [call_state_changed] [message] 2020-04-13 21:56:26:020 Doing early media... [message] 2020-04-13 21:56:26:020 linphone_call_start_media_streams() call=[0x55ff9849ed10] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s [message] 2020-04-13 21:56:26:020 Audio bandwidth for this call is 80 [warning] 2020-04-13 21:56:26:020 LinphoneCall[0x55ff9849ed10] - advanced adaptive rate control requested but avpf is not activated in this stream. Reverting to basic rate control instead. [message] 2020-04-13 21:56:26:020 RtpSession [0x55ff9854df40] sending to rtp 217.10.77.153:19290 rtcp 217.10.77.153:19291 [message] 2020-04-13 21:56:26:020 Stun packet sent for session [0x55ff9854df40] [error ] 2020-04-13 21:56:26:020 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x55ff9850a0c0] to 217.10.77.153:19290: Operation not permitted [1] [message] 2020-04-13 21:56:26:021 Stun packet sent on rtcp for session [0x55ff9854df40] [error ] 2020-04-13 21:56:26:021 RtpSession [0x55ff9854df40] error sending [rtcp] packet [0x55ff9850a0c0] to 217.10.77.153:19291: Operation not permitted [1] [message] 2020-04-13 21:56:26:021 ms_filter_unlink: MSRtpRecv:0x55ff9855e4c0,0-->MSVoidSink:0x55ff982e0cf0,0 [message] 2020-04-13 21:56:26:021 Setting echo canceller delay with value configured by application. [error ] 2020-04-13 21:56:26:021 No such filter with id 117 [message] 2020-04-13 21:56:26:021 target bitrate not set for stream [0x55ff98316810] using payload's bitrate is 80000 [message] 2020-04-13 21:56:26:021 Setting audio encoder network bitrate to [80000] on stream [0x55ff98316810] [message] 2020-04-13 21:56:26:021 MSAlawEnc: got ptime=20 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSPulseRead:0x55ff9855e4c0,0-->MSEqualizer:0x55ff98113420,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSEqualizer:0x55ff98113420,0-->MSSpeexEC:0x55ff98304480,1 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSSpeexEC:0x55ff98304480,1-->MSVolume:0x55ff985d4270,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSVolume:0x55ff985d4270,0-->MSAudioMixer:0x55ff985c5210,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSAudioMixer:0x55ff985c5210,0-->MSAlawEnc:0x55ff9852ec30,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSAlawEnc:0x55ff9852ec30,0-->MSRtpSend:0x55ff982d8960,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSRtpRecv:0x55ff98113640,0-->MSAlawDec:0x55ff985665f0,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSAlawDec:0x55ff985665f0,0-->MSGenericPLC:0x55ff983002a0,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSGenericPLC:0x55ff983002a0,0-->MSAudioFlowControl:0x55ff985d05e0,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSAudioFlowControl:0x55ff985d05e0,0-->MSDtmfGen:0x55ff9852bd70,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSDtmfGen:0x55ff9852bd70,0-->MSVolume:0x55ff9852a0d0,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSVolume:0x55ff9852a0d0,0-->MSTee:0x55ff9852e660,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSTee:0x55ff9852e660,0-->MSEqualizer:0x55ff981134f0,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSEqualizer:0x55ff981134f0,0-->MSAudioMixer:0x55ff985f9180,0 [message] 2020-04-13 21:56:26:021 speex_lib_ctl does not support SPEEX_LIB_CPU_FEATURE_NEON [message] 2020-04-13 21:56:26:021 ms_filter_link: MSFilePlayer:0x55ff98519180,0-->MSResample:0x55ff98519270,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSResample:0x55ff98519270,0-->MSAudioMixer:0x55ff985f9180,1 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSAudioMixer:0x55ff985f9180,0-->MSSpeexEC:0x55ff98304480,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSSpeexEC:0x55ff98304480,0-->MSPulseWrite:0x55ff982e0cf0,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSAudioMixer:0x55ff985c5210,1-->MSAudioMixer:0x55ff9852e5d0,0 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSTee:0x55ff9852e660,1-->MSAudioMixer:0x55ff9852e5d0,1 [message] 2020-04-13 21:56:26:021 ms_filter_link: MSAudioMixer:0x55ff9852e5d0,0-->MSFileRec:0x55ff98521600,0 [message] 2020-04-13 21:56:26:040 pulseaudio record stream connected (8000Hz, 1ch) [message] 2020-04-13 21:56:26:040 ms_ticker_set_time_func: ticker's time method updated. [message] 2020-04-13 21:56:26:040 Initializing speex echo canceler with framesize=64, filterlength=2000, delay_samples=288 [message] 2020-04-13 21:56:26:041 Initializing speex resampler in mode [voip] [message] 2020-04-13 21:56:26:064 pulseaudio playback stream connected (8000Hz, 1ch) [message] 2020-04-13 21:56:26:064 Filter MSRtpRecv is already being scheduled; nothing to do. [error ] 2020-04-13 21:56:26:064 no such method on filter MSPulseWrite, fid=16394 method index=2 [message] 2020-04-13 21:56:26:064 MSVolume set gain to [0.000000] linear [message] 2020-04-13 21:56:26:065 No valid video stream defined. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 8 PCMA/8000 fmtp= added to frozen list. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 0 PCMU/8000 fmtp= added to frozen list. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 101 telephone-event/8000 fmtp= added to frozen list. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 100 telephone-event/8000 fmtp= added to frozen list. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list. [message] 2020-04-13 21:56:26:065 LinphoneCall[0x55ff9849ed10] : payload type 99 telephone-event/16000 fmtp= added to frozen list. [message] 2020-04-13 21:56:26:065 Garbage collecting unowned object of type belle_sdp_session_description_t [warning] 2020-04-13 21:56:26:071 Getting reference signal but no echo to synchronize on. [warning] 2020-04-13 21:56:26:071 Not enough ref samples, using zeroes [message] 2020-04-13 21:56:26:071 MSAudioMixer [0x55ff985c5210] is entering bypass mode. [error ] 2020-04-13 21:56:26:071 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x7f19800049c0] to 217.10.77.153:19290: Operation not permitted [1] [message] 2020-04-13 21:56:26:081 Samples are back. [message] 2020-04-13 21:56:26:081 Stun packet sent for session [0x55ff9854df40] [error ] 2020-04-13 21:56:26:081 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x7f1980003aa0] to 217.10.77.153:19290: Operation not permitted [1] [message] 2020-04-13 21:56:26:081 Stun packet sent on rtcp for session [0x55ff9854df40] [error ] 2020-04-13 21:56:26:081 RtpSession [0x55ff9854df40] error sending [rtcp] packet [0x7f1980003aa0] to 217.10.77.153:19291: Operation not permitted [1] [warning] 2020-04-13 21:56:26:090 Not enough ref samples, using zeroes [error ] 2020-04-13 21:56:26:090 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x7f1980004800] to 217.10.77.153:19290: Operation not permitted [1] [message] 2020-04-13 21:56:26:101 Samples are back. [error ] 2020-04-13 21:56:26:110 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x55ff9850a0c0] to 217.10.77.153:19290: Operation not permitted [1] [error ] 2020-04-13 21:56:26:130 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x7f19800020b0] to 217.10.77.153:19290: Operation not permitted [1] [error ] 2020-04-13 21:56:26:151 RtpSession [0x55ff9854df40] error sending [rtp] packet [0x7f19800040b0] to 217.10.77.153:19290: Operation not permitted [1] |