[Blink] debian buster
Alberto
alberto.rinaudo at gmail.com
Thu Jul 29 10:51:37 CEST 2021
he, here's a new call
On Wed, 28 Jul 2021 at 23:26, Adrian Georgescu <ag at ag-projects.com> wrote:
> The 200 OK is not correctly handed. Can you enable both notifications and
> pjsip traces as well
>
> On 28 Jul 2021, at 19:00, Alberto <alberto.rinaudo at gmail.com> wrote:
>
> Hi,
> I mentioned I'm reproducing this on my own asterisk vm,
> I happen to have the cli open and noticed this error when blink cores
>
> [Jul 28 22:39:43] WARNING[24030]: pjproject: <?>: tsx0x7f7e5c315d48
> .Error sending Response msg 200/INVITE/cseq=19985 (tdta0x7f7e50010d68):
> Invalid operation (PJ_EINVALIDOP)
>
>
>
> On Wed, 28 Jul 2021 at 22:54, Alberto <alberto.rinaudo at gmail.com> wrote:
>
>> attached, thanks for looking into this.
>>
>> On Wed, 28 Jul 2021 at 22:01, Adrian Georgescu <ag at ag-projects.com>
>> wrote:
>>
>>> Can you show the sip trace. Enable SIP trace file logging.
>>>
>>> Adrian
>>>
>>>
>>>
>>> On 28 Jul 2021, at 17:57, Alberto <alberto.rinaudo at gmail.com> wrote:
>>>
>>> I have python3-sipsimple 5.2.5buster amd64
>>>
>>> On Wed, 28 Jul 2021 at 17:57, Adrian Georgescu <ag at ag-projects.com>
>>> wrote:
>>>
>>>> What version of python3-sipsimple are you running?
>>>>
>>>> dpkg -l python3-sipsimple
>>>>
>>>> On 28 Jul 2021, at 13:50, Alberto <alberto.rinaudo at gmail.com> wrote:
>>>>
>>>> I could do that, at least now we know that the "Unsupported Protocol"
>>>> is about.
>>>>
>>>> But now I have another problem, I tried to make a call using tls 1.2
>>>> and sdes.
>>>> Blink established the call, I got less than half a second of audio,
>>>> then cored.
>>>>
>>>> I followed this for crash reports:
>>>> https://icanblink.com/help/problems-qt/
>>>> but I don't see anything useful here. Any advice?
>>>>
>>>> $ gdb -c core blink
>>>> GNU gdb (Ubuntu 9.2-0ubuntu1~20.04) 9.2
>>>> Copyright (C) 2020 Free Software Foundation, Inc.
>>>> License GPLv3+: GNU GPL version 3 or later <
>>>> http://gnu.org/licenses/gpl.html>
>>>> This is free software: you are free to change and redistribute it.
>>>> There is NO WARRANTY, to the extent permitted by law.
>>>> Type "show copying" and "show warranty" for details.
>>>> This GDB was configured as "x86_64-linux-gnu".
>>>> Type "show configuration" for configuration details.
>>>> For bug reporting instructions, please see:
>>>> <http://www.gnu.org/software/gdb/bugs/>.
>>>> Find the GDB manual and other documentation resources online at:
>>>> <http://www.gnu.org/software/gdb/documentation/>.
>>>>
>>>> For help, type "help".
>>>> Type "apropos word" to search for commands related to "word"...
>>>> "/usr/bin/blink": not in executable format: file format not recognized
>>>> [New LWP 3893]
>>>> [New LWP 3887]
>>>> [New LWP 3905]
>>>> [New LWP 3910]
>>>> [New LWP 3914]
>>>> [New LWP 3883]
>>>> [New LWP 3885]
>>>> [New LWP 3891]
>>>> [New LWP 3917]
>>>> [New LWP 3901]
>>>> [New LWP 3884]
>>>> [New LWP 3886]
>>>> [New LWP 3889]
>>>> [New LWP 3890]
>>>> [New LWP 3912]
>>>> [New LWP 3902]
>>>> [New LWP 3913]
>>>> [New LWP 3881]
>>>> [New LWP 3915]
>>>> [New LWP 3916]
>>>> Core was generated by `/usr/bin/python3 /usr/bin/blink'.
>>>> Program terminated with signal SIGSEGV, Segmentation fault.
>>>> #0 0x00007f945cf6a955 in ?? ()
>>>> [Current thread is 1 (LWP 3893)]
>>>> (gdb)
>>>>
>>>>
>>>>
>>>>
>>>> On Wed, 28 Jul 2021 at 17:43, <blink at neilzone.co.uk> wrote:
>>>>
>>>>>
>>>>>
>>>>> On 28 Jul 2021, at 17:24, Alberto <alberto.rinaudo at gmail.com> wrote:
>>>>>
>>>>> the problem is that the server only accepts tls 1 and
>>>>>
>>>>>
>>>>> i thought TLS 1.0 and TLS 1.1 had been deprecated?
>>>>>
>>>>> If you had to make it work, could you use asterisk to connect to the
>>>>> SIP provider, and then use Blink to connect to asterisk? (In other worse,
>>>>> running your own PBX until the SIP trunk provider upgrades their security
>>>>> to something more modern.)
>>>>>
>>>>> Best wishes
>>>>>
>>>>> Neil
>>>>>
>>>>>
>>>>>
>>>>> _______________________________________________
>>>>> Blink mailing list
>>>>> Blink at lists.ag-projects.com
>>>>> https://lists.ag-projects.com/mailman/listinfo/blink
>>>>>
>>>> _______________________________________________
>>>> Blink mailing list
>>>> Blink at lists.ag-projects.com
>>>> https://lists.ag-projects.com/mailman/listinfo/blink
>>>>
>>>>
>>>> _______________________________________________
>>>> Blink mailing list
>>>> Blink at lists.ag-projects.com
>>>> https://lists.ag-projects.com/mailman/listinfo/blink
>>>>
>>> _______________________________________________
>>> Blink mailing list
>>> Blink at lists.ag-projects.com
>>> https://lists.ag-projects.com/mailman/listinfo/blink
>>>
>>>
>>> _______________________________________________
>>> Blink mailing list
>>> Blink at lists.ag-projects.com
>>> https://lists.ag-projects.com/mailman/listinfo/blink
>>>
>> _______________________________________________
> Blink mailing list
> Blink at lists.ag-projects.com
> https://lists.ag-projects.com/mailman/listinfo/blink
>
>
> _______________________________________________
> Blink mailing list
> Blink at lists.ag-projects.com
> https://lists.ag-projects.com/mailman/listinfo/blink
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ag-projects.com/pipermail/blink/attachments/20210729/3c452e46/attachment-0001.htm>
-------------- next part --------------
[blink 1605] (5) b"2021-07-29 09:43:34.915 alsa_dev.c open_playback: Open playback device 'default'"
[blink 1605] (5) b'2021-07-29 09:43:34.920 alsa_dev.c Opened device alsa(default) for playing, sample rate=48000, ch=1, bits=16, period size=960 frames, latency=140 ms'
[blink 1605] (5) b"2021-07-29 09:43:34.920 alsa_dev.c open_capture: Open capture device 'default'"
[blink 1605] (5) b'2021-07-29 09:43:34.924 alsa_dev.c Opened device alsa(default) for capture, sample rate=48000, ch=1, bits=16, period size=960 frames, latency=100 ms'
[blink 1605] (5) b'2021-07-29 09:43:34.924 ec0x3132610 Creating WEBRTC AEC'
[blink 1605] (3) b'2021-07-29 09:43:34.925 echo_webrtc.c WebRTC AEC successfully created with options 0'
[blink 1605] (5) b'2021-07-29 09:43:34.925 ec0x3132610 Using delay buffer with WSOLA.'
[blink 1605] (4) b'2021-07-29 09:43:34.925 ec0x3132610 WEBRTC AEC created, clock_rate=48000, channel=1, samples per frame=960, tail length=2 ms, latency=100 ms'
[blink 1605] (5) b'2021-07-29 09:43:34.925 alsa_dev.c ca_thread_func(1642): Set thread priority for audio capture thread.'
[blink 1605] (5) b'2021-07-29 09:43:34.925 alsa_dev.c Unable to increase thread priority, root access needed.'
[blink 1605] (4) b'2021-07-29 09:43:34.925 conference.c Port 2 (mixer-port) transmitting to port 0 (Master/sound)'
[blink 1605] (4) b'2021-07-29 09:43:34.925 conference.c Port 0 (Master/sound) transmitting to port 1 (mixer-port)'
[blink 1605] (4) b'2021-07-29 09:43:34.927 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.929 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.930 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.930 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.931 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.931 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (5) b'2021-07-29 09:43:34.932 ec0x3132610 Latency bufferring complete'
[blink 1605] (4) b'2021-07-29 09:43:34.932 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.932 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.933 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.933 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.933 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.934 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:34.935 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (5) b'2021-07-29 09:43:34.953 ec0x3132610 1439 samples reduced, buf_cnt=5281'
[blink 1605] (4) b'2021-07-29 09:43:34.953 ec0x3132610 Buffer size adjusted from 6720 to 5281 (eff_cnt=4320)'
[blink 1605] (5) b'2021-07-29 09:43:34.980 srtp0x7fd9f0049450 SRTP keying SDES created'
[blink 1605] (5) b'2021-07-29 09:43:34.987 ec0x3132610 1439 samples reduced, buf_cnt=3842'
[blink 1605] (4) b'2021-07-29 09:43:34.987 ec0x3132610 Buffer size adjusted from 5281 to 3842 (eff_cnt=4320)'
[blink 1605] (4) b'2021-07-29 09:43:34.987 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (4) b'2021-07-29 09:43:35.078 srtp0x7fd9f0049450 SRTP uses keying method SDES'
[blink 1605] (5) b'2021-07-29 09:43:35.079 dlg0x7fd9f0048508 UAC dialog created'
[blink 1605] (5) b'2021-07-29 09:43:35.079 dlg0x7fd9f0048508 Module mod-invite added as dialog usage, data=0x7fd9f005b0d8'
[blink 1605] (5) b'2021-07-29 09:43:35.079 dlg0x7fd9f0048508 .Session count inc to 3 by mod-invite'
[blink 1605] (5) b'2021-07-29 09:43:35.079 dlg0x7fd9f0048508 Module mod-100rel added as dialog usage, data=0x7fd9f0035b60'
[blink 1605] (5) b'2021-07-29 09:43:35.079 dlg0x7fd9f0048508 100rel module attached'
[blink 1605] (5) b'2021-07-29 09:43:35.079 inv0x7fd9f0048508 UAC invite session created for dialog dlg0x7fd9f0048508'
[blink 1605] (5) b'2021-07-29 09:43:35.079 endpoint Request msg INVITE/cseq=30188 (tdta0x7fd9f0067628) created.'
[blink 1605] (5) b'2021-07-29 09:43:35.079 inv0x7fd9f0048508 .Sending Request msg INVITE/cseq=30188 (tdta0x7fd9f0067628)'
[blink 1605] (5) b'2021-07-29 09:43:35.079 dlg0x7fd9f0048508 ..Sending Request msg INVITE/cseq=30188 (tdta0x7fd9f0067628)'
[blink 1605] (5) b'2021-07-29 09:43:35.079 tsx0x7fd9f005c9a8 ...Transaction created for Request msg INVITE/cseq=30187 (tdta0x7fd9f0067628)'
[blink 1605] (5) b'2021-07-29 09:43:35.079 tsx0x7fd9f005c9a8 ..Sending Request msg INVITE/cseq=30187 (tdta0x7fd9f0067628) in state Null'
[blink 1605] (5) b"2021-07-29 09:43:35.079 sip_resolve.c ...Target '217.75.85.122:0' type=TLS resolved to '217.75.85.122:5061' type=TLS (TLS transport)"
[blink 1605] (5) b'2021-07-29 09:43:35.080 tsx0x7fd9f005c9a8 ...State changed from Null to Calling, event=TX_MSG'
[blink 1605] (5) b'2021-07-29 09:43:35.080 dlg0x7fd9f0048508 ....Transaction tsx0x7fd9f005c9a8 state changed to Calling'
[blink 1605] (5) b'2021-07-29 09:43:35.098 sip_endpoint.c Processing incoming message: Response msg 401/INVITE/cseq=30187 (rdata0x7fd9f0083f78)'
[blink 1605] (5) b'2021-07-29 09:43:35.098 tsx0x7fd9f005c9a8 .Incoming Response msg 401/INVITE/cseq=30187 (rdata0x7fd9f0083f78) in state Calling'
[blink 1605] (5) b'2021-07-29 09:43:35.098 endpoint ..Request msg ACK/cseq=30187 (tdta0x7fd9e83614f8) created.'
[blink 1605] (5) b'2021-07-29 09:43:35.098 tsx0x7fd9f005c9a8 ..State changed from Calling to Completed, event=RX_MSG'
[blink 1605] (5) b'2021-07-29 09:43:35.098 dlg0x7fd9f0048508 ...Received Response msg 401/INVITE/cseq=30187 (rdata0x7fd9f0083f78)'
[blink 1605] (5) b'2021-07-29 09:43:35.098 dlg0x7fd9f0048508 ...Transaction tsx0x7fd9f005c9a8 state changed to Completed'
[blink 1605] (5) b'2021-07-29 09:43:35.098 inv0x7fd9f0048508 .....Sending Request msg INVITE/cseq=30187 (tdta0x7fd9f0067628)'
[blink 1605] (5) b'2021-07-29 09:43:35.098 dlg0x7fd9f0048508 ......Sending Request msg INVITE/cseq=30187 (tdta0x7fd9f0067628)'
[blink 1605] (5) b'2021-07-29 09:43:35.098 tsx0x7fd9e827cd18 .......Transaction created for Request msg INVITE/cseq=30188 (tdta0x7fd9f0067628)'
[blink 1605] (5) b'2021-07-29 09:43:35.098 tsx0x7fd9e827cd18 ......Sending Request msg INVITE/cseq=30188 (tdta0x7fd9f0067628) in state Null'
[blink 1605] (5) b'2021-07-29 09:43:35.098 endpoint .......Request msg INVITE/cseq=30188 (tdta0x7fd9f0067628): skipping target resolution because address is already set'
[blink 1605] (5) b'2021-07-29 09:43:35.098 tsx0x7fd9e827cd18 .......State changed from Null to Calling, event=TX_MSG'
[blink 1605] (5) b'2021-07-29 09:43:35.098 dlg0x7fd9f0048508 ........Transaction tsx0x7fd9e827cd18 state changed to Calling'
[blink 1605] (1) b'2021-07-29 09:43:35.098 ssl_sock_ossl.c [local TLS certificate] subject:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Blink/CN=Blink/emailAddress=devel at ag-projects.com | issuer:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Development/CN=AG Projects Development/emailAddress=devel at ag-projects.com | valid until:2029-1-24'
[blink 1605] (1) b"2021-07-29 09:43:35.098 ssl_sock_ossl.c [remote TLS certificate] subject:/CN=provider.net | issuer:/C=US/O=Let's Encrypt/CN=R3 | valid until:2021-8-6 | host:217.75.85.122:50451"
[blink 1605] (5) b'2021-07-29 09:43:35.101 tsx0x7fd9f005c9a8 Timeout timer event'
[blink 1605] (5) b'2021-07-29 09:43:35.101 tsx0x7fd9f005c9a8 .State changed from Completed to Terminated, event=TIMER'
[blink 1605] (5) b'2021-07-29 09:43:35.101 dlg0x7fd9f0048508 ..Transaction tsx0x7fd9f005c9a8 state changed to Terminated'
[blink 1605] (5) b'2021-07-29 09:43:35.101 tsx0x7fd9f005c9a8 Timeout timer event'
[blink 1605] (5) b'2021-07-29 09:43:35.101 tsx0x7fd9f005c9a8 .State changed from Terminated to Destroyed, event=TIMER'
[blink 1605] (5) b'2021-07-29 09:43:35.101 tdta0x7fd9e83614f8 ..Destroying txdata Request msg ACK/cseq=30187 (tdta0x7fd9e83614f8)'
[blink 1605] (5) b'2021-07-29 09:43:35.101 tsx0x7fd9f005c9a8 Transaction destroyed!'
[blink 1605] (5) b'2021-07-29 09:43:35.115 sip_endpoint.c Processing incoming message: Response msg 100/INVITE/cseq=30188 (rdata0x7fd9f0083f78)'
[blink 1605] (5) b'2021-07-29 09:43:35.115 tsx0x7fd9e827cd18 .Incoming Response msg 100/INVITE/cseq=30188 (rdata0x7fd9f0083f78) in state Calling'
[blink 1605] (5) b'2021-07-29 09:43:35.115 tsx0x7fd9e827cd18 ..State changed from Calling to Proceeding, event=RX_MSG'
[blink 1605] (5) b'2021-07-29 09:43:35.115 dlg0x7fd9f0048508 ...Received Response msg 100/INVITE/cseq=30188 (rdata0x7fd9f0083f78)'
[blink 1605] (5) b'2021-07-29 09:43:35.115 dlg0x7fd9f0048508 ...Transaction tsx0x7fd9e827cd18 state changed to Proceeding'
[blink 1605] (1) b'2021-07-29 09:43:35.116 ssl_sock_ossl.c [local TLS certificate] subject:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Blink/CN=Blink/emailAddress=devel at ag-projects.com | issuer:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Development/CN=AG Projects Development/emailAddress=devel at ag-projects.com | valid until:2029-1-24'
[blink 1605] (1) b"2021-07-29 09:43:35.116 ssl_sock_ossl.c [remote TLS certificate] subject:/CN=provider.net | issuer:/C=US/O=Let's Encrypt/CN=R3 | valid until:2021-8-6 | host:217.75.85.122:50451"
[blink 1605] (5) b'2021-07-29 09:43:35.170 sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=30188 (rdata0x7fd9f0083f78)'
[blink 1605] (5) b'2021-07-29 09:43:35.170 tsx0x7fd9e827cd18 .Incoming Response msg 200/INVITE/cseq=30188 (rdata0x7fd9f0083f78) in state Proceeding'
[blink 1605] (5) b'2021-07-29 09:43:35.170 tsx0x7fd9e827cd18 ..State changed from Proceeding to Terminated, event=RX_MSG'
[blink 1605] (5) b'2021-07-29 09:43:35.170 dlg0x7fd9f0048508 ...Received Response msg 200/INVITE/cseq=30188 (rdata0x7fd9f0083f78)'
[blink 1605] (5) b'2021-07-29 09:43:35.170 dlg0x7fd9f0048508 ....Route-set updated'
[blink 1605] (5) b'2021-07-29 09:43:35.170 dlg0x7fd9f0048508 ....Route-set frozen'
[blink 1605] (5) b'2021-07-29 09:43:35.170 dlg0x7fd9f0048508 ...Transaction tsx0x7fd9e827cd18 state changed to Terminated'
[blink 1605] (5) b'2021-07-29 09:43:35.170 inv0x7fd9f0048508 ....Got SDP answer in Response msg 200/INVITE/cseq=30188 (rdata0x7fd9f0083f78)'
[blink 1605] (4) b'2021-07-29 09:43:35.170 inv0x7fd9f0048508 ....SDP negotiation done: Success'
[blink 1605] (5) b'2021-07-29 09:43:35.171 inv0x7fd9f0048508 ....Received Response msg 200/INVITE/cseq=30188 (rdata0x7fd9f0083f78), sending ACK'
[blink 1605] (5) b'2021-07-29 09:43:35.171 endpoint ....Request msg ACK/cseq=30188 (tdta0x7fd9e8366248) created.'
[blink 1605] (5) b'2021-07-29 09:43:35.171 dlg0x7fd9f0048508 .....Sending Request msg ACK/cseq=30188 (tdta0x7fd9e8366248)'
[blink 1605] (5) b"2021-07-29 09:43:35.171 sip_resolve.c .....Target '172.0.0.217:5061' type=TLS resolved to '172.0.0.217:5061' type=TLS (TLS transport)"
[blink 1605] (4) b'2021-07-29 09:43:35.172 tlsc0x7fd9e83a4b38 .....TLS client transport created'
[blink 1605] (4) b'2021-07-29 09:43:35.172 tlsc0x7fd9e83a4b38 .....TLS transport 192.168.0.15:36705 is connecting to 172.0.0.217:5061...'
[blink 1605] (1) b'2021-07-29 09:43:35.172 ssl_sock_ossl.c [local TLS certificate] subject:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Blink/CN=Blink/emailAddress=devel at ag-projects.com | issuer:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Development/CN=AG Projects Development/emailAddress=devel at ag-projects.com | valid until:2029-1-24'
[blink 1605] (1) b"2021-07-29 09:43:35.172 ssl_sock_ossl.c [remote TLS certificate] subject:/CN=provider.net | issuer:/C=US/O=Let's Encrypt/CN=R3 | valid until:2021-8-6 | host:217.75.85.122:50451"
[blink 1605] (5) b'2021-07-29 09:43:35.188 tsx0x7fd9e827cd18 Timeout timer event'
[blink 1605] (5) b'2021-07-29 09:43:35.188 tsx0x7fd9e827cd18 .State changed from Terminated to Destroyed, event=TIMER'
[blink 1605] (5) b'2021-07-29 09:43:35.188 tsx0x7fd9e827cd18 Transaction destroyed!'
[blink 1605] (5) b'2021-07-29 09:43:35.201 srtp0x7fd9f0049450 TX: AES_CM_128_HMAC_SHA1_80 key=C/pTxqoCx7Fc0u15ONE//qyX2gGBhtE6DXqumAjI'
[blink 1605] (5) b'2021-07-29 09:43:35.201 srtp0x7fd9f0049450 RX: AES_CM_128_HMAC_SHA1_80 key=d0tUarUhywLuXqG5CU3SR8TijgF2/ril8GDE7vOq'
[blink 1605] (4) b'2021-07-29 09:43:35.201 srtp0x7fd9f0049450 SRTP started, keying=SDES, crypto=AES_CM_128_HMAC_SHA1_80'
[blink 1605] (5) b'2021-07-29 09:43:35.201 rtp.c pjmedia_rtp_session_init: ses=0x7fd9f006d890, default_pt=3, ssrc=0x72d424b9'
[blink 1605] (5) b'2021-07-29 09:43:35.201 rtp.c pjmedia_rtp_session_init: ses=0x7fd9f006da40, default_pt=3, ssrc=0x72d424b9'
[blink 1605] (5) b'2021-07-29 09:43:35.201 udp0x7fd9f0028ab0 SO_RCVBUF set to 212992'
[blink 1605] (5) b'2021-07-29 09:43:35.201 udp0x7fd9f0028ab0 SO_SNDBUF set to 212992'
[blink 1605] (4) b'2021-07-29 09:43:35.201 Master/sound Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (5) b'2021-07-29 09:43:35.201 strm0x7fd9f006bb18 Sending keep-alive (RTCP and empty RTP)'
[blink 1605] (5) b'2021-07-29 09:43:35.201 stream.c Stream strm0x7fd9f006bb18 created'
[blink 1605] (4) b'2021-07-29 09:43:35.201 strm0x7fd9f006bb18 Encoder stream started'
[blink 1605] (4) b'2021-07-29 09:43:35.201 strm0x7fd9f006bb18 Decoder stream started'
[blink 1605] (5) b'2021-07-29 09:43:35.203 resample.c resample created: high qualiy, large filter, in/out rate=8000/48000'
[blink 1605] (5) b'2021-07-29 09:43:35.203 resample.c resample created: high qualiy, large filter, in/out rate=48000/8000'
[blink 1605] (5) b'2021-07-29 09:43:35.203 ec0x3132610 Delay buffer is reset'
[blink 1605] (4) b'2021-07-29 09:43:35.203 echo_webrtc.c WebRTC AEC reset succeeded'
[blink 1605] (4) b'2021-07-29 09:43:35.203 sound_port.c EC reset'
[blink 1605] (4) b'2021-07-29 09:43:35.204 ec0x3132610 Underflow, buf_cnt=0, will generate 1 frame'
[blink 1605] (5) b'2021-07-29 09:43:35.205 ec0x3132610 Delay buffer is reset'
[blink 1605] (4) b'2021-07-29 09:43:35.205 echo_webrtc.c WebRTC AEC reset succeeded'
[blink 1605] (4) b'2021-07-29 09:43:35.205 sound_port.c EC reset'
[blink 1605] (4) b'2021-07-29 09:43:35.205 conference.c Port 2 (mixer-port) transmitting to port 3 (strm0x7fd9f006bb18)'
[blink 1605] (4) b'2021-07-29 09:43:35.205 conference.c Port 3 (strm0x7fd9f006bb18) transmitting to port 1 (mixer-port)'
[blink 1605] (4) b'2021-07-29 09:43:35.207 conference.c Port 0 (Master/sound) transmitting to port 3 (strm0x7fd9f006bb18)'
[blink 1605] (4) b'2021-07-29 09:43:35.207 conference.c Port 3 (strm0x7fd9f006bb18) transmitting to port 0 (Master/sound)'
[blink 1605] (1) b'2021-07-29 09:43:35.211 ssl0x7fd9e8341ee0 [SSL_set_tlsext_host_name] server_name:172.0.0.217'
[blink 1605] (5) b'2021-07-29 09:43:35.223 ec0x3132610 Prefetching..'
[blink 1605] (5) b'2021-07-29 09:43:35.224 strm0x7fd9f006bb18 Jitter buffer is bufferring (prefetch=0)'
[blink 1605] (5) b'2021-07-29 09:43:35.226 strm0x7fd9f006bb18 Start talksprut..'
[blink 1605] (5) b'2021-07-29 09:43:35.246 ec0x3132610 Prefetching..'
[blink 1605] (5) b'2021-07-29 09:43:35.246 ec0x3132610 Prefetching..'
[blink 1605] (5) b'2021-07-29 09:43:35.269 ec0x3132610 Prefetching..'
[blink 1605] (1) b'2021-07-29 09:43:35.275 ssl_sock_ossl.c [local TLS certificate] subject:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Blink/CN=Blink/emailAddress=devel at ag-projects.com | issuer:/C=NL/ST=Noord-Holland/L=Haarlem/O=AG Projects/OU=Development/CN=AG Projects Development/emailAddress=devel at ag-projects.com | valid until:2029-1-24'
[blink 1605] (1) b"2021-07-29 09:43:35.275 ssl_sock_ossl.c [remote TLS certificate] subject:/CN=provider.net | issuer:/C=US/O=Let's Encrypt/CN=R3 | valid until:2021-8-6 | host:172.0.0.217:50451"
[blink 1605] (4) b'2021-07-29 09:43:35.275 sip_transport.c Transport tlsc0x7fd9e83a4b38 shutting down, force=0'
[blink 1605] (3) b'2021-07-29 09:43:35.275 tlsc0x7fd9e83a4b38 TLS connect() error: [code=171173] peer: 172.0.0.217: SSL certificate verification error (PJSIP_TLS_ECERTVERIF)'
[blink 1605] (3) b'2021-07-29 09:43:35.275 sip_dialog.c Failed to send Request msg ACK/cseq=30188 (tdta0x7fd9e8366248)!: SSL certificate verification error (PJSIP_TLS_ECERTVERIF)'
[blink 1605] (5) b'2021-07-29 09:43:35.275 tlsc0x7fd9e83a4b38 TLS send() error, sent=-171173'
[blink 1605] (4) b'2021-07-29 09:43:35.279 tlsc0x7fd9e83a4b38 TLS transport destroyed with reason 171173: SSL certificate verification error (PJSIP_TLS_ECERTVERIF)'
[blink 1605] (5) b'2021-07-29 09:43:35.291 ec0x3132610 Prefetching..'
[blink 1605] (5) b'2021-07-29 09:43:35.314 ec0x3132610 Prefetching..'
[blink 1605] (5) b'2021-07-29 09:43:35.325 ec0x3132610 Latency bufferring complete'
[blink 1605] (5) b'2021-07-29 09:43:35.326 strm0x7fd9f006bb18 RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0'
[blink 1605] (5) b'2021-07-29 09:43:35.358 strm0x7fd9f006bb18 Jitter buffer starts returning normal frames (after 6 empty/lost)'
[blink 1605] (4) b'2021-07-29 09:43:35.407 ec0x3132610 Underflow, buf_cnt=0, will generate 1 frame'
-------------- next part --------------
2021-07-29 09:43:34.957084 [blink 1605]: DNS lookup A sip.provider.net succeeded, ttl=289: 217.75.85.122
2021-07-29 09:43:35.099567 [blink 1605]: SENDING: Packet 1, +0:00:04.014156
192.168.0.15:39103 -(SIP over TLS)-> 217.75.85.122:5061
INVITE sip:0 at sip.provider.net SIP/2.0
Via: SIP/2.0/TLS 192.168.0.15:39103;rport;branch=z9hG4bKPj5ee4ce8a-f049-11eb-a63e-bfc50dd0085e;alias
Max-Forwards: 70
From: "1000" <sip:1000 at sip.provider.net>;tag=5ee2b35c-f049-11eb-b853-2797e780240f
To: <sip:0 at sip.provider.net>
Contact: <sip:69018234 at 192.168.0.15:36787;transport=tls>
Call-ID: 5ee88c82-f049-11eb-b75b-b7233f2233c8
CSeq: 30187 INVITE
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Supported: replaces, norefersub, gruu
User-Agent: Blink 5.1.3 (Linux)
Content-Type: application/sdp
Content-Length: 420
v=0
o=- 3836537015 3836537015 IN IP4 192.168.0.15
s=Blink 5.1.3 (Linux)
t=0 0
m=audio 50000 RTP/SAVP 3 8 101
c=IN IP4 192.168.0.15
a=rtcp:50001
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:C/pTxqoCx7Fc0u15ONE//qyX2gGBhtE6DXqumAjI
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:WHJ2g5md9gZAoolFZcXwvFTub/rp+DdhpsZILb9o
a=sendrecv
--
2021-07-29 09:43:35.099866 [blink 1605]: RECEIVED: Packet 2, +0:00:04.014455
217.75.85.122:5061 -(SIP over TLS)-> 192.168.0.15:39103
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TLS 192.168.0.15:39103;rport=48824;received=172.0.0.13;branch=z9hG4bKPj5ee4ce8a-f049-11eb-a63e-bfc50dd0085e;alias
Call-ID: 5ee88c82-f049-11eb-b75b-b7233f2233c8
From: "1000" <sip:1000 at sip.provider.net>;tag=5ee2b35c-f049-11eb-b853-2797e780240f
To: <sip:0 at sip.provider.net>;tag=z9hG4bKPj5ee4ce8a-f049-11eb-a63e-bfc50dd0085e
CSeq: 30187 INVITE
WWW-Authenticate: Digest realm="sip.provider.net",nonce="1627548215/e4f9ea9ab191972842bd23e7ee937ed0",opaque="6546cd620f2aebed",algorithm=md5,qop="auth"
Server: 1000PBX
Content-Length: 0
--
2021-07-29 09:43:35.100088 [blink 1605]: SENDING: Packet 3, +0:00:04.014677
192.168.0.15:39103 -(SIP over TLS)-> 217.75.85.122:5061
ACK sip:0 at sip.provider.net SIP/2.0
Via: SIP/2.0/TLS 192.168.0.15:39103;rport;branch=z9hG4bKPj5ee4ce8a-f049-11eb-a63e-bfc50dd0085e;alias
Max-Forwards: 70
From: "1000" <sip:1000 at sip.provider.net>;tag=5ee2b35c-f049-11eb-b853-2797e780240f
To: <sip:0 at sip.provider.net>;tag=z9hG4bKPj5ee4ce8a-f049-11eb-a63e-bfc50dd0085e
Call-ID: 5ee88c82-f049-11eb-b75b-b7233f2233c8
CSeq: 30187 ACK
User-Agent: Blink 5.1.3 (Linux)
Content-Length: 0
--
2021-07-29 09:43:35.100841 [blink 1605]: SENDING: Packet 4, +0:00:04.015430
192.168.0.15:39103 -(SIP over TLS)-> 217.75.85.122:5061
INVITE sip:0 at sip.provider.net SIP/2.0
Via: SIP/2.0/TLS 192.168.0.15:39103;rport;branch=z9hG4bKPj5eea923e-f049-11eb-bbe1-fff7adcb7972;alias
Max-Forwards: 70
From: "1000" <sip:1000 at sip.provider.net>;tag=5ee2b35c-f049-11eb-b853-2797e780240f
To: <sip:0 at sip.provider.net>
Contact: <sip:69018234 at 192.168.0.15:36787;transport=tls>
Call-ID: 5ee88c82-f049-11eb-b75b-b7233f2233c8
CSeq: 30188 INVITE
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Supported: replaces, norefersub, gruu
User-Agent: Blink 5.1.3 (Linux)
Authorization: Digest username="1000", realm="sip.provider.net", nonce="1627548215/e4f9ea9ab191972842bd23e7ee937ed0", uri="sip:0 at sip.provider.net", response="1dbc77f73bb4e4477e933dcb6059da70", algorithm=md5, cnonce="f24220f013362298bb20232749b41e2d", opaque="6546cd620f2aebed", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Length: 420
v=0
o=- 3836537015 3836537015 IN IP4 192.168.0.15
s=Blink 5.1.3 (Linux)
t=0 0
m=audio 50000 RTP/SAVP 3 8 101
c=IN IP4 192.168.0.15
a=rtcp:50001
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:C/pTxqoCx7Fc0u15ONE//qyX2gGBhtE6DXqumAjI
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:WHJ2g5md9gZAoolFZcXwvFTub/rp+DdhpsZILb9o
a=sendrecv
--
2021-07-29 09:43:35.116820 [blink 1605]: RECEIVED: Packet 5, +0:00:04.031409
217.75.85.122:5061 -(SIP over TLS)-> 192.168.0.15:39103
SIP/2.0 100 Trying
Via: SIP/2.0/TLS 192.168.0.15:39103;rport=48824;received=172.0.0.13;branch=z9hG4bKPj5eea923e-f049-11eb-bbe1-fff7adcb7972;alias
Call-ID: 5ee88c82-f049-11eb-b75b-b7233f2233c8
From: "1000" <sip:1000 at sip.provider.net>;tag=5ee2b35c-f049-11eb-b853-2797e780240f
To: <sip:0 at sip.provider.net>
CSeq: 30188 INVITE
Server: 1000PBX
Content-Length: 0
--
2021-07-29 09:43:35.173004 [blink 1605]: RECEIVED: Packet 6, +0:00:04.087593
217.75.85.122:5061 -(SIP over TLS)-> 192.168.0.15:39103
SIP/2.0 200 OK
Via: SIP/2.0/TLS 192.168.0.15:39103;rport=48824;received=172.0.0.13;branch=z9hG4bKPj5eea923e-f049-11eb-bbe1-fff7adcb7972;alias
Call-ID: 5ee88c82-f049-11eb-b75b-b7233f2233c8
From: "1000" <sip:1000 at sip.provider.net>;tag=5ee2b35c-f049-11eb-b853-2797e780240f
To: <sip:0 at sip.provider.net>;tag=5ee08ffa-f049-11eb-892b-d3e19dc6cb17
CSeq: 30188 INVITE
Server: 1000PBX
Contact: <sip:172.0.0.217:5061;transport=TLS>
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub
Content-Type: application/sdp
Content-Length: 345
v=0
o=- 3836537015 3836537017 IN IP4 172.0.0.217
s=Asterisk
c=IN IP4 172.0.0.217
t=0 0
m=audio 12994 RTP/SAVP 3 8 101
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:d0tUarUhywLuXqG5CU3SR8TijgF2/ril8GDE7vOq
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
--
2021-07-29 09:43:35.174364 [blink 1605]: SENDING: Packet 7, +0:00:04.088953
192.168.0.15:36705 -(SIP over TLS)-> 172.0.0.217:5061
ACK sip:172.0.0.217:5061;transport=TLS SIP/2.0
Via: SIP/2.0/TLS 192.168.0.15:36705;rport;branch=z9hG4bKPj5ee6e94a-f049-11eb-8f40-835f77923d21;alias
Max-Forwards: 70
From: "1000" <sip:1000 at sip.provider.net>;tag=5ee2b35c-f049-11eb-b853-2797e780240f
To: <sip:0 at sip.provider.net>;tag=5ee08ffa-f049-11eb-892b-d3e19dc6cb17
Call-ID: 5ee88c82-f049-11eb-b75b-b7233f2233c8
CSeq: 30188 ACK
User-Agent: Blink 5.1.3 (Linux)
Content-Length: 0
--
More information about the Blink
mailing list