Skip to content
New issue

Have a question about this project? # for a free GitHub account to open an issue and contact its maintainers and the community.

#

By clicking “#”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? # to your account

Jump to bottom

No audio on answer #73

Open
multi opened this issue Mar 10, 2023 · 10 comments
Open

No audio on answer #73

multi opened this issue Mar 10, 2023 · 10 comments

Comments

@multi
Copy link

multi commented Mar 10, 2023 •
edited
Loading

Hi there, i'm having troubles getting the audio rtp streams working, it doesn't "starts" 99% of the times.
The SIP signalling works, I'm trying a SIP->TG call, the mobile phone (tg) rings, but on answer - there's no audio.

Asterisk - on real IP (in the logs - 192.168.1.6 is the internal address in the DMZ)
tg2sip (running branch feature/libtgvoip-update) - using opus (running on the same machine)

I've saw few times First audio packet - setting state to ESTABLISHED and then the audio started. As far as I understand, there's no STREAM_DATA* packet for some reason?!
Not sure if it's a networking/latency issue on my side or something else? Any hints are welcome :)

I've tried with

  1. jitter_initial_delay_60=4 - it worked 10 times in a row, after restarting tg2sip doesn't worked anymore

  2. ptime 20, instead of 10 (in sip.cpp)

ep_cfg.medConfig.audioFramePtime = 20;
ep_cfg.medConfig.ptime = 20;

No audio

[09:54:01.704][t:419318][p:419318][pjsip][info]   [CONFIRMED] To: <sip:telegram@127.0.0.1>;tag=52dec82e-39d4-45a6-921d-e63287d8272f
    Call time: 00h:00m:25s, 1st res in 3 ms, conn in 7955ms
    #0 audio opus @48kHz, sendrecv, peer=127.0.0.1:17212
       SRTP status: Not active Crypto-suite: 
       RX pt=107, last update:00h:00m:04.301s ago
          total 1.2Kpkt 81.0KB (130.3KB +IP hdr) @avg=25.3Kbps/40.8Kbps
          pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%)
                (msec)    min     avg     max     last    dev
          loss period:   0.000   0.000   0.000   0.000   0.000
          jitter     :   1.166   6.502  29.020   5.645   3.434
       TX pt=107, ptime=20, last update:00h:00m:00.422s ago
          total 1.2Kpkt 4.4KB (55.4KB +IP hdr) @avg=1.3Kbps/17.3Kbps
          pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%)
                (msec)    min     avg     max     last    dev 
          loss period:   0.000   0.000   0.000   0.000   0.000
          jitter     :   0.458   0.471   0.479   0.479   0.010
       RTT msec      :   0.198   0.631   0.930   0.930   0.243

[09:54:01.704][t:419318][p:419318][pjsip][debug] UDP media transport detached
[09:54:01.704][t:419318][p:419318][pjsip][trace] JB summary:
  size=1/eff=1 prefetch=0 level=2
  delay (min/max/avg/dev)=40/140/67/27 ms
  burst (min/max/avg/dev)=1/7/1/0 frames
  lost=4 discard=0 empty=49

With audio

[20:26:25.904][t:1994726][p:1994723][pjsip][info]   [DISCONNECTED] To: <sip:telegram@127.0.0.1>;tag=27982595-834a-4d03-be19-c6d5afbef9a8
    Call time: 00h:01m:36s, 1st res in 7 ms, conn in 3153ms
    #0 audio opus @48kHz, sendrecv, peer=127.0.0.1:12560
       SRTP status: Not active Crypto-suite: 
       RX pt=107, last update:00h:00m:00.507s ago
          total 4.7Kpkt 188.8KB (379.4KB +IP hdr) @avg=15.6Kbps/31.5Kbps
          pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%)
                (msec)    min     avg     max     last    dev
          loss period:   0.000   0.000   0.000   0.000   0.000
          jitter     :   0.020   4.385   6.645   3.333   1.029
       TX pt=107, ptime=20, last update:00h:00m:01.197s ago
          total 4.8Kpkt 271.8KB (464.4KB +IP hdr) @avg=22.5Kbps/38.5Kbps
          pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%)
                (msec)    min     avg     max     last    dev 
          loss period:   0.000   0.000   0.000   0.000   0.000
          jitter     :   0.104   0.384   0.895   0.354   0.175
       RTT msec      :   0.244   0.711   1.174   0.366   0.270

No audio log

[20:26:56.166][t:1994723][p:1994723][core][debug] [1994723-4] creating voip for TG #2
[20:26:56.166][t:1994723][p:1994723][tgvoip][info] update data saving mode, config 0, enabled 0, reqd by peer 0
[20:26:56.166][t:1994723][p:1994723][tgvoip][warning] Set remote endpoints, allowP2P=0, connectionMaxLayer=92
[20:26:56.166][t:1994723][p:1994723][tgvoip][warning] Starting voip controller
[20:26:56.166][t:1994723][p:1994723][tgvoip][debug] Bound to local UDP port 23980
[20:26:56.166][t:1995076][p:1994723][tgvoip][info] Receive thread starting
[20:26:56.166][t:1994723][p:1994723][core][debug] [1994723-4] answering SIP #1
[20:26:56.166][t:1995078][p:1994723][tgvoip][info] before create audio io
[20:26:56.166][t:1995078][p:1994723][tgvoip][info] AEC: 0 NS: 0 AGC: 0
[20:26:56.166][t:1994723][p:1994723][pjsip][debug] Answering call 1: code=200
[20:26:56.166][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:26:56.166][t:1994723][p:1994723][pjsip][debug] SDP negotiation done: Success
[20:26:56.166][t:1994723][p:1994723][pjsip][debug] Call 1: updating media..
[20:26:56.167][t:1995078][p:1994723][tgvoip][info] Audio initialization took 0.000132 seconds
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Media stream call01:0 is destroyed
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] UDP media transport started
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Audio channel update..
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Initialize Opus encoder, sample rate: 48000, ch: 1, avg bitrate: 0(auto), vad: 1, plc: 1, pkt loss: 5, complexity: 5, constant bit rate: 1
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Modifying Opus encoder, sample rate: 48000, ch: 1, avg bitrate: 0(auto), vad: 0, plc: 1, pkt loss: 5, complexity: 5, constant bit rate: 0
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] VAD temporarily disabled
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] UDP media transport attached
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Encoder stream started
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Decoder stream started
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Audio updated, stream #0: opus (sendrecv)
[20:26:56.167][t:1995078][p:1994723][tgvoip][info] === send thread exiting ===
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] TX 875 bytes Response msg 200/INVITE/cseq=5438 (tdta0x984039b53c8) to UDP 127.0.0.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 127.0.0.1:5060;rport=5060;received=127.0.0.1;branch=z9hG4bKPj2d26e8f7-8846-4d80-b37f-2570eac9d12c
Call-ID: af2e5fc7-c2a4-4e85-bd45-ed0200201171
From: <sip:telegram@127.0.0.1>;tag=ede6f311-1193-42e7-af97-17b88430e2bc
To: <sip:+XXXXXXXXXXXX@127.0.0.1>;tag=45ce0d7b-bfe7-4da9-9d9e-e01ae09dd448
CSeq: 5438 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Contact: <sip:telegram@127.0.0.1:5566>
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length:   293

v=0
o=- 3886518411 3886518412 IN IP4 192.168.1.6
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4002 RTP/AVPF 107
c=IN IP4 192.168.1.6
b=TIAS:96000
a=rtcp:4003 IN IP4 192.168.1.6
a=sendrecv
a=ssrc:1333642423 cname:32452c6f0de6bfd7
a=rtpmap:107 opus/48000/2
a=fmtp:107 useinbandfec=1

--end msg--
[20:26:56.167][t:1994723][p:1994723][core][debug] [1994723-4] bridging tgvoip audio with SIP#1
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Switch connect: 3 --> 1
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Set sound device: capture=-99, playback=-99, mode=0, use_default_settings=0
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Null sound device, mode setting is ignored
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Setting null sound device..
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Opening null sound device..
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Port 3 (sip:telegram@127.0.0.1) transmitting to port 1 (input)
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Switch connect: 2 --> 3
[20:26:56.167][t:1994723][p:1994723][pjsip][debug] Port 2 (output) transmitting to port 3 (sip:telegram@127.0.0.1)
[20:26:56.167][t:1994726][p:1994723][pjsip][debug] RX 418 bytes Request msg ACK/cseq=5438 (rdata0x6dda20023ba8) from UDP 127.0.0.1:5060:
ACK sip:telegram@127.0.0.1:5566 SIP/2.0
Via: SIP/2.0/UDP 127.0.0.1:5060;rport;branch=z9hG4bKPjb9c2f49b-faf0-4c42-8025-1178361539a9
From: <sip:telegram@127.0.0.1>;tag=ede6f311-1193-42e7-af97-17b88430e2bc
To: <sip:+XXXXXXXXXXXX@127.0.0.1>;tag=45ce0d7b-bfe7-4da9-9d9e-e01ae09dd448
Call-ID: af2e5fc7-c2a4-4e85-bd45-ed0200201171
CSeq: 5438 ACK
Max-Forwards: 70
User-Agent: Asterisk PBX 20.1.0
Content-Length:  0


--end msg--
[20:26:56.176][t:1995079][p:1994723][pjsip][debug] Resetting jitter buffer in stream playback start
[20:26:56.206][t:1995076][p:1994723][tgvoip][info] Detected IPv4 connectivity, will not try IPv6
[20:26:56.252][t:1995076][p:1994723][tgvoip][debug] Received init
[20:26:56.252][t:1995076][p:1994723][tgvoip][info] Peer version is 9
[20:26:56.252][t:1995076][p:1994723][tgvoip][info] Sending init ack
[20:26:56.366][t:1995076][p:1994723][tgvoip][warning] Received duplicated packet for seq 1
[20:26:56.385][t:1995076][p:1994723][tgvoip][warning] Received duplicated packet for seq 1
[20:26:56.666][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:26:56.752][t:1995076][p:1994723][tgvoip][debug] Received init ack
[20:26:56.752][t:1995076][p:1994723][tgvoip][info] peer version from init ack 9
[20:26:56.752][t:1995076][p:1994723][tgvoip][info] jitter: set min packet count 2
[20:26:56.752][t:1995076][p:1994723][tgvoip][info] Audio I/O ready
[20:26:56.753][t:1995082][p:1994723][tgvoip][info] decoder: packets per frame 3
[20:26:56.753][t:1995076][p:1994723][tgvoip][debug] Received init
[20:26:56.753][t:1995076][p:1994723][tgvoip][info] Peer version is 9
[20:26:56.753][t:1995076][p:1994723][tgvoip][info] Sending init ack
[20:26:56.807][t:1995079][p:1994723][pjsip][debug] Modifying Opus encoder, sample rate: 48000, ch: 1, avg bitrate: 0(auto), vad: 1, plc: 1, pkt loss: 5, complexity: 5, constant bit rate: 0
[20:26:56.807][t:1995079][p:1994723][pjsip][debug] VAD re-enabled
[20:26:56.868][t:1995076][p:1994723][tgvoip][warning] Received duplicated packet for seq 3
[20:26:56.887][t:1995076][p:1994723][tgvoip][warning] Received duplicated packet for seq 3
[20:26:57.166][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:26:57.248][t:1995076][p:1994723][tgvoip][debug] Received init ack
[20:26:57.258][t:1995076][p:1994723][tgvoip][debug] Received init
[20:26:57.258][t:1995076][p:1994723][tgvoip][info] Peer version is 9
[20:26:57.258][t:1995076][p:1994723][tgvoip][info] Sending init ack
[20:26:57.372][t:1995076][p:1994723][tgvoip][warning] Received duplicated packet for seq 5
[20:26:57.391][t:1995076][p:1994723][tgvoip][warning] Received duplicated packet for seq 5
[20:26:57.666][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:26:57.748][t:1995076][p:1994723][tgvoip][debug] Received init ack
[20:26:58.253][t:1995076][p:1994723][tgvoip][debug] Received init ack
[20:26:58.667][t:1995077][p:1994723][tgvoip][info] UDP ping reply count: 4.00
[20:26:59.253][t:1995077][p:1994723][tgvoip][debug] SIGNAL BAR COUNT CHANGED: 4
[20:27:00.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 32 was not acknowledged
[20:27:00.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 33 was not acknowledged
[20:27:00.254][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.9.118: 0.087, average: 0.087
[20:27:00.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 35 was not acknowledged
[20:27:00.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 36 was not acknowledged
[20:27:00.479][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.13.40: 0.312, average: 0.312
[20:27:00.518][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.17.26: 0.351, average: 0.351
[20:27:01.253][t:1995077][p:1994723][tgvoip][warning] Enabling extra EC
[20:27:02.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 74 was not acknowledged
[20:27:02.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 75 was not acknowledged
[20:27:02.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 76 was not acknowledged
[20:27:02.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 77 was not acknowledged
[20:27:02.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 78 was not acknowledged
[20:27:02.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 79 was not acknowledged
[20:27:02.753][t:1995077][p:1994723][tgvoip][info] Resetting UDP availability
[20:27:02.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 80 was not acknowledged
[20:27:02.753][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:02.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 81 was not acknowledged
[20:27:02.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 82 was not acknowledged
[20:27:02.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 83 was not acknowledged
[20:27:02.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 84 was not acknowledged
[20:27:03.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 85 was not acknowledged
[20:27:03.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 86 was not acknowledged
[20:27:03.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 87 was not acknowledged
[20:27:03.253][t:1995077][p:1994723][tgvoip][debug] SIGNAL BAR COUNT CHANGED: 3
[20:27:03.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 88 was not acknowledged
[20:27:03.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 89 was not acknowledged
[20:27:03.253][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:03.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 90 was not acknowledged
[20:27:03.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 91 was not acknowledged
[20:27:03.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 92 was not acknowledged
[20:27:03.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 93 was not acknowledged
[20:27:03.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 94 was not acknowledged
[20:27:03.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 95 was not acknowledged
[20:27:03.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 96 was not acknowledged
[20:27:03.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 97 was not acknowledged
[20:27:03.753][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:03.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 98 was not acknowledged
[20:27:03.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 99 was not acknowledged
[20:27:03.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 100 was not acknowledged
[20:27:04.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 101 was not acknowledged
[20:27:04.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 102 was not acknowledged
[20:27:04.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 103 was not acknowledged
[20:27:04.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 104 was not acknowledged
[20:27:04.253][t:1995077][p:1994723][tgvoip][debug] SIGNAL BAR COUNT CHANGED: 2
[20:27:04.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 105 was not acknowledged
[20:27:04.253][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:04.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 106 was not acknowledged
[20:27:04.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 107 was not acknowledged
[20:27:04.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 108 was not acknowledged
[20:27:04.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 109 was not acknowledged
[20:27:04.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 110 was not acknowledged
[20:27:04.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 111 was not acknowledged
[20:27:04.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 112 was not acknowledged
[20:27:04.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 113 was not acknowledged
[20:27:04.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 114 was not acknowledged
[20:27:04.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 115 was not acknowledged
[20:27:04.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 116 was not acknowledged
[20:27:04.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 117 was not acknowledged
[20:27:05.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 118 was not acknowledged
[20:27:05.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 119 was not acknowledged
[20:27:05.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 120 was not acknowledged
[20:27:05.253][t:1995077][p:1994723][tgvoip][debug] SIGNAL BAR COUNT CHANGED: 1
[20:27:05.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 121 was not acknowledged
[20:27:05.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 122 was not acknowledged
[20:27:05.253][t:1995077][p:1994723][tgvoip][info] UDP ping reply count: 4.00
[20:27:05.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 123 was not acknowledged
[20:27:05.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 124 was not acknowledged
[20:27:05.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 125 was not acknowledged
[20:27:05.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 126 was not acknowledged
[20:27:05.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 127 was not acknowledged
[20:27:05.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 128 was not acknowledged
[20:27:05.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 129 was not acknowledged
[20:27:05.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 130 was not acknowledged
[20:27:05.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 131 was not acknowledged
[20:27:05.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 132 was not acknowledged
[20:27:05.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 133 was not acknowledged
[20:27:05.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 134 was not acknowledged
[20:27:06.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 135 was not acknowledged
[20:27:06.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 136 was not acknowledged
[20:27:06.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 137 was not acknowledged
[20:27:06.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 138 was not acknowledged
[20:27:06.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 139 was not acknowledged
[20:27:06.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 140 was not acknowledged
[20:27:06.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 141 was not acknowledged
[20:27:06.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 142 was not acknowledged
[20:27:06.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 143 was not acknowledged
[20:27:06.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 144 was not acknowledged
[20:27:06.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 145 was not acknowledged
[20:27:06.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 146 was not acknowledged
[20:27:06.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 147 was not acknowledged
[20:27:06.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 148 was not acknowledged
[20:27:06.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 149 was not acknowledged
[20:27:06.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 150 was not acknowledged
[20:27:07.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 151 was not acknowledged
[20:27:07.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 152 was not acknowledged
[20:27:07.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 153 was not acknowledged
[20:27:07.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 154 was not acknowledged
[20:27:07.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 155 was not acknowledged
[20:27:07.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 156 was not acknowledged
[20:27:07.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 157 was not acknowledged
[20:27:07.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 158 was not acknowledged
[20:27:07.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 159 was not acknowledged
[20:27:07.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 160 was not acknowledged
[20:27:07.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 161 was not acknowledged
[20:27:07.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 162 was not acknowledged
[20:27:07.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 163 was not acknowledged
[20:27:07.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 164 was not acknowledged
[20:27:07.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 165 was not acknowledged
[20:27:07.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 166 was not acknowledged
[20:27:07.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 167 was not acknowledged
[20:27:08.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 168 was not acknowledged
[20:27:08.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 169 was not acknowledged
[20:27:08.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 170 was not acknowledged
[20:27:08.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 171 was not acknowledged
[20:27:08.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 172 was not acknowledged
[20:27:08.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 173 was not acknowledged
[20:27:08.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 174 was not acknowledged
[20:27:08.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 175 was not acknowledged
[20:27:08.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 176 was not acknowledged
[20:27:08.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 177 was not acknowledged
[20:27:08.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 178 was not acknowledged
[20:27:08.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 179 was not acknowledged
[20:27:08.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 180 was not acknowledged
[20:27:08.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 181 was not acknowledged
[20:27:08.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 182 was not acknowledged
[20:27:08.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 183 was not acknowledged
[20:27:08.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 184 was not acknowledged
[20:27:09.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 185 was not acknowledged
[20:27:09.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 186 was not acknowledged
[20:27:09.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 187 was not acknowledged
[20:27:09.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 188 was not acknowledged
[20:27:09.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 189 was not acknowledged
[20:27:09.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 190 was not acknowledged
[20:27:09.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 191 was not acknowledged
[20:27:09.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 192 was not acknowledged
[20:27:09.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 193 was not acknowledged
[20:27:09.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 194 was not acknowledged
[20:27:09.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 195 was not acknowledged
[20:27:09.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 196 was not acknowledged
[20:27:09.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 197 was not acknowledged
[20:27:09.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 198 was not acknowledged
[20:27:09.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 199 was not acknowledged
[20:27:09.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 200 was not acknowledged
[20:27:10.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 201 was not acknowledged
[20:27:10.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 202 was not acknowledged
[20:27:10.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 203 was not acknowledged
[20:27:10.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 204 was not acknowledged
[20:27:10.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 205 was not acknowledged
[20:27:10.253][t:1995076][p:1994723][tgvoip][info] Received a valid packet while reconnecting - setting state to established
[20:27:12.252][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.9.118: 0.086, average: 0.086
[20:27:12.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 238 was not acknowledged
[20:27:12.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 239 was not acknowledged
[20:27:12.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 241 was not acknowledged
[20:27:12.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 242 was not acknowledged
[20:27:12.479][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.13.40: 0.312, average: 0.312
[20:27:12.519][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.17.26: 0.352, average: 0.352
[20:27:14.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 280 was not acknowledged
[20:27:14.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 281 was not acknowledged
[20:27:14.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 282 was not acknowledged
[20:27:14.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 283 was not acknowledged
[20:27:14.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 284 was not acknowledged
[20:27:14.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 285 was not acknowledged
[20:27:14.753][t:1995077][p:1994723][tgvoip][info] Resetting UDP availability
[20:27:14.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 286 was not acknowledged
[20:27:14.753][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:14.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 287 was not acknowledged
[20:27:14.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 288 was not acknowledged
[20:27:14.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 289 was not acknowledged
[20:27:14.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 290 was not acknowledged
[20:27:15.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 291 was not acknowledged
[20:27:15.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 292 was not acknowledged
[20:27:15.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 293 was not acknowledged
[20:27:15.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 294 was not acknowledged
[20:27:15.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 295 was not acknowledged
[20:27:15.253][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:15.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 296 was not acknowledged
[20:27:15.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 297 was not acknowledged
[20:27:15.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 298 was not acknowledged
[20:27:15.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 299 was not acknowledged
[20:27:15.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 300 was not acknowledged
[20:27:15.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 301 was not acknowledged
[20:27:15.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 302 was not acknowledged
[20:27:15.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 303 was not acknowledged
[20:27:15.753][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:15.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 304 was not acknowledged
[20:27:15.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 305 was not acknowledged
[20:27:15.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 306 was not acknowledged
[20:27:16.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 307 was not acknowledged
[20:27:16.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 308 was not acknowledged
[20:27:16.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 309 was not acknowledged
[20:27:16.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 310 was not acknowledged
[20:27:16.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 311 was not acknowledged
[20:27:16.253][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:16.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 312 was not acknowledged
[20:27:16.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 313 was not acknowledged
[20:27:16.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 314 was not acknowledged
[20:27:16.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 315 was not acknowledged
[20:27:16.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 316 was not acknowledged
[20:27:16.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 317 was not acknowledged
[20:27:16.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 318 was not acknowledged
[20:27:16.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 319 was not acknowledged
[20:27:16.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 320 was not acknowledged
[20:27:16.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 321 was not acknowledged
[20:27:16.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 322 was not acknowledged
[20:27:16.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 323 was not acknowledged
[20:27:17.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 324 was not acknowledged
[20:27:17.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 325 was not acknowledged
[20:27:17.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 326 was not acknowledged
[20:27:17.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 327 was not acknowledged
[20:27:17.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 328 was not acknowledged
[20:27:17.253][t:1995077][p:1994723][tgvoip][info] UDP ping reply count: 4.00
[20:27:17.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 329 was not acknowledged
[20:27:17.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 330 was not acknowledged
[20:27:17.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 331 was not acknowledged
[20:27:17.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 332 was not acknowledged
[20:27:17.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 333 was not acknowledged
[20:27:17.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 334 was not acknowledged
[20:27:17.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 335 was not acknowledged
[20:27:17.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 336 was not acknowledged
[20:27:17.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 337 was not acknowledged
[20:27:17.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 338 was not acknowledged
[20:27:17.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 339 was not acknowledged
[20:27:17.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 340 was not acknowledged
[20:27:18.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 341 was not acknowledged
[20:27:18.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 342 was not acknowledged
[20:27:18.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 343 was not acknowledged
[20:27:18.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 344 was not acknowledged
[20:27:18.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 345 was not acknowledged
[20:27:18.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 346 was not acknowledged
[20:27:18.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 347 was not acknowledged
[20:27:18.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 348 was not acknowledged
[20:27:18.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 349 was not acknowledged
[20:27:18.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 350 was not acknowledged
[20:27:18.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 351 was not acknowledged
[20:27:18.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 352 was not acknowledged
[20:27:18.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 353 was not acknowledged
[20:27:18.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 354 was not acknowledged
[20:27:18.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 355 was not acknowledged
[20:27:18.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 356 was not acknowledged
[20:27:19.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 357 was not acknowledged
[20:27:19.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 358 was not acknowledged
[20:27:19.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 359 was not acknowledged
[20:27:19.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 360 was not acknowledged
[20:27:19.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 361 was not acknowledged
[20:27:19.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 362 was not acknowledged
[20:27:19.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 363 was not acknowledged
[20:27:19.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 364 was not acknowledged
[20:27:19.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 365 was not acknowledged
[20:27:19.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 366 was not acknowledged
[20:27:19.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 367 was not acknowledged
[20:27:19.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 368 was not acknowledged
[20:27:19.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 369 was not acknowledged
[20:27:19.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 370 was not acknowledged
[20:27:19.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 371 was not acknowledged
[20:27:19.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 372 was not acknowledged
[20:27:19.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 373 was not acknowledged
[20:27:20.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 374 was not acknowledged
[20:27:20.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 375 was not acknowledged
[20:27:20.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 376 was not acknowledged
[20:27:20.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 377 was not acknowledged
[20:27:20.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 378 was not acknowledged
[20:27:20.258][t:1995076][p:1994723][tgvoip][info] Received a valid packet while reconnecting - setting state to established
[20:27:22.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 411 was not acknowledged
[20:27:22.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 413 was not acknowledged
[20:27:22.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 414 was not acknowledged
[20:27:22.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 416 was not acknowledged
[20:27:22.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 418 was not acknowledged
[20:27:22.553][t:1995077][p:1994723][tgvoip][info] Resetting UDP availability
[20:27:22.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 419 was not acknowledged
[20:27:22.553][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:22.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 420 was not acknowledged
[20:27:22.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 421 was not acknowledged
[20:27:22.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 422 was not acknowledged
[20:27:22.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 423 was not acknowledged
[20:27:22.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 424 was not acknowledged
[20:27:22.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 425 was not acknowledged
[20:27:22.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 426 was not acknowledged
[20:27:23.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 427 was not acknowledged
[20:27:23.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 428 was not acknowledged
[20:27:23.053][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:23.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 429 was not acknowledged
[20:27:23.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 430 was not acknowledged
[20:27:23.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 431 was not acknowledged
[20:27:23.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 432 was not acknowledged
[20:27:23.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 433 was not acknowledged
[20:27:23.453][t:1995077][p:1994723][tgvoip][debug] Packet with seq 434 was not acknowledged
[20:27:23.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 435 was not acknowledged
[20:27:23.553][t:1995077][p:1994723][tgvoip][debug] Packet with seq 436 was not acknowledged
[20:27:23.553][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:23.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 437 was not acknowledged
[20:27:23.653][t:1995077][p:1994723][tgvoip][debug] Packet with seq 438 was not acknowledged
[20:27:23.753][t:1995077][p:1994723][tgvoip][debug] Packet with seq 439 was not acknowledged
[20:27:23.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 440 was not acknowledged
[20:27:23.853][t:1995077][p:1994723][tgvoip][debug] Packet with seq 441 was not acknowledged
[20:27:23.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 442 was not acknowledged
[20:27:23.953][t:1995077][p:1994723][tgvoip][debug] Packet with seq 443 was not acknowledged
[20:27:24.053][t:1995077][p:1994723][tgvoip][debug] Packet with seq 444 was not acknowledged
[20:27:24.053][t:1995077][p:1994723][tgvoip][warning] Send udp pings
[20:27:24.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 445 was not acknowledged
[20:27:24.153][t:1995077][p:1994723][tgvoip][debug] Packet with seq 446 was not acknowledged
[20:27:24.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 447 was not acknowledged
[20:27:24.253][t:1995077][p:1994723][tgvoip][debug] Packet with seq 448 was not acknowledged
[20:27:24.254][t:1995076][p:1994723][tgvoip][info] Received a valid packet while reconnecting - setting state to established
[20:27:24.254][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.9.118: 0.087, average: 0.087
[20:27:24.353][t:1995077][p:1994723][tgvoip][debug] Packet with seq 449 was not acknowledged
[20:27:24.480][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.13.40: 0.313, average: 0.312
[20:27:24.518][t:1995076][p:1994723][tgvoip][debug] Current RTT via 91.108.17.26: 0.351, average: 0.352
[20:27:25.053][t:1995077][p:1994723][tgvoip][info] UDP ping reply count: 4.00
[20:27:25.900][t:1994726][p:1994723][pjsip][debug] RX 442 bytes Request msg BYE/cseq=5439 (rdata0x6dda20023ba8) from UDP 127.0.0.1:5060:

Audio wokring

[20:24:49.606][t:1994723][p:1994723][core][debug] [1994723-1] creating voip for TG #1
[20:24:49.606][t:1994723][p:1994723][tgvoip][info] update data saving mode, config 0, enabled 0, reqd by peer 0
[20:24:49.606][t:1994723][p:1994723][tgvoip][warning] Set remote endpoints, allowP2P=0, connectionMaxLayer=92
[20:24:49.606][t:1994723][p:1994723][tgvoip][warning] Starting voip controller
[20:24:49.606][t:1994723][p:1994723][tgvoip][debug] Bound to local UDP port 26402
[20:24:49.606][t:1994795][p:1994723][tgvoip][info] Receive thread starting
[20:24:49.606][t:1994723][p:1994723][core][debug] [1994723-1] answering SIP #0
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Answering call 0: code=200
[20:24:49.607][t:1994797][p:1994723][tgvoip][info] before create audio io
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] SDP negotiation done: Success
[20:24:49.607][t:1994797][p:1994723][tgvoip][info] AEC: 0 NS: 0 AGC: 0
[20:24:49.607][t:1994796][p:1994723][tgvoip][warning] Send udp pings
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Call 0: updating media..
[20:24:49.607][t:1994797][p:1994723][tgvoip][info] Audio initialization took 0.000214 seconds
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Media stream call00:0 is destroyed
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] UDP media transport started
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Audio channel update..
[20:24:49.607][t:1994797][p:1994723][tgvoip][info] === send thread exiting ===
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Initialize Opus encoder, sample rate: 48000, ch: 1, avg bitrate: 0(auto), vad: 1, plc: 1, pkt loss: 5, complexity: 5, constant bit rate: 1
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Modifying Opus encoder, sample rate: 48000, ch: 1, avg bitrate: 0(auto), vad: 0, plc: 1, pkt loss: 5, complexity: 5, constant bit rate: 0
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] VAD temporarily disabled
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] UDP media transport attached
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Encoder stream started
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Decoder stream started
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Audio updated, stream #0: opus (sendrecv)
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] TX 876 bytes Response msg 200/INVITE/cseq=11416 (tdta0x984039d3878) to UDP 127.0.0.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 127.0.0.1:5060;rport=5060;received=127.0.0.1;branch=z9hG4bKPj94f7d363-2164-41a5-a1a1-a12a3e168015
Call-ID: 329a6e8d-b049-4248-a284-2abbc7fccad3
From: <sip:telegram@127.0.0.1>;tag=27982595-834a-4d03-be19-c6d5afbef9a8
To: <sip:+XXXXXXXXXXXX@127.0.0.1>;tag=ee573dc2-b971-464b-a905-88e3113f81e0
CSeq: 11416 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Contact: <sip:telegram@127.0.0.1:5566>
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length:   293

v=0
o=- 3886518286 3886518287 IN IP4 192.168.1.6
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVPF 107
c=IN IP4 192.168.1.6
b=TIAS:96000
a=rtcp:4001 IN IP4 192.168.1.6
a=sendrecv
a=ssrc:2145002816 cname:68d5ec8b4ffacb4c
a=rtpmap:107 opus/48000/2
a=fmtp:107 useinbandfec=1

--end msg--
[20:24:49.607][t:1994723][p:1994723][core][debug] [1994723-1] bridging tgvoip audio with SIP#0
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Switch connect: 3 --> 1
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Set sound device: capture=-99, playback=-99, mode=0, use_default_settings=0
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Null sound device, mode setting is ignored
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Setting null sound device..
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Opening null sound device..
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Port 3 (sip:telegram@127.0.0.1) transmitting to port 1 (input)
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Switch connect: 2 --> 3
[20:24:49.607][t:1994723][p:1994723][pjsip][debug] Port 2 (output) transmitting to port 3 (sip:telegram@127.0.0.1)
[20:24:49.608][t:1994726][p:1994723][pjsip][debug] RX 419 bytes Request msg ACK/cseq=11416 (rdata0x6dda200384f8) from UDP 127.0.0.1:5060:
ACK sip:telegram@127.0.0.1:5566 SIP/2.0
Via: SIP/2.0/UDP 127.0.0.1:5060;rport;branch=z9hG4bKPjf7b6fd77-2fdd-42a9-8ac0-40fa0d842c83
From: <sip:telegram@127.0.0.1>;tag=27982595-834a-4d03-be19-c6d5afbef9a8
To: <sip:+XXXXXXXXXXXX@127.0.0.1>;tag=ee573dc2-b971-464b-a905-88e3113f81e0
Call-ID: 329a6e8d-b049-4248-a284-2abbc7fccad3
CSeq: 11416 ACK
Max-Forwards: 70
User-Agent: Asterisk PBX 20.1.0
Content-Length:  0


--end msg--
[20:24:49.616][t:1994798][p:1994723][pjsip][debug] Resetting jitter buffer in stream playback start
[20:24:49.645][t:1994795][p:1994723][tgvoip][info] Detected IPv4 connectivity, will not try IPv6
[20:24:50.059][t:1994795][p:1994723][tgvoip][debug] Received init
[20:24:50.059][t:1994795][p:1994723][tgvoip][info] Peer version is 9
[20:24:50.059][t:1994795][p:1994723][tgvoip][info] Sending init ack
[20:24:50.107][t:1994796][p:1994723][tgvoip][warning] Send udp pings
[20:24:50.167][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 1
[20:24:50.193][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 1
[20:24:50.247][t:1994798][p:1994723][pjsip][debug] Modifying Opus encoder, sample rate: 48000, ch: 1, avg bitrate: 0(auto), vad: 1, plc: 1, pkt loss: 5, complexity: 5, constant bit rate: 0
[20:24:50.247][t:1994798][p:1994723][pjsip][debug] VAD re-enabled
[20:24:50.296][t:1994795][p:1994723][tgvoip][debug] Received init ack
[20:24:50.296][t:1994795][p:1994723][tgvoip][info] peer version from init ack 9
[20:24:50.296][t:1994795][p:1994723][tgvoip][info] jitter: set min packet count 2
[20:24:50.296][t:1994795][p:1994723][tgvoip][info] Audio I/O ready
[20:24:50.296][t:1994801][p:1994723][tgvoip][info] decoder: packets per frame 3
[20:24:50.565][t:1994795][p:1994723][tgvoip][debug] Received init
[20:24:50.565][t:1994795][p:1994723][tgvoip][info] Peer version is 9
[20:24:50.565][t:1994795][p:1994723][tgvoip][info] Sending init ack
[20:24:50.607][t:1994796][p:1994723][tgvoip][warning] Send udp pings
[20:24:50.673][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 3
[20:24:50.700][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 3
[20:24:51.071][t:1994795][p:1994723][tgvoip][debug] Received init
[20:24:51.071][t:1994795][p:1994723][tgvoip][info] Peer version is 9
[20:24:51.071][t:1994795][p:1994723][tgvoip][info] Sending init ack
[20:24:51.107][t:1994796][p:1994723][tgvoip][warning] Send udp pings
[20:24:51.179][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 4
[20:24:51.205][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 4
[20:24:51.428][t:1994795][p:1994723][tgvoip][debug] Received init ack
[20:24:51.428][t:1994795][p:1994723][tgvoip][debug] Received init ack
[20:24:51.573][t:1994795][p:1994723][tgvoip][debug] Received init
[20:24:51.573][t:1994795][p:1994723][tgvoip][info] Peer version is 9
[20:24:51.573][t:1994795][p:1994723][tgvoip][info] Sending init ack
[20:24:51.674][t:1994795][p:1994723][tgvoip][warning] First audio packet - setting state to ESTABLISHED
[20:24:51.674][t:1994795][p:1994723][tgvoip][info] jitter: resyncing, next timestamp = -120 (step=60, minDelay=2.000000)
[20:24:51.681][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 7
[20:24:51.690][t:1994795][p:1994723][tgvoip][debug] Received init ack
[20:24:51.707][t:1994795][p:1994723][tgvoip][warning] Received duplicated packet for seq 7
[20:24:52.107][t:1994796][p:1994723][tgvoip][info] UDP ping reply count: 4.00
[20:24:52.497][t:1994801][p:1994723][tgvoip][warning] jitter: lost 20 packets in a row, resetting
[20:24:52.558][t:1994795][p:1994723][tgvoip][info] jitter: resyncing, next timestamp = 600 (step=60, minDelay=2.000000)
[20:24:52.796][t:1994796][p:1994723][tgvoip][debug] SIGNAL BAR COUNT CHANGED: 4
[20:24:53.692][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.085, average: 0.085
[20:24:53.910][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.303, average: 0.303
[20:24:53.954][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.347, average: 0.347
[20:25:05.691][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.084, average: 0.085
[20:25:05.909][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.302, average: 0.302
[20:25:05.955][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.348, average: 0.348
[20:25:17.689][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.082, average: 0.084
[20:25:17.909][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.302, average: 0.302
[20:25:17.953][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.346, average: 0.347
[20:25:25.857][t:1994801][p:1994723][tgvoip][warning] jitter: lost 20 packets in a row, resetting
[20:25:25.879][t:1994795][p:1994723][tgvoip][info] jitter: resyncing, next timestamp = 33660 (step=60, minDelay=2.000000)
[20:25:29.596][t:1994795][p:1994723][tgvoip][error] ProcessExtraData
[20:25:29.689][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.082, average: 0.083
[20:25:29.909][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.302, average: 0.302
[20:25:29.952][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.345, average: 0.347
[20:25:41.688][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.081, average: 0.083
[20:25:41.910][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.303, average: 0.302
[20:25:41.953][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.346, average: 0.347
[20:25:53.689][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.082, average: 0.083
[20:25:53.909][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.302, average: 0.302
[20:25:53.953][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.346, average: 0.347
[20:26:01.039][t:1994795][p:1994723][tgvoip][error] ProcessExtraData
[20:26:05.687][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.080, average: 0.082
[20:26:05.909][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.302, average: 0.302
[20:26:05.954][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.347, average: 0.346
[20:26:08.476][t:1994795][p:1994723][tgvoip][error] ProcessExtraData
[20:26:15.688][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.9.105: 0.081, average: 0.081
[20:26:15.909][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.13.23: 0.302, average: 0.302
[20:26:15.952][t:1994795][p:1994723][tgvoip][debug] Current RTT via 91.108.17.55: 0.345, average: 0.346
[20:26:22.861][t:1994795][p:1994723][tgvoip][error] ProcessExtraData
[20:26:23.717][t:1994801][p:1994723][tgvoip][warning] jitter: lost 20 packets in a row, resetting
[20:26:23.774][t:1994795][p:1994723][tgvoip][info] jitter: resyncing, next timestamp = 45180 (step=60, minDelay=2.000000)
[20:26:25.904][t:1994726][p:1994723][pjsip][debug] RX 443 bytes Request msg BYE/cseq=11417 (rdata0x6dda20027b48) from UDP 127.0.0.1:5060:
@multi
Copy link
Author

multi commented Mar 10, 2023 •
edited
Loading

Also, it doesn't work with the pre-built binaries from here. Tried with universal 1.3.0

@multi
Copy link
Author

multi commented Mar 10, 2023

@oksakhartman

  • iOS (latest os and tg app version) - that's the first leg, I'm trying to telegram call it from tg2sip
  • the second leg - Linphone (macOS, latest again), running on the same local network Asterisk
  • calls are working fine on the iOS tg app - Tested by calling friends. Also few of the Linphone calls succeeds, and there was audio :)

Not sure if related, but

pjproject-git 2.13.r79.gf60d1c4-1 - built with

#define PJMEDIA_CONF_USE_SWITCH_BOARD 1
#define PJMEDIA_CONF_SWITCH_BOARD_BUF_SIZE 2000

I can paste the whole config_site.h if needed ...

Here's the configure command

./configure \
    --prefix=/usr \
    --enable-epoll \
    --with-external-speex \
    --with-external-srtp \
    --with-external-gsm \
    --disable-video \
    --disable-sound \
    --enable-shared

@multi
Copy link
Author

multi commented Mar 11, 2023 •
edited
Loading

@oksakhartman I've managed to test some scenarios :)

A - tg2sip (and Asterisk)
B - iOS, latest tg
C - iOS, linphone
D - Android, latest tg
E - Android, latest tg X
F - macos, latest tg client
G - macos, chrome web z
H - macos, linphone

H -> A -> B - no audio
B -> A -> H - no audio
H -> A -> D - works
H -> A -> E - works
C -> A -> H - works
C -> A -> G - can't answer, the web client says "You need to upgrade to latest version..." From there I've downloaded F (macos tg client)
C -> A -> F - works (audio connects after 2-3 seconds delay)
D -> A -> H - works
E -> A -> H - works
C -> A -> D - works
C -> A -> E - works
E -> B - no audio (emojis match)
D -> B - works
G -> B - works
F -> B - works

Not sure, why few of the calls had audio (also, 7-8 in a row), between H -> A -> B ? I'm starting to suspect, that if the call gets "routed" through "udp reflector" that "accepts" version 2.4.4 - it works :/

Here are the changes that I've made to get branch feature/libtgvoip-update building without errors

using tdlib commit id b3ab664a18f8611f4dfcd3054717504271eeaa7a (few commits after that, the lib apis changes and doesn't work anymore with the implementation here in tg2sip)

diff --git a/CMakeLists.txt b/CMakeLists.txt
index 8507dbe..9d76659 100644
--- a/CMakeLists.txt
+++ b/CMakeLists.txt
@@ -7,7 +7,7 @@ add_subdirectory(libtgvoip)
 
 find_package(PkgConfig REQUIRED)
 find_package(Threads REQUIRED)
-find_package(Td 1.7.10 REQUIRED)
+find_package(Td 1.8.0 REQUIRED)
 find_package(spdlog 0.17 REQUIRED)
 pkg_check_modules(PJSIP libpjproject>=2.8 REQUIRED)
 pkg_check_modules(OPUS opus REQUIRED)
@@ -57,4 +57,4 @@ target_include_directories(gen_db PRIVATE
         include)
 
 target_link_libraries(gen_db PRIVATE
-        Td::TdStatic)
\ No newline at end of file
+        Td::TdStatic)
diff --git a/tg2sip/queue.h b/tg2sip/queue.h
index bfe1fa0..fb98706 100755
--- a/tg2sip/queue.h
+++ b/tg2sip/queue.h
@@ -21,6 +21,7 @@
 #include <mutex>
 #include <queue>
 #include <condition_variable>
+#include <optional>
 
 template<typename T>
 class OptionalQueue {
diff --git a/tg2sip/sip.cpp b/tg2sip/sip.cpp
index 06672a6..8dccc9d 100755
--- a/tg2sip/sip.cpp
+++ b/tg2sip/sip.cpp
@@ -185,10 +185,10 @@ void Client::init_pj_endpoint(Settings &settings, LogWriter *sip_log_writer) {
     // and TG audio port clock rate so we MUST force
     // using 48kHz codecs for all SIP calls
     std::string codecId = settings.raw_pcm() ? "L16/48000/1" : "opus/48000/2";
-    CodecInfoVector codecVector = ep.codecEnum();
+    CodecInfoVector2 codecVector = ep.codecEnum2();
 
-    for (auto const &value : codecVector) {
-        ep.codecSetPriority(value->codecId, (pj_uint8_t) (value->codecId == codecId ? 255 : 0));
+    for (auto const value : codecVector) {
+        ep.codecSetPriority(value.codecId, (pj_uint8_t) (value.codecId == codecId ? 255 : 0));
     }
 
     TransportConfig t_cfg;
@@ -309,4 +309,4 @@ void Client::DialDtmf(pjsua_call_id call_id, const string &dtmf_digits) {
 
     auto call = it->second;
     call->dialDtmf(dtmf_digits);
-}
\ No newline at end of file
+}
diff --git a/tg2sip/utils.cpp b/tg2sip/utils.cpp
index c8da02b..60b5df9 100644
--- a/tg2sip/utils.cpp
+++ b/tg2sip/utils.cpp
@@ -15,7 +15,8 @@
  * along with this program; If not, see <https://www.gnu.org/licenses/>.
  */
 
+#include <regex>
 #include <algorithm>
 #include "utils.h"
 
-bool is_digits(const std::string &str) { return std::all_of(str.begin(), str.end(), ::isdigit); };
\ No newline at end of file
+bool is_digits(const std::string &str) { return std::regex_search(str, std::regex( "\\d+" )); };

@multi
Copy link
Author

multi commented Mar 11, 2023 •
edited
Loading

@oksakhartman Yup, few calls had audio. Maybe 10 out of ~200 :) Also, one time it worked for 5-6 calls made in a row, then after restarting tg2sip doesn't worked anymore (still not worked from that time) :/

Looks like a bug, yeah ... If it was a "rejection" from deprecation, it shouldn't worked at all :)

@multi
Copy link
Author

multi commented Mar 12, 2023

@oksakhartman

I switched to a fork on webrtc

Can you elaborate more on that? Where, how, any hints/tips/steps would be helpful! :)

@AlexHighTower
Copy link

I switched to a fork on webrtc

I will be grateful if you provide compiled x64 binaries for tests

@French1979
Copy link

Can you elaborate more on that? Where, how, any hints/tips/steps would be helpful! :)

https://github.com/TelegramMessenger/Telegram-iOS/releases/tag/build-25451

hi i see some fixes with calls here maybe ios client is fixed now

you can try test it if you want)

We tested - no voice :(

@French1979
Copy link

We tested - no voice :(

https://github.com/TelegramMessenger/Telegram-iOS/releases/tag/build-25780

No voice :(

@French1979
Copy link

French1979 commented Oct 19, 2023 •
edited
Loading

No voice :(

вроде уже работает на айфонах

Что значит вроде, вы тестировали?

@French1979
Copy link

No voice :(

вроде уже работает на айфонах

Протестировали, голоса нету.

Repository owner deleted a comment Dec 17, 2023
# for free to join this conversation on GitHub. Already have an account? # to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@multi @AlexHighTower @French1979