Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

Audio is not connected with Dahua VTO3221D SIP server / Dahua VTO1201G-P SIP intercom: Unsupported media type (PJMEDIA_EUNSUPMEDIATYPE) #2823

Closed
altserg opened this issue Sep 10, 2021 · 5 comments

Comments

@altserg
Copy link

altserg commented Sep 10, 2021

Describe the bug

No audio is connected after SIP call from Dahua VTO 3221D is initiated. Log shows Unsupported media type (PJMEDIA_EUNSUPMEDIATYPE).

The behavior is the same in pjsip app (see log), and my custom Python app. Changing codec priority does not help. The Linux host has sound disabled, so the set_null_snd_dev() function is called in Python app. Looks like that pjsip and Dahua devices cannot negotiate PCMU/8000 codec. However, both support this codec (see log)

Calling from one pjsua instance to another pjsua instance via Dahua VTO3221D SIP server works fine (negotiated codec is L16/16000)

Extract from the log file:
10.09.2021 01:02:32 [DEBUG] pjsua_media.c python worke ..Call 0: initializing media..
10.09.2021 01:02:32 [DEBUG] pjsua_media.c python worke ...RTP socket reachable at 192.168.2.140:40429
10.09.2021 01:02:32 [DEBUG] pjsua_media.c python worke ...RTCP socket reachable at 192.168.2.140:40430
10.09.2021 01:02:32 [DEBUG] pjsua_media.c python worke ...Media index 0 selected for audio call 0
10.09.2021 01:02:44 [DEBUG] pjsua_media.c Voice proces ...Call 0: updating media..
10.09.2021 01:02:44 [DEBUG] pjsua_media.c Voice proces .....Media stream call00:0 is destroyed
10.09.2021 01:02:44 [DEBUG] pjsua_media.c Voice proces ....Audio updated, stream #0: PCMU (sendrecv)
10.09.2021 01:02:44 [DEBUG] pjsua_media.c Voice proces .....Media stream call00:1 is destroyed
10.09.2021 01:02:44 [INFO] pjsua_media.c Voice proces ....Skipped updating media call00:1 (media type=unknown): Unsupported media type (PJMEDIA_EUNSUPMEDIATYPE)

Steps to reproduce

  1. Install Dahua VTO3221D as SIP server at 192.168.2.99 (VTO_GATE)

  2. Install Dahua VTO1201G-P extra SIP intercom at 192.168.98, connect it to the SIP server 192.168.2.99

  3. On Raspberry PI 192.168.2.140:
    Run ./pjsua --registrar="sip:VTO_GATE"
    --id="sip:1000#2@192.168.2.140"
    --realm="VDP"
    --username="XXX"
    --password="XXX"
    --log-file="log.txt"
    --log-level=6
    --app-log-level=3
    --null-audio
    --play-file="test.wav"
    --auto-play
    --rec-file="rec.wav"
    --auto-rec

  4. Initiate call from Dahua VTO3221D or from Dahua VTO1201G-P to number 1000#2

  5. Answer call in pjsip

  6. Reproduce the issue. The call newer switches to CONFIRMED state

PJSIP version

2.11.1

Context

Raspberry PI 4, Debian/Buster
pjsip test app, own custom Python3 app using pjsua

Log, call stack, etc

pjsip test app log:
17:22:46.296 sip_endpoint.c  .Module "mod-pjsua-log" registered
17:22:46.296 sip_endpoint.c  .Module "mod-tsx-layer" registered
17:22:46.296 sip_endpoint.c  .Module "mod-stateful-util" registered
17:22:46.296 sip_endpoint.c  .Module "mod-ua" registered
17:22:46.296 sip_endpoint.c  .Module "mod-100rel" registered
17:22:46.296 sip_endpoint.c  .Module "mod-pjsua" registered
17:22:46.296 sip_endpoint.c  .Module "mod-invite" registered
17:22:46.296          pjlib  ..select() I/O Queue created (0xc69944)
17:22:46.297   conference.c  ..Creating conference bridge with 254 ports
17:22:46.297   Master/sound  ..Using delay buffer with WSOLA.
17:22:46.306 sip_endpoint.c  .Module "mod-evsub" registered
17:22:46.306 sip_endpoint.c  .Module "mod-presence" registered
17:22:46.306        evsub.c  .Event pkg "presence" registered by mod-presence
17:22:46.306 sip_endpoint.c  .Module "mod-mwi" registered
17:22:46.306        evsub.c  .Event pkg "message-summary" registered by mod-mwi
17:22:46.306 sip_endpoint.c  .Module "mod-refer" registered
17:22:46.306        evsub.c  .Event pkg "refer" registered by mod-refer
17:22:46.306 sip_endpoint.c  .Module "mod-pjsua-pres" registered
17:22:46.307 sip_endpoint.c  .Module "mod-pjsua-im" registered
17:22:46.307 sip_endpoint.c  .Module "mod-pjsua-options" registered
17:22:46.307   pjsua_core.c  .1 SIP worker threads created
17:22:46.307   pjsua_core.c  .pjsua version 2.11.1 for Linux-5.10.60/armv7l/glibc-2.28 initialized
17:22:46.307   pjsua_core.c  .PJSUA state changed: CREATED --> INIT
17:22:46.307 sip_endpoint.c  Module "mod-default-handler" registered
17:22:46.307    pjsua_aud.c  Creating file player: test.wav..
17:22:46.307   wav_player.c  .File player 'test.wav' created: samp.rate=16000, ch=1, bufsize=4KB, filesize=112KB
17:22:46.307    pjsua_aud.c  .Player created, id=0, slot=1
17:22:46.307    pjsua_aud.c  Creating recorder rec.wav..
17:22:46.307   wav_writer.c  .File writer 'rec.wav' created: samp.rate=16000, bufsize=4KB
17:22:46.307    pjsua_aud.c  .Recorder created, id=0, slot=2
17:22:46.308   pjsua_core.c  SIP UDP socket reachable at 192.168.2.140:10000
17:22:46.308    udp0xc399f8  SIP UDP transport started, published address is 192.168.2.140:10000
17:22:46.308    pjsua_acc.c  Adding account: id=<sip:192.168.2.140:10000>
17:22:46.308    pjsua_acc.c  .Account <sip:192.168.2.140:10000> added with id 0
17:22:46.308    pjsua_acc.c  Modifying account 0
17:22:46.308    pjsua_acc.c  Acc 0: setting online status to 1..
17:22:46.309    tcptp:10000  SIP TCP listener ready for incoming connections at 192.168.2.140:10000
17:22:46.309    pjsua_acc.c  Adding account: id=<sip:192.168.2.140:10000;transport=TCP>
17:22:46.309    pjsua_acc.c  .Account <sip:192.168.2.140:10000;transport=TCP> added with id 1
17:22:46.309    pjsua_acc.c  Modifying account 1
17:22:46.309    pjsua_acc.c  Acc 1: setting online status to 1..
17:22:46.309    pjsua_acc.c  Adding account: id=sip:1000#2@192.168.2.140
17:22:46.309    pjsua_acc.c  .Account sip:1000#2@192.168.2.140 added with id 2
17:22:46.309    pjsua_acc.c  .Acc 2: setting registration..
17:22:46.309       endpoint  ..Request msg REGISTER/cseq=12457 (tdta0xc89344) created.
17:22:46.310    tsx0xc8a34c  ...Transaction created for Request msg REGISTER/cseq=12458 (tdta0xc89344)
17:22:46.310    tsx0xc8a34c  ..Sending Request msg REGISTER/cseq=12458 (tdta0xc89344) in state Null
17:22:46.310  sip_resolve.c  ...DNS resolver not available, target 'VTO_GATE:0' type=Unspecified will be resolved with getaddrinfo()
17:22:46.310  sip_resolve.c  ...Target 'VTO_GATE:0' type=Unspecified resolved to '192.168.2.99:5060' type=UDP (UDP transport)
17:22:46.310   pjsua_core.c  ...TX 555 bytes Request msg REGISTER/cseq=12458 (tdta0xc89344) to UDP 192.168.2.99:5060:
REGISTER sip:VTO_GATE SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPjGYdu9dJX-meR63YV.61XHXEDoVPr7gCg
Max-Forwards: 70
From: <sip:1000%232@192.168.2.140>;tag=TcgyAYJeudVFhQwsx0IupgPhdeUViSX3
To: <sip:1000%232@192.168.2.140>
Call-ID: LvBj.icgKQHApzwZDiHJs8tIKFiJlK7E
CSeq: 12458 REGISTER
User-Agent: PJSUA v2.11.1 Linux-5.10.60/armv7l/glibc-2.28
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length:  0


--end msg--
17:22:46.310    tsx0xc8a34c  ...State changed from Null to Calling, event=TX_MSG
17:22:46.310    pjsua_acc.c  ..Acc 2: Registration sent
17:22:46.310    pjsua_acc.c  Acc 2: setting online status to 1..
17:22:46.310    pjsua_aud.c  Setting null sound device..
17:22:46.310    pjsua_app.c  .Turning sound device -99 -99 ON
17:22:46.310    pjsua_aud.c  .Opening null sound device..
17:22:46.310   pjsua_core.c  PJSUA state changed: INIT --> STARTING
17:22:46.311 sip_endpoint.c  .Module "mod-unsolicited-mwi" registered
17:22:46.311   pjsua_core.c  .PJSUA state changed: STARTING --> RUNNING
17:22:46.311         main.c  Ready: Success
17:22:46.339 sip_endpoint.c !Processing incoming message: Response msg 401/REGISTER/cseq=12458 (rdata0xc8570c)
17:22:46.339   pjsua_core.c  .RX 463 bytes Response msg 401/REGISTER/cseq=12458 (rdata0xc8570c) from UDP 192.168.2.99:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.2.140:10000;rport=10000;branch=z9hG4bKPjGYdu9dJX-meR63YV.61XHXEDoVPr7gCg
From: <sip:1000%232@192.168.2.140>;tag=TcgyAYJeudVFhQwsx0IupgPhdeUViSX3
To: <sip:1000%232@192.168.2.140>;tag=112486828
Call-ID: LvBj.icgKQHApzwZDiHJs8tIKFiJlK7E
CSeq: 12458 REGISTER
WWW-Authenticate: Digest realm="VDP", nonce="eb5f7028734f3d45fa6c4294a8c2ef5b", algorithm=MD5
User-Agent: Dahua UAS/3.0 VTO3221D
Content-Length: 0


--end msg--
17:22:46.339    tsx0xc8a34c  .Incoming Response msg 401/REGISTER/cseq=12458 (rdata0xc8570c) in state Calling
17:22:46.339    tsx0xc8a34c  ..State changed from Calling to Completed, event=RX_MSG
17:22:46.339  tsx0xb4500c2c  ....Transaction created for Request msg REGISTER/cseq=12459 (tdta0xc89344)
17:22:46.339  tsx0xb4500c2c  ...Sending Request msg REGISTER/cseq=12459 (tdta0xc89344) in state Null
17:22:46.339       endpoint  ....Request msg REGISTER/cseq=12459 (tdta0xc89344): skipping target resolution because address is already set
17:22:46.339   pjsua_core.c  ....TX 731 bytes Request msg REGISTER/cseq=12459 (tdta0xc89344) to UDP 192.168.2.99:5060:
REGISTER sip:VTO_GATE SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPjKN8RzWJvdWsk6NITdSB7NU2COHpBC9jJ
Max-Forwards: 70
From: <sip:1000%232@192.168.2.140>;tag=TcgyAYJeudVFhQwsx0IupgPhdeUViSX3
To: <sip:1000%232@192.168.2.140>
Call-ID: LvBj.icgKQHApzwZDiHJs8tIKFiJlK7E
CSeq: 12459 REGISTER
User-Agent: PJSUA v2.11.1 Linux-5.10.60/armv7l/glibc-2.28
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="1000#2", realm="VDP", nonce="eb5f7028734f3d45fa6c4294a8c2ef5b", uri="sip:VTO_GATE", response="b544814b1606bc4831cce100f6dc8535", algorithm=MD5
Content-Length:  0


--end msg--
17:22:46.339  tsx0xb4500c2c  ....State changed from Null to Calling, event=TX_MSG
17:22:46.379 sip_endpoint.c  Processing incoming message: Response msg 200/REGISTER/cseq=12459 (rdata0xb45013e4)
17:22:46.379   pjsua_core.c  .RX 457 bytes Response msg 200/REGISTER/cseq=12459 (rdata0xb45013e4) from UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.140:10000;rport=10000;branch=z9hG4bKPjKN8RzWJvdWsk6NITdSB7NU2COHpBC9jJ
From: <sip:1000%232@192.168.2.140>;tag=TcgyAYJeudVFhQwsx0IupgPhdeUViSX3
To: <sip:1000%232@192.168.2.140>;tag=1747700622
Call-ID: LvBj.icgKQHApzwZDiHJs8tIKFiJlK7E
CSeq: 12459 REGISTER
Contact: <sip:1000%232@192.168.2.140:10000;ob>
User-Agent: Dahua UAS/3.0 VTO3221D
Expires: 600
Date: Fri,10 Sep 2021 18:22:44 UTC
Content-Length: 0


--end msg--
17:22:46.379  tsx0xb4500c2c  .Incoming Response msg 200/REGISTER/cseq=12459 (rdata0xb45013e4) in state Calling
17:22:46.379  tsx0xb4500c2c  ..State changed from Calling to Completed, event=RX_MSG
17:22:46.379    pjsua_acc.c  ....SIP outbound status for acc 2 is not active
17:22:46.379    pjsua_acc.c  ....sip:1000#2@192.168.2.140: registration success, status=200 (OK), will re-register in 600 seconds
17:22:46.379    pjsua_acc.c  ....Keep-alive timer started for acc 2, destination:192.168.2.99:5060, interval:15s
17:22:47.311    pjsua_aud.c  Closing sound device after idle for 1 second(s)
17:22:47.311    pjsua_app.c  .Turning sound device -99 -99 OFF
17:22:47.311    pjsua_aud.c  .Closing null sound device..
17:22:51.339    tsx0xc8a34c  Timeout timer event
17:22:51.339    tsx0xc8a34c  .State changed from Completed to Terminated, event=TIMER
17:22:51.339    tsx0xc8a34c  Timeout timer event
17:22:51.339    tsx0xc8a34c  .State changed from Terminated to Destroyed, event=TIMER
17:22:51.339    tsx0xc8a34c  Transaction destroyed!
17:22:51.378  tsx0xb4500c2c  Timeout timer event
17:22:51.378  tsx0xb4500c2c  .State changed from Completed to Terminated, event=TIMER
17:22:51.378  tsx0xb4500c2c  Timeout timer event
17:22:51.378  tsx0xb4500c2c  .State changed from Terminated to Destroyed, event=TIMER
17:22:51.378   tdta0xc89344  ..Destroying txdata Request msg REGISTER/cseq=12459 (tdta0xc89344)
17:22:51.378  tsx0xb4500c2c  Transaction destroyed!
17:23:01.380    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:01.380 tdta0xb4503584  Destroying txdata raw
17:23:16.381    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:16.381 tdta0xb4503584  Destroying txdata raw
17:23:31.381    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:31.381 tdta0xb4503584  Destroying txdata raw
17:23:46.381    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:46.381 tdta0xb4503584  Destroying txdata raw
17:24:01.380    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:01.381 tdta0xb4503584  Destroying txdata raw
17:24:16.380    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:16.380 tdta0xb4503584  Destroying txdata raw
17:24:31.380    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:31.381 tdta0xb4503584  Destroying txdata raw
17:24:46.380    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:46.381 tdta0xb4503584  Destroying txdata raw
17:25:01.380    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:25:01.380 tdta0xb4503584  Destroying txdata raw
17:25:16.381    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:25:16.381 tdta0xb4503584  Destroying txdata raw
17:25:31.382    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:25:31.382 tdta0xb4503584  Destroying txdata raw
17:25:46.383    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:25:46.383 tdta0xb4503584  Destroying txdata raw
17:26:01.383    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:26:01.383 tdta0xb4503584  Destroying txdata raw
17:26:16.384    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:26:16.384 tdta0xb4503584  Destroying txdata raw
17:26:31.383    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:26:31.383 tdta0xb4503584  Destroying txdata raw
17:26:46.384    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:26:46.384 tdta0xb4503584  Destroying txdata raw
17:27:01.384    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:27:01.384 tdta0xb4503584  Destroying txdata raw
17:27:16.385    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:27:16.385 tdta0xb4503584  Destroying txdata raw
17:27:31.385    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:27:31.385 tdta0xb4503584  Destroying txdata raw
17:27:46.385    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:27:46.385 tdta0xb4503584  Destroying txdata raw
17:28:01.386    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:28:01.386 tdta0xb4503584  Destroying txdata raw
17:28:16.387    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:28:16.387 tdta0xb4503584  Destroying txdata raw
17:28:31.388    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:28:31.388 tdta0xb4503584  Destroying txdata raw
17:28:46.388    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:28:46.388 tdta0xb4503584  Destroying txdata raw
17:29:01.388    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:29:01.389 tdta0xb4503584  Destroying txdata raw
17:29:16.389    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:29:16.389 tdta0xb4503584  Destroying txdata raw
17:29:18.246 sip_endpoint.c  Processing incoming message: Request msg INVITE/cseq=20 (rdata0xb45013e4)
17:29:18.246   pjsua_core.c  .RX 877 bytes Request msg INVITE/cseq=20 (rdata0xb45013e4) from UDP 192.168.2.99:5060:
INVITE sip:1000%232@192.168.2.140:10000 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.99:5060;rport;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99:5060>
Call-ID: 20210910172918689983810
CSeq: 20 INVITE
Contact: <sip:8002@192.168.2.98:5060>
User-agent: Dahua UAC/3.0 VTO1201G-P V4.400.0.0
Group-Call: 224.0.2.14;30001;30000
Max-Forwards: 68
Content-Type: application/sdp
Content-Length:   315

v=0
o=- 1631294958 1 IN IP4 192.168.2.98
s=Dahua VT 1.5
c=IN IP4 192.168.2.98
t=0 0
m=audio 20000 RTP/AVP 101 0 97
a=rtpmap:97 PCM/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 20001 RTP/AVP 96
a=framerate:25.000000
a=rtpmap:96 H264/90000
a=sendrecv

--end msg--
17:29:18.246   pjsua_call.c  .Incoming Request msg INVITE/cseq=20 (rdata0xb45013e4)
17:29:18.246  tsx0xb45064d4  ...Transaction created for Request msg INVITE/cseq=20 (rdata0xb45013e4)
17:29:18.246  tsx0xb45064d4  ..Incoming Request msg INVITE/cseq=20 (rdata0xb45013e4) in state Null
17:29:18.246  tsx0xb45064d4  ...State changed from Null to Trying, event=RX_MSG
17:29:18.246  dlg0xb4500c2c  ....Transaction tsx0xb45064d4 state changed to Trying
17:29:18.246  dlg0xb4500c2c  ..UAS dialog created
17:29:18.246  dlg0xb4500c2c  ..Module mod-invite added as dialog usage, data=0xb450332c
17:29:18.246  dlg0xb4500c2c  ...Session count inc to 3 by mod-invite
17:29:18.246  inv0xb4500c2c  ..UAS invite session created for dialog dlg0xb4500c2c
17:29:18.246  dlg0xb4500c2c  ...Session count inc to 3 by mod-pjsua
17:29:18.247  pjsua_media.c  ..Call 0: initializing media..
17:29:18.247  pjsua_media.c  ...RTP socket reachable at 192.168.2.140:4000
17:29:18.247  pjsua_media.c  ...RTCP socket reachable at 192.168.2.140:4001
17:29:18.247 srtp0xb450c1a0  ...SRTP keying SDES created
17:29:18.247  pjsua_media.c  ...Media index 0 selected for audio call 0
17:29:18.247  pjsua_media.c  ...Call 0: media transport initialization complete: Success
17:29:18.247  dlg0xb4500c2c  ...Session count dec to 3 by mod-pjsua
17:29:18.248   pjsua_call.c  ..Call 0: remote NAT type is 0 (Unknown)
17:29:18.248       endpoint  ...Response msg 100/INVITE/cseq=20 (tdta0xb45148f4) created
17:29:18.248  dlg0xb4500c2c  ...Initial answer Response msg 100/INVITE/cseq=20 (tdta0xb45148f4)
17:29:18.248  inv0xb4500c2c  ...Sending Response msg 100/INVITE/cseq=20 (tdta0xb45148f4)
17:29:18.248  dlg0xb4500c2c  ....Sending Response msg 100/INVITE/cseq=20 (tdta0xb45148f4)
17:29:18.248  tsx0xb45064d4  ....Sending Response msg 100/INVITE/cseq=20 (tdta0xb45148f4) in state Trying
17:29:18.248   pjsua_core.c  .....TX 379 bytes Response msg 100/INVITE/cseq=20 (tdta0xb45148f4) to UDP 192.168.2.99:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>
CSeq: 20 INVITE
Content-Length:  0


--end msg--
17:29:18.248  tsx0xb45064d4  .....State changed from Trying to Proceeding, event=TX_MSG
17:29:18.248  dlg0xb4500c2c  ......Transaction tsx0xb45064d4 state changed to Proceeding
17:29:18.248    pjsua_aud.c  ..Conf connect: 4 --> 0
17:29:18.248    pjsua_aud.c  ...Set sound device: capture=-99, playback=-99
17:29:18.248    pjsua_aud.c  ....Setting null sound device..
17:29:18.248    pjsua_app.c  .....Turning sound device -99 -99 ON
17:29:18.248    pjsua_aud.c  .....Opening null sound device..
17:29:18.248   conference.c  ...Port 4 (ring) transmitting to port 0 (Master/sound)
17:29:18.248    pjsua_app.c  ..Incoming call for account 2!
Media count: 1 audio & 0 video
From: <sip:8002@192.168.2.99>
To: <sip:1000@192.168.2.99>
Press a to answer or h to reject call
17:29:31.389    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:29:31.389 tdta0xb4503584  Destroying txdata raw
17:29:40.906   pjsua_call.c !Answering call 0: code=200
17:29:40.906 sip_transport.  ..Tx data Response msg 100/INVITE/cseq=20 (tdta0xc89344) cloned
17:29:40.906  inv0xb4500c2c  ..SDP negotiation done: Success
17:29:40.906   pjsua_call.c  ...Call 0: remote NAT type is 0 (Unknown)
17:29:40.906  pjsua_media.c  ...Call 0: updating media..
17:29:40.907  pjsua_media.c  .....Media stream call00:0 is destroyed
17:29:40.907    pjsua_aud.c  ....Audio channel update..
17:29:40.907   strm0xc86124  .....VAD temporarily disabled
17:29:40.907          rtp.c  .....pjmedia_rtp_session_init: ses=0xc8be14, default_pt=0, ssrc=0x1a9bdec5
17:29:40.907          rtp.c  .....pjmedia_rtp_session_init2: ses=0xc8be14, seq=12161, ts=0, peer_ssrc=0
17:29:40.907          rtp.c  .....pjmedia_rtp_session_init: ses=0xc8c49c, default_pt=0, ssrc=0x1a9bdec5
17:29:40.907          rtp.c  .....pjmedia_rtp_session_init2: ses=0xc8c49c, seq=9685, ts=0, peer_ssrc=0
17:29:40.907       stream.c  .....Stream strm0xc86124 created
17:29:40.907   strm0xc86124  .....Encoder stream started
17:29:40.907   strm0xc86124  .....Decoder stream started
17:29:40.907     resample.c  .....resample created: high qualiy, large filter, in/out rate=8000/16000
17:29:40.907     resample.c  .....resample created: high qualiy, large filter, in/out rate=16000/8000
17:29:40.907  pjsua_media.c  ....Audio updated, stream #0: PCMU (sendrecv)
17:29:40.907  pjsua_media.c  .....Media stream call00:1 is destroyed
17:29:40.907  pjsua_media.c  ....Skipped updating media call00:1 (media type=unknown): Unsupported media type (PJMEDIA_EUNSUPMEDIATYPE)
17:29:40.907   strm0xc86124 !Start talksprut..
17:29:40.907    pjsua_app.c !...Call 0 media 0 [type=audio], status is Active
17:29:40.907    pjsua_aud.c  ...Conf disconnect: 4 -x- 0
17:29:40.907   conference.c  ....Port 4 (ring) stop transmitting to port 0 (Master/sound)
17:29:40.907    pjsua_aud.c  ...Conf connect: 5 --> 2
17:29:40.908   conference.c  ....Port 5 (sip:8002@192.168.2.99) transmitting to port 2 (rec.wav)
17:29:40.908    pjsua_aud.c  ...Conf connect: 1 --> 5
17:29:40.908   conference.c  ....Port 1 (test.wav) transmitting to port 5 (sip:8002@192.168.2.99)
17:29:40.908  inv0xb4500c2c  ..Sending Response msg 200/INVITE/cseq=20 (tdta0xc89344)
17:29:40.908  dlg0xb4500c2c  ...Sending Response msg 200/INVITE/cseq=20 (tdta0xc89344)
17:29:40.908  tsx0xb45064d4  ...Sending Response msg 200/INVITE/cseq=20 (tdta0xc89344) in state Proceeding
17:29:40.908 tdta0xb45148f4  ....Destroying txdata Response msg 100/INVITE/cseq=20 (tdta0xb45148f4)
17:29:40.908   pjsua_core.c  ....TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:29:40.908  tsx0xb45064d4  ....State changed from Proceeding to Completed, event=TX_MSG
17:29:40.908  dlg0xb4500c2c  .....Transaction tsx0xb45064d4 state changed to Completed
17:29:40.908    pjsua_app.c  .......Call 0 state changed to CONNECTING
17:29:40.928   strm0xc86124 !Resetting jitter buffer in stream playback start
17:29:40.948   strm0xc86124  Jitter buffer empty (prefetch=0)
17:29:41.408  tsx0xb45064d4 !Retransmit timer event
17:29:41.408  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=0, restart?=1
17:29:41.408   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:29:41.528   strm0xc86124 !VAD re-enabled
17:29:41.528   silencedet.c  Starting talk burst (level=1009 threshold=1000)
17:29:42.408  tsx0xb45064d4 !Retransmit timer event
17:29:42.408  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=1, restart?=1
17:29:42.408   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:29:44.407  tsx0xb45064d4  Retransmit timer event
17:29:44.407  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=2, restart?=1
17:29:44.408   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:29:44.448   wav_player.c !File port test.wav EOF
17:29:44.448   wav_player.c  File port test.wav rewinding..
17:29:46.390    pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:29:46.390 tdta0xb45148f4  Destroying txdata raw
17:29:47.968   wav_player.c !File port test.wav EOF
17:29:47.968   wav_player.c  File port test.wav rewinding..
17:29:48.408  tsx0xb45064d4 !Retransmit timer event
17:29:48.408  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=3, restart?=1
17:29:48.408   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:29:51.488   wav_player.c !File port test.wav EOF
17:29:51.488   wav_player.c  File port test.wav rewinding..
17:29:52.407  tsx0xb45064d4 !Retransmit timer event
17:29:52.407  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=4, restart?=1
17:29:52.407   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:29:55.008   wav_player.c !File port test.wav EOF
17:29:55.008   wav_player.c  File port test.wav rewinding..
17:29:56.408  tsx0xb45064d4 !Retransmit timer event
17:29:56.408  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=5, restart?=1
17:29:56.408   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:29:58.528   wav_player.c !File port test.wav EOF
17:29:58.528   wav_player.c  File port test.wav rewinding..
17:30:00.409  tsx0xb45064d4 !Retransmit timer event
17:30:00.409  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=6, restart?=1
17:30:00.409   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:30:01.390    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:30:01.390 tdta0xb45148f4  Destroying txdata raw
17:30:02.047   wav_player.c !File port test.wav EOF
17:30:02.047   wav_player.c  File port test.wav rewinding..
17:30:04.410  tsx0xb45064d4 !Retransmit timer event
17:30:04.410  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=7, restart?=1
17:30:04.410   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:30:05.567   wav_player.c !File port test.wav EOF
17:30:05.567   wav_player.c  File port test.wav rewinding..
17:30:08.411  tsx0xb45064d4 !Retransmit timer event
17:30:08.411  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=8, restart?=1
17:30:08.411   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:30:09.088   wav_player.c !File port test.wav EOF
17:30:09.088   wav_player.c  File port test.wav rewinding..
17:30:12.411  tsx0xb45064d4 !Retransmit timer event
17:30:12.411  tsx0xb45064d4  .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xc89344), count=9, restart?=1
17:30:12.411   pjsua_core.c  .TX 1000 bytes Response msg 200/INVITE/cseq=20 (tdta0xc89344) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1475499907
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK262994042
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910172918689983810
From: <sip:8002@192.168.2.99>;tag=414211954
To: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:10000;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   360

v=0
o=- 3840276558 3840276559 IN IP4 192.168.2.140
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:446422725 cname:3f9808500542d1dc
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
17:30:12.607   wav_player.c !File port test.wav EOF
17:30:12.607   wav_player.c  File port test.wav rewinding..
17:30:12.908  tsx0xb45064d4 !Timeout timer event
17:30:12.908  tsx0xb45064d4  .State changed from Completed to Terminated, event=TIMER
17:30:12.908  dlg0xb4500c2c  ..Transaction tsx0xb45064d4 state changed to Terminated
17:30:12.908       endpoint  ...Request msg BYE/cseq=22043 (tdta0xb45148f4) created.
17:30:12.908  inv0xb4500c2c  ....Sending Request msg BYE/cseq=22043 (tdta0xb45148f4)
17:30:12.908  dlg0xb4500c2c  .....Sending Request msg BYE/cseq=22043 (tdta0xb45148f4)
17:30:12.908  tsx0xb45158fc  ......Transaction created for Request msg BYE/cseq=22042 (tdta0xb45148f4)
17:30:12.908  tsx0xb45158fc  .....Sending Request msg BYE/cseq=22042 (tdta0xb45148f4) in state Null
17:30:12.908  sip_resolve.c  ......Target '192.168.2.99:5060' type=Unspecified resolved to '192.168.2.99:5060' type=UDP (UDP transport)
17:30:12.908   pjsua_core.c  ......TX 375 bytes Request msg BYE/cseq=22042 (tdta0xb45148f4) to UDP 192.168.2.99:5060:
BYE sip:8002@192.168.2.98:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPjeSwhd52bV9lb5g2c-TYvwBkIlSD3Jqbg
Max-Forwards: 70
From: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
To: <sip:8002@192.168.2.99>;tag=414211954
Call-ID: 20210910172918689983810
CSeq: 22042 BYE
Route: <sip:8000@192.168.2.99:5060;lr>
Content-Length:  0


--end msg--
17:30:12.908  tsx0xb45158fc  ......State changed from Null to Calling, event=TX_MSG
17:30:12.908  dlg0xb4500c2c  .......Transaction tsx0xb45158fc state changed to Calling
17:30:12.908  tsx0xb45064d4  Timeout timer event
17:30:12.908  tsx0xb45064d4  .State changed from Terminated to Destroyed, event=TIMER
17:30:12.908   tdta0xc89344  ..Destroying txdata Response msg 200/INVITE/cseq=20 (tdta0xc89344)
17:30:12.908  tsx0xb45064d4  Transaction destroyed!
17:30:12.911 sip_endpoint.c  Processing incoming message: Response msg 200/BYE/cseq=22042 (rdata0xb45013e4)
17:30:12.911   pjsua_core.c  .RX 334 bytes Response msg 200/BYE/cseq=22042 (rdata0xb45013e4) from UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.140:10000;rport=10000;branch=z9hG4bKPjeSwhd52bV9lb5g2c-TYvwBkIlSD3Jqbg
From: <sip:1000@192.168.2.99>;tag=E.XaCcHXVAggXvmWGNPdfTK3Vr0QOjhS
To: <sip:8002@192.168.2.99>;tag=414211954
Call-ID: 20210910172918689983810
CSeq: 22042 BYE
User-Agent: Dahua UAS/3.0 VTO3221D
Content-Length: 0


--end msg--
17:30:12.911  tsx0xb45158fc  .Incoming Response msg 200/BYE/cseq=22042 (rdata0xb45013e4) in state Calling
17:30:12.911  tsx0xb45158fc  ..State changed from Calling to Completed, event=RX_MSG
17:30:12.911  dlg0xb4500c2c  ...Received Response msg 200/BYE/cseq=22042 (rdata0xb45013e4)
17:30:12.911  dlg0xb4500c2c  ...Transaction tsx0xb45158fc state changed to Completed
17:30:12.911  pjsua_media.c  .....Call 0: deinitializing media..
17:30:12.911    pjsua_app.c  .......Call 0 stream 0 destroyed, dumping media stats..
17:30:12.911 pjsua_app_comm  .......
  [DISCONNCTD] To: <sip:8002@192.168.2.99>;tag=414211954
    Call time: 00h:00m:00s, 1st res in 22660 ms, conn in 0ms
    #0 audio PCMU @8kHz, sendrecv, peer=-
       SRTP status: Not active Crypto-suite: 
       RX pt=0, last update:00h:00m:00.000s ago
          total 0pkt 0B (0B +IP hdr) @avg=0bps/0bps
          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.000   0.000   0.000   0.000   0.000
       TX pt=0, ptime=20, last update:never
          total 1.6Kpkt 256.1KB (320.2KB +IP hdr) @avg=64.0Kbps/80.0Kbps
          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.000   0.000   0.000   0.000   0.000
       RTT msec      :   0.000   0.000   0.000   0.000   0.000
    #1 unknown deactivated
17:30:12.912   strm0xc86124  .......JB summary:
  size=0/eff=0 prefetch=0 level=0
  delay (min/max/avg/dev)=0/0/0/0 ms
  burst (min/max/avg/dev)=0/0/0/0 frames
  lost=0 discard=0 empty=1599
17:30:12.912  pjsua_media.c  .......Media stream call00:0 is destroyed
17:30:12.912  pjsua_media.c  .......Media stream call00:1 is destroyed
17:30:12.912    pjsua_app.c  .....Call 0 is DISCONNECTED [reason=408 (Request Timeout)]
17:30:12.912  dlg0xb4500c2c  .....Session count dec to 1 by mod-invite
17:30:13.911    pjsua_aud.c  Closing sound device after idle for 1 second(s)
17:30:13.911    pjsua_app.c  .Turning sound device -99 -99 OFF
17:30:13.911    pjsua_aud.c  .Closing null sound device..
17:30:16.390    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:30:16.390 tdta0xb45054cc  Destroying txdata raw
17:30:17.910  tsx0xb45158fc  Timeout timer event
17:30:17.910  tsx0xb45158fc  .State changed from Completed to Terminated, event=TIMER
17:30:17.910  dlg0xb4500c2c  ..Transaction tsx0xb45158fc state changed to Terminated
17:30:17.910  dlg0xb4500c2c  ...Dialog destroyed!
17:30:17.910  tsx0xb45158fc  


Python app log (the same behavior as pjsup):
10.09.2021 01:02:16 [INFO] ============ SIP client starting 10.09.2021 13:02:16 =======================
10.09.2021 01:02:16 [DEBUG] Trying Photo path /home/pi/Intercom/data/photos/
10.09.2021 01:02:16 [INFO] Photo path /home/pi/SmartHome.old/Intercom/photos/
10.09.2021 01:02:16 [DEBUG] Setting singleton dispatcher as <telegram.ext.dispatcher.Dispatcher object at 0xb5194500>
10.09.2021 01:02:16 [INFO] Scheduler started
13:02:16.945 os_core_unix.c !pjlib 2.11-dev for POSIX initialized
13:02:16.946 sip_endpoint.c  .Creating endpoint instance...
13:02:16.946          pjlib  .select() I/O Queue created (0x23db318)
13:02:16.946 sip_endpoint.c  .Module "mod-msg-print" registered
13:02:16.946 sip_transport.  .Transport manager created.
13:02:16.946   pjsua_core.c  .PJSUA state changed: NULL --> CREATED
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-pjsua-log" registered
10.09.2021 01:02:16 [DEBUG] Entering: get_updates
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-tsx-layer" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-stateful-util" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-ua" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-100rel" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-pjsua" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-invite" registered
10.09.2021 01:02:16 [DEBUG]          pjlib thr0xb6f12ec  ..select() I/O Queue created (0x23b955c)
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-evsub" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-presence" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-mwi" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-refer" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-pjsua-pres" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-pjsua-im" registered
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-pjsua-options" registered
10.09.2021 01:02:16 [DEBUG]   pjsua_core.c thr0xb6f12ec  .No SIP worker threads created
10.09.2021 01:02:16 [INFO]   pjsua_core.c thr0xb6f12ec  .pjsua version 2.11-dev for Linux-5.10.60/armv7l/glibc-2.28 initialized
10.09.2021 01:02:16 [DEBUG]   pjsua_core.c thr0xb6f12ec  .PJSUA state changed: CREATED --> INIT
10.09.2021 01:02:16 [DEBUG]   pjsua_core.c thr0xb6f12ec  SIP UDP socket reachable at 192.168.2.140:57384
10.09.2021 01:02:16 [DEBUG]   udp0x23b7d20 thr0xb6f12ec  SIP UDP transport started, published address is 192.168.2.140:57384
10.09.2021 01:02:16 [DEBUG]   pjsua_core.c thr0xb6f12ec  PJSUA state changed: INIT --> STARTING
10.09.2021 01:02:16 [DEBUG] sip_endpoint.c thr0xb6f12ec  .Module "mod-unsolicited-mwi" registered
10.09.2021 01:02:16 [DEBUG]   pjsua_core.c thr0xb6f12ec  .PJSUA state changed: STARTING --> RUNNING
10.09.2021 01:02:16 [DEBUG] Codec Name: speex/16000/1, Priority 130, Clock Rate 16000, Channel count 1, Payload Type 0
10.09.2021 01:02:16 [DEBUG] Codec Name: speex/8000/1, Priority 129, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:16 [DEBUG] Codec Name: speex/32000/1, Priority 128, Clock Rate 32000, Channel count 1, Payload Type 0
10.09.2021 01:02:16 [DEBUG] Codec Name: iLBC/8000/1, Priority 128, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:16 [DEBUG] Codec Name: GSM/8000/1, Priority 128, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:16 [DEBUG] Codec Name: PCMU/8000/1, Priority 128, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:16 [DEBUG] Codec Name: PCMA/8000/1, Priority 128, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:16 [DEBUG] Codec Name: G722/16000/1, Priority 128, Clock Rate 16000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: L16/44100/1, Priority 0, Clock Rate 44100, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: L16/44100/2, Priority 0, Clock Rate 44100, Channel count 2, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Available codecs
10.09.2021 01:02:17 [DEBUG] Codec Name: speex/16000/1, Priority 130, Clock Rate 16000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: speex/8000/1, Priority 129, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: speex/32000/1, Priority 128, Clock Rate 32000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: iLBC/8000/1, Priority 128, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: L16/44100/2, Priority 90, Clock Rate 44100, Channel count 2, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: L16/44100/1, Priority 80, Clock Rate 44100, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: G722/16000/1, Priority 50, Clock Rate 16000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: PCMA/8000/1, Priority 40, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: GSM/8000/1, Priority 20, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Codec Name: PCMU/8000/1, Priority 10, Clock Rate 8000, Channel count 1, Payload Type 0
10.09.2021 01:02:17 [DEBUG] Available sound devices
10.09.2021 01:02:17 [DEBUG]    pjsua_aud.c thr0xb6f12ec  Setting null sound device..
10.09.2021 01:02:17 [DEBUG]    pjsua_aud.c thr0xb6f12ec  .Opening null sound device..
10.09.2021 01:02:17 [DEBUG]    pjsua_acc.c thr0xb6f12ec  Adding account: id=<sip:1000#2@VTO_GATE>
10.09.2021 01:02:17 [DEBUG]    pjsua_acc.c thr0xb6f12ec  .Account <sip:1000#2@VTO_GATE> added with id 0
10.09.2021 01:02:17 [DEBUG]    pjsua_acc.c thr0xb6f12ec  .Acc 0: setting registration..
10.09.2021 01:02:17 [DEBUG]   pjsua_core.c thr0xb6f12ec  ...TX 538 bytes Request msg REGISTER/cseq=48401 (tdta0x23f4af4) to UDP 192.168.2.99:5060:
REGISTER sip:VTO_GATE SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:57384;rport;branch=z9hG4bKPjLeQBL35bfEONeCODFhrTj55LdTr1GayU
Route: <sip:VTO_GATE;lr>
Max-Forwards: 70
From: <sip:1000%232@VTO_GATE>;tag=rxpGCFUuHi7PDwdEFluZF0eFfFK3Eu1R
To: <sip:1000%232@VTO_GATE>
Call-ID: jLKphRJFMlGhocz0MFvTt7m9C0Zq4A0r
CSeq: 48401 REGISTER
User-Agent: pjsip python
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length:  0


--end msg--
10.09.2021 01:02:17 [DEBUG]    pjsua_acc.c thr0xb6f12ec  ..Acc 0: Registration sent
10.09.2021 01:02:17 [DEBUG]   pjsua_core.c python worke !.RX 454 bytes Response msg 401/REGISTER/cseq=48401 (rdata0x23f0534) from UDP 192.168.2.99:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.2.140:57384;rport=57384;branch=z9hG4bKPjLeQBL35bfEONeCODFhrTj55LdTr1GayU
From: <sip:1000%232@VTO_GATE>;tag=rxpGCFUuHi7PDwdEFluZF0eFfFK3Eu1R
To: <sip:1000%232@VTO_GATE>;tag=1703849587
Call-ID: jLKphRJFMlGhocz0MFvTt7m9C0Zq4A0r
CSeq: 48401 REGISTER
WWW-Authenticate: Digest realm="VDP", nonce="e41abe7706e987b2b9a6adcbe23427d0", algorithm=MD5
User-Agent: Dahua UAS/3.0 VTO3221D
Content-Length: 0


--end msg--
10.09.2021 01:02:17 [DEBUG] sip_auth_clien python worke  ...Digest algorithm is "MD5"
10.09.2021 01:02:17 [DEBUG]   pjsua_core.c python worke  ....TX 714 bytes Request msg REGISTER/cseq=48402 (tdta0x23f4af4) to UDP 192.168.2.99:5060:
REGISTER sip:VTO_GATE SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:57384;rport;branch=z9hG4bKPjUif41nj774avGkjg88woz44rU.Y-LVEY
Route: <sip:VTO_GATE;lr>
Max-Forwards: 70
From: <sip:1000%232@VTO_GATE>;tag=rxpGCFUuHi7PDwdEFluZF0eFfFK3Eu1R
To: <sip:1000%232@VTO_GATE>
Call-ID: jLKphRJFMlGhocz0MFvTt7m9C0Zq4A0r
CSeq: 48402 REGISTER
User-Agent: pjsip python
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="1000#2", realm="VDP", nonce="e41abe7706e987b2b9a6adcbe23427d0", uri="sip:VTO_GATE", response="802b3e19d8012260da5e54f9108516b2", algorithm=MD5
Content-Length:  0


--end msg--
10.09.2021 01:02:17 [DEBUG]   pjsua_core.c python worke  .RX 447 bytes Response msg 200/REGISTER/cseq=48402 (rdata0x23f0534) from UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.140:57384;rport=57384;branch=z9hG4bKPjUif41nj774avGkjg88woz44rU.Y-LVEY
From: <sip:1000%232@VTO_GATE>;tag=rxpGCFUuHi7PDwdEFluZF0eFfFK3Eu1R
To: <sip:1000%232@VTO_GATE>;tag=1518147372
Call-ID: jLKphRJFMlGhocz0MFvTt7m9C0Zq4A0r
CSeq: 48402 REGISTER
Contact: <sip:1000%232@192.168.2.140:57384;ob>
User-Agent: Dahua UAS/3.0 VTO3221D
Expires: 600
Date: Fri,10 Sep 2021 14:02:17 UTC
Content-Length: 0


--end msg--
10.09.2021 01:02:17 [DEBUG]    pjsua_acc.c python worke  ....SIP outbound status for acc 0 is not active
10.09.2021 01:02:17 [INFO]    pjsua_acc.c python worke  ....<sip:1000#2@VTO_GATE>: registration success, status=200 (OK), will re-register in 600 seconds
10.09.2021 01:02:17 [DEBUG]    pjsua_acc.c python worke  ....Keep-alive timer started for acc 0, destination:192.168.2.99:5060, interval:15s
10.09.2021 01:02:17 [DEBUG] Account: Registration status changed to 200 (OK)
10.09.2021 01:02:17 [INFO] Registration complete, status 200 (OK)
10.09.2021 01:02:26 [DEBUG] No new updates found.
10.09.2021 01:02:26 [DEBUG] []
10.09.2021 01:02:26 [DEBUG] Exiting: get_updates
10.09.2021 01:02:26 [DEBUG] Entering: get_updates
10.09.2021 01:02:32 [DEBUG]   pjsua_core.c python worke  .RX 879 bytes Request msg INVITE/cseq=20 (rdata0x23f0534) from UDP 192.168.2.99:5060:
INVITE sip:1000%232@192.168.2.140:57384 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.99:5060;rport;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99:5060>
Call-ID: 20210910130232161869794
CSeq: 20 INVITE
Contact: <sip:8002@192.168.2.98:5060>
User-agent: Dahua UAC/3.0 VTO1201G-P V4.400.0.0
Group-Call: 224.0.2.14;30001;30000
Max-Forwards: 68
Content-Type: application/sdp
Content-Length:   315

v=0
o=- 1631278952 0 IN IP4 192.168.2.98
s=Dahua VT 1.5
c=IN IP4 192.168.2.98
t=0 0
m=audio 20000 RTP/AVP 101 0 97
a=rtpmap:97 PCM/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 20001 RTP/AVP 96
a=framerate:25.000000
a=rtpmap:96 H264/90000
a=sendrecv

--end msg--
10.09.2021 01:02:32 [DEBUG]   pjsua_call.c python worke  .Incoming Request msg INVITE/cseq=20 (rdata0x23f0534)
10.09.2021 01:02:32 [DEBUG]  pjsua_media.c python worke  ..Call 0: initializing media..
10.09.2021 01:02:32 [DEBUG]  pjsua_media.c python worke  ...RTP socket reachable at 192.168.2.140:40429
10.09.2021 01:02:32 [DEBUG]  pjsua_media.c python worke  ...RTCP socket reachable at 192.168.2.140:40430
10.09.2021 01:02:32 [DEBUG]  pjsua_media.c python worke  ...Media index 0 selected for audio call 0
10.09.2021 01:02:32 [DEBUG]   pjsua_core.c python worke  .....TX 381 bytes Response msg 100/INVITE/cseq=20 (tdta0x240804c) to UDP 192.168.2.99:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>
CSeq: 20 INVITE
Content-Length:  0


--end msg--
10.09.2021 01:02:32 [INFO] Account: Incoming call from <sip:8002@192.168.2.99> is INCOMING (2), last code = 0 ()
10.09.2021 01:02:32 [INFO] Account: Initializing SIP call hangup timer, delay 120 seconds
10.09.2021 01:02:32 [INFO] Account: Detected source number 8002
10.09.2021 01:02:44 [INFO] Telegram: Saved audio file /home/pi/SmartHome.old/Intercom/photos//10.09.2021 13:02:42 Serhiy.wav
10.09.2021 01:02:44 [INFO] SIP Player: Creating new voice player from /home/pi/SmartHome.old/Intercom/photos//10.09.2021 13:02:42 Serhiy.wav
10.09.2021 01:02:44 [DEBUG]    pjsua_aud.c Voice proces !Creating file player: /home/pi/SmartHome.old/Intercom/photos//10.09.2021 13:02:42 Serhiy.wav..
10.09.2021 01:02:44 [DEBUG]   wav_player.c Voice proces  .File player '/home/pi/SmartHome.old/Intercom/photos//10.09.2021 13:02:42 Serhiy.wav' created: samp.rate=16000, ch=1, bufsize=4KB, filesize=99KB
10.09.2021 01:02:44 [DEBUG]    pjsua_aud.c Voice proces  .Player created, id=0, slot=1
10.09.2021 01:02:44 [INFO] SIP Player: New voice player created, ID 0, SLOT 1
10.09.2021 01:02:44 [INFO] Telegram: Answering incoming call
10.09.2021 01:02:44 [DEBUG]   pjsua_call.c Voice proces  Answering call 0: code=200
10.09.2021 01:02:44 [DEBUG]   inv0x23f7a44 Voice proces  ..SDP negotiation done: Success
10.09.2021 01:02:44 [DEBUG]  pjsua_media.c Voice proces  ...Call 0: updating media..
10.09.2021 01:02:44 [DEBUG]  pjsua_media.c Voice proces  .....Media stream call00:0 is destroyed
10.09.2021 01:02:44 [DEBUG]    pjsua_aud.c Voice proces  ....Audio channel update..
10.09.2021 01:02:44 [DEBUG] strm0xb3c17714 Voice proces  .....VAD temporarily disabled
10.09.2021 01:02:44 [DEBUG] strm0xb3c17714 Voice proces  .....Encoder stream started
10.09.2021 01:02:44 [DEBUG] strm0xb3c17714 Voice proces  .....Decoder stream started
10.09.2021 01:02:44 [DEBUG]  pjsua_media.c Voice proces  ....Audio updated, stream #0: PCMU (sendrecv)
10.09.2021 01:02:44 [DEBUG]  pjsua_media.c Voice proces  .....Media stream call00:1 is destroyed
10.09.2021 01:02:44 [INFO]  pjsua_media.c Voice proces  ....Skipped updating media call00:1 (media type=unknown): Unsupported media type (PJMEDIA_EUNSUPMEDIATYPE)
10.09.2021 01:02:44 [INFO] Call: MEDIA ACTIVE
10.09.2021 01:02:44 [DEBUG]   pjsua_core.c Voice proces  ....TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:02:44 [INFO] Call: Call with <sip:8002@192.168.2.99> is CONNECTING (4), last code = 200 (OK)
10.09.2021 01:02:44 [DEBUG] Entering: send_message
10.09.2021 01:02:44 [DEBUG] {'date': 1631271764, 'text': 'Voice message transmitted', 'channel_chat_created': False, 'caption_entities': [], 'message_id': 566, 'chat': {'id': -1001441177323, 'type': 'supergroup', 'title': 'Home Intercom'}, 'group_chat_created': False, 'entities': [], 'supergroup_chat_created': False, 'reply_to_message': {'date': 1631271762, 'channel_chat_created': False, 'caption_entities': [], 'message_id': 565, 'chat': {'id': -1001441177323, 'type': 'supergroup', 'title': 'Home Intercom'}, 'group_chat_created': False, 'entities': [], 'supergroup_chat_created': False, 'new_chat_photo': [], 'voice': {'mime_type': 'audio/ogg', 'file_id': 'AwACAgQAAx0CVeae6wACAjVhOztSTIKxkspzDo4QAoG9HxpyQQAC9gsAAtgR4VG_njRwAhhG9iAE', 'file_size': 12843, 'file_unique_id': 'AgAD9gsAAtgR4VE', 'duration': 3}, 'new_chat_members': [], 'delete_chat_photo': False, 'photo': [], 'from': {'id': 890809473, 'language_code': 'en', 'last_name': 'Tkachenko', 'is_bot': False, 'first_name': 'Serhiy'}}, 'new_chat_photo': [], 'new_chat_members': [], 'delete_chat_photo': False, 'photo': [], 'from': {'id': 1933387114, 'is_bot': True, 'first_name': 'Intercom', 'username': 'Intecom_Bot'}}
10.09.2021 01:02:44 [DEBUG] Exiting: send_message
10.09.2021 01:02:45 [DEBUG]   pjsua_core.c python worke !.TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:02:46 [DEBUG]   pjsua_core.c python worke !.TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:02:48 [DEBUG]   pjsua_core.c python worke  .TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:02:52 [DEBUG]   pjsua_core.c python worke  .TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:02:52 [INFO] Hangup: Hanging up call from <sip:8002@192.168.2.99>
10.09.2021 01:02:52 [DEBUG]   pjsua_call.c Call hangup  !Call 0 hanging up: code=603..
10.09.2021 01:02:52 [DEBUG]  pjsua_media.c Call hangup   .Call 0: deinitializing media..
10.09.2021 01:02:52 [DEBUG]  pjsua_media.c Call hangup   ...Media stream call00:0 is destroyed
10.09.2021 01:02:52 [DEBUG]  pjsua_media.c Call hangup   ...Media stream call00:1 is destroyed
10.09.2021 01:02:52 [INFO] Call: Call with <sip:8002@192.168.2.99> is DISCONNCTD (6), last code = 603 (Decline)
10.09.2021 01:02:52 [INFO] Call: CALL DISCONNECED
10.09.2021 01:02:52 [INFO] SIP Player: Deleting old voice player ID 0
10.09.2021 01:02:52 [DEBUG]    pjsua_aud.c Call hangup   .Destroying player 0..
10.09.2021 01:02:52 [DEBUG]   inv0x23f7a44 Call hangup   ..Delaying BYE request until ACK is received
10.09.2021 01:02:53 [DEBUG]    pjsua_aud.c python worke !Closing sound device after idle for 1 second(s)
10.09.2021 01:02:53 [DEBUG]    pjsua_aud.c python worke  .Closing null sound device..
10.09.2021 01:02:56 [DEBUG]   pjsua_core.c python worke  .TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:03:00 [DEBUG]   pjsua_core.c python worke  .TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:03:02 [DEBUG] No new updates found.
10.09.2021 01:03:02 [DEBUG] []
10.09.2021 01:03:02 [DEBUG] Exiting: get_updates
10.09.2021 01:03:02 [DEBUG] Entering: get_updates
10.09.2021 01:03:04 [DEBUG]   pjsua_core.c python worke  .TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
10.09.2021 01:03:08 [DEBUG]   pjsua_core.c python worke  .TX 1007 bytes Response msg 200/INVITE/cseq=20 (tdta0xb3c0626c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK1311608601
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2107584479
Record-Route: <sip:8000@192.168.2.99:5060;lr>
Call-ID: 20210910130232161869794
From: <sip:8002@192.168.2.99>;tag=1029163189
To: <sip:1000@192.168.2.99>;tag=60PdTS0gThJZguFe9Xyanuu2Gguid-5.
CSeq: 20 INVITE
Contact: <sip:1000%232@192.168.2.140:57384;ob>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   365

v=0
o=- 3840260552 3840260553 IN IP4 192.168.2.140
s=pjmedia
b=AS:1498
t=0 0
a=X-nat:0
m=audio 40429 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:1411200
a=rtcp:40430 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:73164192 cname:651396dc1c18d355
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 96
c=IN IP4 127.0.0.1

--end msg--
@altserg altserg changed the title Audio is not connected with Dahua VTO3221D SIP server: Unsupported media type (PJMEDIA_EUNSUPMEDIATYPE) Audio is not connected with Dahua VTO3221D SIP server / Dahua VTO1201G-P SIP intercom: Unsupported media type (PJMEDIA_EUNSUPMEDIATYPE) Sep 15, 2021
@trengginas
Copy link
Member

If the library is not built with video support and receives an incoming call with video, the PJMEDIA_EUNSUPMEDIATYPE message is expected. The issue is with the remote that didn't respond to the 200/OK

@altserg
Copy link
Author

altserg commented Mar 1, 2023

I have build the lib with video support (SDL dummy renderer) and OpenH264 support, there is still no audio call established. See the log below. It's kind of different issue now. Shell I submit anew ticket for that ?

17:21:30.003 sip_endpoint.c !.Module "mod-pjsua-log" registered
17:21:30.003 sip_endpoint.c .Module "mod-tsx-layer" registered
17:21:30.003 sip_endpoint.c .Module "mod-stateful-util" registered
17:21:30.003 sip_endpoint.c .Module "mod-ua" registered
17:21:30.004 sip_endpoint.c .Module "mod-100rel" registered
17:21:30.004 sip_endpoint.c .Module "mod-pjsua" registered
17:21:30.004 sip_endpoint.c .Module "mod-invite" registered
17:21:30.005 pjlib ..select() I/O Queue created (0x1401d94)
17:21:30.005 conference.c ..Creating conference bridge with 12 ports
17:21:30.005 Master/sound ..Using delay buffer with WSOLA.
17:21:30.012 pjsua_vid.c ..Initializing video subsystem..
17:21:30.012 vid_conf.c ...Created video conference bridge with 32 ports
17:21:30.012 openh264.cpp ...OpenH264 codec initialized
17:21:30.014 sdl_dev.c ...SDL 2.26 initialized
17:21:30.014 colorbar_dev.c ...Colorbar video src initialized with 2 device(s):
17:21:30.014 colorbar_dev.c ... 0: Colorbar generator
17:21:30.014 colorbar_dev.c ... 1: Colorbar-active
17:21:30.014 sip_endpoint.c .Module "mod-evsub" registered
17:21:30.014 sip_endpoint.c .Module "mod-presence" registered
17:21:30.014 evsub.c .Event pkg "presence" registered by mod-presence
17:21:30.014 sip_endpoint.c .Module "mod-mwi" registered
17:21:30.014 evsub.c .Event pkg "message-summary" registered by mod-mwi
17:21:30.014 sip_endpoint.c .Module "mod-refer" registered
17:21:30.014 evsub.c .Event pkg "refer" registered by mod-refer
17:21:30.014 sip_endpoint.c .Module "mod-pjsua-pres" registered
17:21:30.014 sip_endpoint.c .Module "mod-pjsua-im" registered
17:21:30.014 sip_endpoint.c .Module "mod-pjsua-options" registered
17:21:30.014 pjsua_core.c .1 SIP worker threads created
17:21:30.014 pjsua_core.c .pjsua version 2.13-dev for Linux-5.15.84/armv7l/glibc-2.31 initialized
17:21:30.014 pjsua_core.c .PJSUA state changed: CREATED --> INIT
17:21:30.014 sip_endpoint.c Module "mod-default-handler" registered
17:21:30.014 pjsua_aud.c Creating file player: t.wav..
17:21:30.014 wav_player.c .File player 't.wav' created: samp.rate=48000, ch=1, bufsize=4KB, filesize=176KB
17:21:30.015 resample.c .resample created: high qualiy, large filter, in/out rate=48000/16000
17:21:30.015 resample.c .resample created: high qualiy, large filter, in/out rate=16000/48000
17:21:30.015 pjsua_aud.c .Player created, id=0, slot=1
17:21:30.015 pjsua_aud.c Creating recorder rec.wav..
17:21:30.015 wav_writer.c .File writer 'rec.wav' created: samp.rate=16000, bufsize=4KB
17:21:30.015 pjsua_aud.c .Recorder created, id=0, slot=2
17:21:30.016 pjsua_core.c SIP UDP socket reachable at 192.168.2.140:10000
17:21:30.016 udp0x141fe00 SIP UDP transport started, published address is 192.168.2.140:10000
17:21:30.016 pjsua_acc.c Adding account: id=sip:192.168.2.140:10000
17:21:30.016 pjsua_acc.c .Account sip:192.168.2.140:10000 added with id 0
17:21:30.016 pjsua_acc.c Modifying account 0
17:21:30.016 pjsua_acc.c Acc 0: setting online status to 1..
17:21:30.017 tcptp:10000 SIP TCP listener ready for incoming connections at 192.168.2.140:10000
17:21:30.017 pjsua_acc.c Adding account: id=sip:192.168.2.140:10000;transport=TCP
17:21:30.017 pjsua_acc.c .Account sip:192.168.2.140:10000;transport=TCP added with id 1
17:21:30.017 pjsua_acc.c Modifying account 1
17:21:30.017 pjsua_acc.c Acc 1: setting online status to 1..
17:21:30.017 pjsua_acc.c Adding account: id=sip:1000#1@192.168.2.140
17:21:30.017 pjsua_acc.c .Account sip:1000#1@192.168.2.140 added with id 2
17:21:30.017 pjsua_acc.c .Acc 2: setting registration..
17:21:30.017 endpoint ..Request msg REGISTER/cseq=29550 (tdta0x1425174) created.
17:21:30.017 tsx0x142617c ...Transaction created for Request msg REGISTER/cseq=29551 (tdta0x1425174)
17:21:30.017 tsx0x142617c ..Sending Request msg REGISTER/cseq=29551 (tdta0x1425174) in state Null
17:21:30.017 sip_resolve.c ...DNS resolver not available, target 'VTO-GATE.local:0' type=Unspecified will be resolved with getaddrinfo()
17:21:30.130 sip_resolve.c ...Target 'VTO-GATE.local:0' type=Unspecified resolved to '192.168.2.99:5060' type=UDP (UDP transport)
17:21:30.130 pjsua_core.c ...TX 575 bytes Request msg REGISTER/cseq=29551 (tdta0x1425174) to UDP 192.168.2.99:5060:
REGISTER sip:VTO-GATE.local SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPjc54c4ea6-b009-498d-a245-848eb829faf3
Max-Forwards: 70
From: sip:1000%231@192.168.2.140;tag=5e585912-ca0a-4c18-ac63-fefb4192695d
To: sip:1000%231@192.168.2.140
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29551 REGISTER
User-Agent: PJSUA v2.13-dev Linux-5.15.84/armv7l/glibc-2.31
Contact: sip:1000%231@192.168.2.140:10000;ob
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length: 0

--end msg--
17:21:30.130 tsx0x142617c ...State changed from Null to Calling, event=TX_MSG
17:21:30.130 pjsua_acc.c ..Acc 2: Registration sent
17:21:30.130 pjsua_acc.c Acc 2: setting online status to 1..
17:21:30.130 pjsua_aud.c Setting null sound device..
17:21:30.130 pjsua_app.c .Turning sound device -99 -99 ON
17:21:30.130 pjsua_aud.c .Opening null sound device..
17:21:30.131 pjsua_core.c PJSUA state changed: INIT --> STARTING
17:21:30.131 sip_endpoint.c .Module "mod-unsolicited-mwi" registered
17:21:30.131 pjsua_core.c .PJSUA state changed: STARTING --> RUNNING
17:21:30.131 main.c Ready: Success
17:21:30.169 sip_endpoint.c !Processing incoming message: Response msg 401/REGISTER/cseq=29551 (rdata0x1421434)
17:21:30.169 pjsua_core.c .RX 476 bytes Response msg 401/REGISTER/cseq=29551 (rdata0x1421434) from UDP 192.168.2.99:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.2.140:10000;rport=10000;branch=z9hG4bKPjc54c4ea6-b009-498d-a245-848eb829faf3
From: sip:1000%231@192.168.2.140;tag=5e585912-ca0a-4c18-ac63-fefb4192695d
To: sip:1000%231@192.168.2.140;tag=1570150487
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29551 REGISTER
WWW-Authenticate: Digest realm="VDP", nonce="e2598554c95aabe705b83deae2791323", algorithm=MD5
User-Agent: Dahua UAS/3.0 VTO3221D
Content-Length: 0

--end msg--
17:21:30.169 tsx0x142617c .Incoming Response msg 401/REGISTER/cseq=29551 (rdata0x1421434) in state Calling
17:21:30.169 tsx0x142617c ..State changed from Calling to Completed, event=RX_MSG
17:21:30.169 sip_auth_clien ...Digest algorithm is "MD5"
17:21:30.169 tsx0xb3100c6c ....Transaction created for Request msg REGISTER/cseq=29552 (tdta0x1425174)
17:21:30.169 tsx0xb3100c6c ...Sending Request msg REGISTER/cseq=29552 (tdta0x1425174) in state Null
17:21:30.169 endpoint ....Request msg REGISTER/cseq=29552 (tdta0x1425174): skipping target resolution because address is already set
17:21:30.170 pjsua_core.c ....TX 757 bytes Request msg REGISTER/cseq=29552 (tdta0x1425174) to UDP 192.168.2.99:5060:
REGISTER sip:VTO-GATE.local SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPj46da1484-3e88-4407-bcd7-d0d25e4400ef
Max-Forwards: 70
From: sip:1000%231@192.168.2.140;tag=5e585912-ca0a-4c18-ac63-fefb4192695d
To: sip:1000%231@192.168.2.140
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29552 REGISTER
User-Agent: PJSUA v2.13-dev Linux-5.15.84/armv7l/glibc-2.31
Contact: sip:1000%231@192.168.2.140:10000;ob
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="1000#1", realm="VDP", nonce="e2598554c95aabe705b83deae2791323", uri="sip:VTO-GATE.local", response="b59cc71fd5c836a2e11f44b19a2cad1b", algorithm=MD5
Content-Length: 0

--end msg--
17:21:30.170 tsx0xb3100c6c ....State changed from Null to Calling, event=TX_MSG
17:21:30.218 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=29552 (rdata0xb3101424)
17:21:30.218 pjsua_core.c .RX 468 bytes Response msg 200/REGISTER/cseq=29552 (rdata0xb3101424) from UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.140:10000;rport=10000;branch=z9hG4bKPj46da1484-3e88-4407-bcd7-d0d25e4400ef
From: sip:1000%231@192.168.2.140;tag=5e585912-ca0a-4c18-ac63-fefb4192695d
To: sip:1000%231@192.168.2.140;tag=591278591
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29552 REGISTER
Contact: sip:1000%231@192.168.2.140:10000;ob
User-Agent: Dahua UAS/3.0 VTO3221D
Expires: 600
Date: Wed,01 Mar 2023 17:21:28 UTC
Content-Length: 0

--end msg--
17:21:30.218 tsx0xb3100c6c .Incoming Response msg 200/REGISTER/cseq=29552 (rdata0xb3101424) in state Calling
17:21:30.218 tsx0xb3100c6c ..State changed from Calling to Completed, event=RX_MSG
17:21:30.218 pjsua_acc.c ....SIP outbound status for acc 2 is not active
17:21:30.218 pjsua_acc.c ....sip:1000#1@192.168.2.140: registration success, status=200 (OK), will re-register in 600 seconds
17:21:30.218 pjsua_acc.c ....Keep-alive timer started for acc 2, destination:192.168.2.99:5060, interval:14s
17:21:31.130 pjsua_aud.c Closing sound device after idle for 1 second(s)
17:21:31.130 pjsua_app.c .Turning sound device -99 -99 OFF
17:21:31.130 pjsua_aud.c .Closing null sound device..
17:21:35.170 tsx0x142617c Timeout timer event
17:21:35.170 tsx0x142617c .State changed from Completed to Terminated, event=TIMER
17:21:35.170 tsx0x142617c Timeout timer event
17:21:35.170 tsx0x142617c .State changed from Terminated to Destroyed, event=TIMER
17:21:35.170 tsx0x142617c Transaction destroyed!
17:21:35.219 tsx0xb3100c6c Timeout timer event
17:21:35.219 tsx0xb3100c6c .State changed from Completed to Terminated, event=TIMER
17:21:35.219 tsx0xb3100c6c Timeout timer event
17:21:35.219 tsx0xb3100c6c .State changed from Terminated to Destroyed, event=TIMER
17:21:35.219 tdta0x1425174 ..Destroying txdata Request msg REGISTER/cseq=29552 (tdta0x1425174)
17:21:35.219 tsx0xb3100c6c Transaction destroyed!
17:21:36.822 pjsua_app_comm !Video device list:
17:21:36.822 pjsua_app_comm 3 device(s) detected:
17:21:36.822 pjsua_app_comm -2 SDL renderer [SDL][render] (default renderer device)
17:21:36.822 pjsua_app_comm Supported capabilities: format, window, resize, wndflags
17:21:36.822 pjsua_app_comm Supported formats: RGBA, RGB24, BGRA, DIB , YUY2, UYVY, YVYU, I420, YV12, I420JPG, I422JPG
17:21:36.822 pjsua_app_comm -1 Colorbar generator [Colorbar][capture] (default capture device)
17:21:36.822 pjsua_app_comm Supported capabilities: format
17:21:36.822 pjsua_app_comm Supported formats: YUY2, UYVY, YVYU, RGBA, RGB24, BGRA, YV12, I420, I422, I420JPG, I422JPG
17:21:36.823 pjsua_app_comm 0 SDL renderer [SDL][render]
17:21:36.823 pjsua_app_comm Supported capabilities: format, window, resize, wndflags
17:21:36.823 pjsua_app_comm Supported formats: RGBA, RGB24, BGRA, DIB , YUY2, UYVY, YVYU, I420, YV12, I420JPG, I422JPG
17:21:36.823 pjsua_app_comm 1 Colorbar generator [Colorbar][capture]
17:21:36.823 pjsua_app_comm Supported capabilities: format
17:21:36.823 pjsua_app_comm Supported formats: YUY2, UYVY, YVYU, RGBA, RGB24, BGRA, YV12, I420, I422, I420JPG, I422JPG
17:21:36.823 pjsua_app_comm 2 Colorbar-active [Colorbar][capture]
17:21:36.823 pjsua_app_comm Supported capabilities: format
17:21:36.823 pjsua_app_comm Supported formats: YUY2, UYVY, YVYU, RGBA, RGB24, BGRA, YV12, I420, I422, I420JPG, I422JPG
17:21:42.399 pjsua_app_lega Invalid command, use 'vid help'
17:21:44.219 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:21:44.219 tdta0xb31035c4 Destroying txdata raw
17:21:49.846 pjsua_app_lega !Found 1 video codecs:
17:21:49.846 pjsua_app_lega codec id prio fps bw(kbps) size
17:21:49.846 pjsua_app_lega ------------------------------------------
17:21:49.846 pjsua_app_lega H264/97 128 15.00 256/ 256 720x480
17:21:51.814 pjsua_core.c Start dumping application states:
pjsip_endpt_dump()
Dumping endpoint 0x13d2e44:
Dumping caching pool:
Capacity=0, max_capacity=0, used_cnt=46
Endpoint pool capacity=172384, used_size=170516
Outstanding transmit buffers: 0
Dumping listeners:
tcptp:10000 TCP:192.168.2.140:10000
Dumping transports:
udp0x141fe00 udp 0.0.0.0:10000 published as 192.168.2.140:10000
Dumping timer heap:
Cur size: 3 entries, max: 3070
Entries:
_id Id Elapsed Source
----------------------------------
6 0 4.404 ../src/pjsua-lib/pjsua_acc.c:2148
2 1 573.403 ../src/pjsip-ua/sip_reg.c:857
4 0 278.315 ../src/pjsua-lib/pjsua_pres.c:2465
Dumping PJMEDIA capabilities:
Total number of installed codecs: 3
Audio codec # 0: pt=3 (GSM @8KHz/1, 13.2Kbps, 20ms vad plc)
Audio codec # 1: pt=0 (PCMU @8KHz/1, 64.0Kbps, 20ms vad plc)
Audio codec # 2: pt=8 (PCMA @8KHz/1, 64.0Kbps, 20ms vad plc)
Dumping media transports:
Dumping transaction table:
Total 0 transactions
Number of dialog sets: 0
Number of server/UAS subscriptions: 0
Number of client/UAC subscriptions: 0
17:21:51.815 pjsua_core.c Dump complete
17:21:56.219 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:21:56.219 tdta0xb31035c4 Destroying txdata raw
17:21:58.671 pjsua_app_comm !Video device list:
17:21:58.671 pjsua_app_comm 3 device(s) detected:
17:21:58.671 pjsua_app_comm -2 SDL renderer [SDL][render] (default renderer device)
17:21:58.671 pjsua_app_comm Supported capabilities: format, window, resize, wndflags
17:21:58.671 pjsua_app_comm Supported formats: RGBA, RGB24, BGRA, DIB , YUY2, UYVY, YVYU, I420, YV12, I420JPG, I422JPG
17:21:58.671 pjsua_app_comm -1 Colorbar generator [Colorbar][capture] (default capture device)
17:21:58.671 pjsua_app_comm Supported capabilities: format
17:21:58.672 pjsua_app_comm Supported formats: YUY2, UYVY, YVYU, RGBA, RGB24, BGRA, YV12, I420, I422, I420JPG, I422JPG
17:21:58.672 pjsua_app_comm 0 SDL renderer [SDL][render]
17:21:58.672 pjsua_app_comm Supported capabilities: format, window, resize, wndflags
17:21:58.672 pjsua_app_comm Supported formats: RGBA, RGB24, BGRA, DIB , YUY2, UYVY, YVYU, I420, YV12, I420JPG, I422JPG
17:21:58.672 pjsua_app_comm 1 Colorbar generator [Colorbar][capture]
17:21:58.672 pjsua_app_comm Supported capabilities: format
17:21:58.672 pjsua_app_comm Supported formats: YUY2, UYVY, YVYU, RGBA, RGB24, BGRA, YV12, I420, I422, I420JPG, I422JPG
17:21:58.672 pjsua_app_comm 2 Colorbar-active [Colorbar][capture]
17:21:58.672 pjsua_app_comm Supported capabilities: format
17:21:58.672 pjsua_app_comm Supported formats: YUY2, UYVY, YVYU, RGBA, RGB24, BGRA, YV12, I420, I422, I420JPG, I422JPG
17:22:09.220 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:22:09.220 tdta0xb31035c4 Destroying txdata raw
17:22:22.220 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:22:22.220 tdta0xb31035c4 Destroying txdata raw
17:22:32.181 pjsua_app_comm !Account 2:
RX auto show: 1
TX auto transmit: 1
Capture dev: -1
Render dev: -2
17:22:35.220 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:22:35.221 tdta0xb31035c4 Destroying txdata raw
17:22:47.221 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:22:47.221 tdta0xb31035c4 Destroying txdata raw
17:23:01.222 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:01.222 tdta0xb31035c4 Destroying txdata raw
17:23:14.222 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:14.223 tdta0xb31035c4 Destroying txdata raw
17:23:21.302 pjsua_acc.c !Modifying account 2
17:23:24.934 pjsua_app_comm Account 2:
RX auto show: 1
TX auto transmit: 0
Capture dev: -1
Render dev: -2
17:23:28.223 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:28.223 tdta0xb31035c4 Destroying txdata raw
17:23:42.224 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:42.224 tdta0xb31035c4 Destroying txdata raw
17:23:47.159 pjsua_core.c !Start dumping application states:
pjsip_endpt_dump()
Dumping endpoint 0x13d2e44:
Dumping caching pool:
Capacity=0, max_capacity=0, used_cnt=46
Endpoint pool capacity=172384, used_size=170516
Outstanding transmit buffers: 0
Dumping listeners:
tcptp:10000 TCP:192.168.2.140:10000
Dumping transports:
udp0x141fe00 udp 0.0.0.0:10000 published as 192.168.2.140:10000
Dumping timer heap:
Cur size: 3 entries, max: 3070
Entries:
_id Id Elapsed Source
----------------------------------
6 0 7.065 ../src/pjsua-lib/pjsua_acc.c:2148
2 1 458.059 ../src/pjsip-ua/sip_reg.c:857
4 0 162.971 ../src/pjsua-lib/pjsua_pres.c:2465
Dumping PJMEDIA capabilities:
Total number of installed codecs: 3
Audio codec # 0: pt=3 (GSM @8KHz/1, 13.2Kbps, 20ms vad plc)
Audio codec # 1: pt=0 (PCMU @8KHz/1, 64.0Kbps, 20ms vad plc)
Audio codec # 2: pt=8 (PCMA @8KHz/1, 64.0Kbps, 20ms vad plc)
Dumping media transports:
Dumping transaction table:
Total 0 transactions
Number of dialog sets: 0
Number of server/UAS subscriptions: 0
Number of client/UAC subscriptions: 0
17:23:47.160 pjsua_core.c Dump complete
17:23:54.224 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:23:54.224 tdta0xb31035c4 Destroying txdata raw
17:24:07.225 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:07.225 tdta0xb31035c4 Destroying txdata raw
17:24:07.361 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=20 (rdata0xb3101424)
17:24:07.361 pjsua_core.c .RX 880 bytes Request msg INVITE/cseq=20 (rdata0xb3101424) from UDP 192.168.2.99:5060:
INVITE sip:1000%231@192.168.2.140:10000 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.99:5060;rport;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99:5060
Call-ID: 202303011724071863651588
CSeq: 20 INVITE
Contact: sip:8002@192.168.2.98:5060
User-agent: Dahua UAC/3.0 VTO1201G-P V4.400.0.0
Group-Call: 224.0.2.14;30001;30000
Max-Forwards: 68
Content-Type: application/sdp
Content-Length: 315

v=0
o=- 1677691447 1 IN IP4 192.168.2.98
s=Dahua VT 1.5
c=IN IP4 192.168.2.98
t=0 0
m=audio 20000 RTP/AVP 101 0 97
a=rtpmap:97 PCM/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 20001 RTP/AVP 96
a=framerate:25.000000
a=rtpmap:96 H264/90000
a=sendrecv

--end msg--
17:24:07.361 pjsua_call.c .Incoming Request msg INVITE/cseq=20 (rdata0xb3101424)
17:24:07.362 tsx0xb3106514 ...Transaction created for Request msg INVITE/cseq=20 (rdata0xb3101424)
17:24:07.362 tsx0xb3106514 ..Incoming Request msg INVITE/cseq=20 (rdata0xb3101424) in state Null
17:24:07.362 tsx0xb3106514 ...State changed from Null to Trying, event=RX_MSG
17:24:07.362 dlg0xb3100c6c ....Transaction tsx0xb3106514 state changed to Trying
17:24:07.362 dlg0xb3100c6c ..UAS dialog created
17:24:07.362 dlg0xb3100c6c ..Module mod-invite added as dialog usage, data=0xb310336c
17:24:07.362 dlg0xb3100c6c ...Session count inc to 3 by mod-invite
17:24:07.362 inv0xb3100c6c ..UAS invite session created for dialog dlg0xb3100c6c
17:24:07.362 dlg0xb3100c6c ...Session count inc to 3 by mod-pjsua
17:24:07.362 pjsua_media.c ..Call 0: initializing media..
17:24:07.363 pjsua_media.c ...RTP socket reachable at 192.168.2.140:4000
17:24:07.363 pjsua_media.c ...RTCP socket reachable at 192.168.2.140:4001
17:24:07.364 srtp0xb310bfb0 ...SRTP keying SDES created
17:24:07.365 pjsua_media.c ...RTP socket reachable at 192.168.2.140:4002
17:24:07.365 pjsua_media.c ...RTCP socket reachable at 192.168.2.140:4003
17:24:07.365 srtp0xb310c608 ...SRTP keying SDES created
17:24:07.365 pjsua_media.c ...Media index 0 selected for audio call 0
17:24:07.365 pjsua_media.c ...Call 0: media transport initialization complete: Success
17:24:07.365 udp0xb310bda8 ...UDP media transport created
17:24:07.365 udp0xb310c400 ...UDP media transport created
17:24:07.365 dlg0xb3100c6c ...Session count dec to 3 by mod-pjsua
17:24:07.366 pjsua_call.c ..Call 0: remote NAT type is 0 (Unknown)
17:24:07.366 endpoint ...Response msg 100/INVITE/cseq=20 (tdta0xb31188ac) created
17:24:07.366 dlg0xb3100c6c ...Initial answer Response msg 100/INVITE/cseq=20 (tdta0xb31188ac)
17:24:07.366 inv0xb3100c6c ...Sending Response msg 100/INVITE/cseq=20 (tdta0xb31188ac)
17:24:07.366 dlg0xb3100c6c ....Sending Response msg 100/INVITE/cseq=20 (tdta0xb31188ac)
17:24:07.366 tsx0xb3106514 ....Sending Response msg 100/INVITE/cseq=20 (tdta0xb31188ac) in state Trying
17:24:07.366 pjsua_core.c .....TX 382 bytes Response msg 100/INVITE/cseq=20 (tdta0xb31188ac) to UDP 192.168.2.99:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99
CSeq: 20 INVITE
Content-Length: 0

--end msg--
17:24:07.366 tsx0xb3106514 .....State changed from Trying to Proceeding, event=TX_MSG
17:24:07.366 dlg0xb3100c6c ......Transaction tsx0xb3106514 state changed to Proceeding
17:24:07.366 pjsua_aud.c ..Conf connect: 4 --> 0
17:24:07.366 pjsua_aud.c ...Set sound device: capture=-99, playback=-99, mode=0, use_default_settings=0
17:24:07.366 pjsua_aud.c ....Null sound device, mode setting is ignored
17:24:07.366 pjsua_aud.c ....Setting null sound device..
17:24:07.366 pjsua_app.c .....Turning sound device -99 -99 ON
17:24:07.366 pjsua_aud.c .....Opening null sound device..
17:24:07.367 conference.c ...Port 4 (ring) transmitting to port 0 (Master/sound)
17:24:07.367 pjsua_call.c ..Answering call 0: code=200
17:24:07.367 sip_transport. ....Tx data Response msg 100/INVITE/cseq=20 (tdta0xb311ca0c) cloned
17:24:07.367 inv0xb3100c6c ....SDP negotiation done: Success
17:24:07.367 pjsua_call.c .....Call 0: remote NAT type is 0 (Unknown)
17:24:07.367 pjsua_media.c .....Call 0: updating media..
17:24:07.367 pjsua_media.c .......Media stream call00:0 is destroyed
17:24:07.367 udp0xb310bda8 ......UDP media transport started
17:24:07.367 pjsua_aud.c ......Audio channel update..
17:24:07.367 strm0xb311f31c .......VAD temporarily disabled
17:24:07.367 strm0xb311f31c .......Jitter buffer reset
17:24:07.367 rtp.c .......pjmedia_rtp_session_init: ses=0xb3122554, default_pt=0, ssrc=0x5bb7e891
17:24:07.367 rtp.c .......pjmedia_rtp_session_init2: ses=0xb3122554, seq=1689, ts=0, peer_ssrc=0
17:24:07.368 rtp.c .......pjmedia_rtp_session_init: ses=0xb3122bdc, default_pt=0, ssrc=0x5bb7e891
17:24:07.368 rtp.c .......pjmedia_rtp_session_init2: ses=0xb3122bdc, seq=29278, ts=0, peer_ssrc=0
17:24:07.368 udp0xb310bda8 .......SO_RCVBUF set to 180224
17:24:07.368 udp0xb310bda8 .......SO_SNDBUF set to 180224
17:24:07.368 udp0xb310bda8 .......UDP media transport attached
17:24:07.368 stream.c .......Stream strm0xb311f31c created
17:24:07.368 strm0xb311f31c .......Encoder stream started
17:24:07.368 strm0xb311f31c .......Decoder stream started
17:24:07.368 resample.c .......resample created: high qualiy, large filter, in/out rate=8000/16000
17:24:07.368 resample.c .......resample created: high qualiy, large filter, in/out rate=16000/8000
17:24:07.368 pjsua_media.c ......Audio updated, stream #0: PCMU (sendrecv)
17:24:07.368 pjsua_media.c .......Media stream call00:1 is destroyed
17:24:07.368 udp0xb310c400 ......UDP media transport started
17:24:07.368 pjsua_vid.c ......Video channel update..
17:24:07.369 openh264.cpp .......Opening codec..
17:24:07.387 strm0xb311f31c !Start talksprut..
17:24:07.399 rtp.c !.......pjmedia_rtp_session_init: ses=0xb3125894, default_pt=96, ssrc=0x6b4c4922
17:24:07.399 rtp.c .......pjmedia_rtp_session_init2: ses=0xb3125894, seq=9080, ts=0, peer_ssrc=0
17:24:07.399 vstdec0xb3124e .......Decoding channel created 720x480 I420<-H264 22/1(~22)fps
17:24:07.399 rtp.c .......pjmedia_rtp_session_init: ses=0xb3125950, default_pt=96, ssrc=0x6b4c4922
17:24:07.399 rtp.c .......pjmedia_rtp_session_init2: ses=0xb3125950, seq=27137, ts=0, peer_ssrc=0
17:24:07.399 vstenc0xb3124e .......Encoding channel created 192x128 I420->H264 15/1(~15)fps
17:24:07.399 vstdec0xb3124e .......Jitter buffer reset
17:24:07.399 udp0xb310c400 .......SO_RCVBUF set to 180224
17:24:07.399 udp0xb310c400 .......SO_SNDBUF set to 180224
17:24:07.399 udp0xb310c400 .......UDP media transport attached
17:24:07.400 vstrm0xb3124ee .......Receive RTCP-FB PLI
17:24:07.400 vid_stream.c .......Video stream vstrm0xb3124ee4 created
17:24:07.400 vstenc0xb3124e .......Encoder stream started
17:24:07.400 vstdec0xb3124e .......Decoder stream started
17:24:07.400 pjsua_vid.c .......Setting up RX..
17:24:07.400 pjsua_vid.c ........Creating video window: type=stream, cap_id=-1, rend_id=-2
17:24:07.400 vid_port.c .........Opening device SDL renderer [SDL] for render: format=I420, size=720x480 @22:1 fps
17:24:07.416 vid_port.c .........Device SDL renderer [SDL] opened: format=I420, size=720x480 @22:1 fps
17:24:07.419 vid_conf.c .........Add port SDL renderer requested
17:24:07.422 vid_conf.c .........Added port 0 (SDL renderer)
17:24:07.422 pjsua_vid.c .........stream window id 0 created for cap_dev=-1 rend_dev=-2
17:24:07.422 pjsua_vid.c .........Window 0 created
17:24:07.422 vid_conf.c ........Add port vstdec0xb3124ee4 requested
17:24:07.425 vid_conf.c ........Added port 1 (vstdec0xb3124ee4)
17:24:07.425 vid_conf.c ........Connect ports 1->0 requested
17:24:07.425 vid_conf.c ........Video connect ports 1->0 queued
17:24:07.426 sdl_dev.c ........Starting sdl video stream
17:24:07.426 vid_conf.c .......Add port vstenc0xb3124ee4 requested
17:24:07.427 vid_conf.c .......Added port 2 (vstenc0xb3124ee4)
17:24:07.427 pjsua_vid.c .......Setting up TX..
17:24:07.427 vstenc0xb3124e .......Encoder stream paused
17:24:07.427 pjsua_media.c ......Video updated, stream #1: H264 (sendrecv)
17:24:07.427 pjsua_app.c .....Call 0 media 0 [type=audio], status is Active
17:24:07.427 pjsua_aud.c .....Conf disconnect: 4 -x- 0
17:24:07.427 conference.c ......Port 4 (ring) stop transmitting to port 0 (Master/sound)
17:24:07.427 pjsua_aud.c .....Conf connect: 5 --> 2
17:24:07.427 conference.c ......Port 5 (sip:8002@192.168.2.99) transmitting to port 2 (rec.wav)
17:24:07.427 pjsua_aud.c .....Conf connect: 1 --> 5
17:24:07.427 conference.c ......Port 1 (t.wav) transmitting to port 5 (sip:8002@192.168.2.99)
17:24:07.427 pjsua_app.c .....Call 0 media 1 [type=video], status is Active
17:24:07.427 inv0xb3100c6c ....Sending Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c)
17:24:07.427 dlg0xb3100c6c .....Sending Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c)
17:24:07.427 tsx0xb3106514 .....Sending Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) in state Proceeding
17:24:07.427 tdta0xb31188ac ......Destroying txdata Response msg 100/INVITE/cseq=20 (tdta0xb31188ac)
17:24:07.427 pjsua_core.c ......TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:07.427 tsx0xb3106514 ......State changed from Proceeding to Completed, event=TX_MSG
17:24:07.427 dlg0xb3100c6c .......Transaction tsx0xb3106514 state changed to Completed
17:24:07.427 pjsua_app.c .........Call 0 state changed to CONNECTING
17:24:07.442 vid_conf.c !Updating render state for port id 0 (1 sources)..
17:24:07.442 vid_conf.c This port only has single source with matched format & size, no conversion needed
17:24:07.442 vid_conf.c Port 1 (vstdec0xb3124ee4) transmitting to port 0 (SDL renderer)
17:24:07.451 strm0xb311f31c !Resetting jitter buffer in stream playback start
17:24:07.451 strm0xb311f31c Jitter buffer reset
17:24:07.470 strm0xb311f31c Jitter buffer empty (prefetch=0)
17:24:07.927 tsx0xb3106514 !Retransmit timer event
17:24:07.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=0, restart?=1
17:24:07.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:08.010 strm0xb311f31c !VAD re-enabled
17:24:08.010 silencedet.c Starting talk burst (level=6602 threshold=1000)
17:24:08.927 tsx0xb3106514 !Retransmit timer event
17:24:08.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=1, restart?=1
17:24:08.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:09.287 wav_player.c !File port t.wav EOF
17:24:09.287 wav_player.c File port t.wav rewinding..
17:24:10.927 tsx0xb3106514 !Retransmit timer event
17:24:10.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=2, restart?=1
17:24:10.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:11.126 wav_player.c !File port t.wav EOF
17:24:11.126 wav_player.c File port t.wav rewinding..
17:24:12.966 wav_player.c File port t.wav EOF
17:24:12.966 wav_player.c File port t.wav rewinding..
17:24:14.786 wav_player.c File port t.wav EOF
17:24:14.786 wav_player.c File port t.wav rewinding..
17:24:14.928 tsx0xb3106514 !Retransmit timer event
17:24:14.928 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=3, restart?=1
17:24:14.928 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:16.627 wav_player.c !File port t.wav EOF
17:24:16.627 wav_player.c File port t.wav rewinding..
17:24:18.466 wav_player.c File port t.wav EOF
17:24:18.466 wav_player.c File port t.wav rewinding..
17:24:18.927 tsx0xb3106514 !Retransmit timer event
17:24:18.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=4, restart?=1
17:24:18.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:20.226 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:20.226 tdta0xb311a7f4 Destroying txdata raw
17:24:20.286 wav_player.c !File port t.wav EOF
17:24:20.286 wav_player.c File port t.wav rewinding..
17:24:22.126 wav_player.c File port t.wav EOF
17:24:22.126 wav_player.c File port t.wav rewinding..
17:24:22.927 tsx0xb3106514 !Retransmit timer event
17:24:22.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=5, restart?=1
17:24:22.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:23.966 wav_player.c !File port t.wav EOF
17:24:23.966 wav_player.c File port t.wav rewinding..
17:24:25.787 wav_player.c File port t.wav EOF
17:24:25.787 wav_player.c File port t.wav rewinding..
17:24:26.927 tsx0xb3106514 !Retransmit timer event
17:24:26.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=6, restart?=1
17:24:26.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:27.626 wav_player.c !File port t.wav EOF
17:24:27.626 wav_player.c File port t.wav rewinding..
17:24:29.466 wav_player.c File port t.wav EOF
17:24:29.466 wav_player.c File port t.wav rewinding..
17:24:30.927 tsx0xb3106514 !Retransmit timer event
17:24:30.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=7, restart?=1
17:24:30.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:31.286 wav_player.c !File port t.wav EOF
17:24:31.286 wav_player.c File port t.wav rewinding..
17:24:33.126 wav_player.c File port t.wav EOF
17:24:33.126 wav_player.c File port t.wav rewinding..
17:24:33.227 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:33.227 tdta0xb311a7f4 Destroying txdata raw
17:24:34.927 tsx0xb3106514 Retransmit timer event
17:24:34.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=8, restart?=1
17:24:34.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:34.966 wav_player.c !File port t.wav EOF
17:24:34.966 wav_player.c File port t.wav rewinding..
17:24:36.787 wav_player.c File port t.wav EOF
17:24:36.787 wav_player.c File port t.wav rewinding..
17:24:38.626 wav_player.c File port t.wav EOF
17:24:38.626 wav_player.c File port t.wav rewinding..
17:24:38.927 tsx0xb3106514 !Retransmit timer event
17:24:38.927 tsx0xb3106514 .Retransmiting Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c), count=9, restart?=1
17:24:38.927 pjsua_core.c .TX 1222 bytes Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c) to UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.99:5060;rport=5060;received=192.168.2.99;branch=z9hG4bK2117697895
Via: SIP/2.0/UDP 192.168.2.98:5060;rport=5060;branch=z9hG4bK2041485073
Record-Route: sip:8000@192.168.2.99:5060;lr
Call-ID: 202303011724071863651588
From: sip:8002@192.168.2.99;tag=1387706780
To: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
CSeq: 20 INVITE
Contact: sip:1000%231@192.168.2.140:10000;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 575

v=0
o=- 3886676647 3886676648 IN IP4 192.168.2.140
s=pjmedia
b=AS:352
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 101 0
c=IN IP4 192.168.2.140
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.140
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ssrc:1538779281 cname:1cef2e1c1239c776
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 4002 RTP/AVP 96
c=IN IP4 192.168.2.140
b=TIAS:256000
a=rtcp:4003 IN IP4 192.168.2.140
a=sendrecv
a=ssrc:1800161570 cname:1cef2e1c1239c776
a=rtcp-fb:* nack pli
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=42000a; packetization-mode=0

--end msg--
17:24:39.428 tsx0xb3106514 Timeout timer event
17:24:39.428 tsx0xb3106514 .State changed from Completed to Terminated, event=TIMER
17:24:39.428 dlg0xb3100c6c ..Transaction tsx0xb3106514 state changed to Terminated
17:24:39.428 endpoint ...Request msg BYE/cseq=15967 (tdta0xb311a7f4) created.
17:24:39.428 inv0xb3100c6c ....Sending Request msg BYE/cseq=15967 (tdta0xb311a7f4)
17:24:39.428 dlg0xb3100c6c .....Sending Request msg BYE/cseq=15967 (tdta0xb311a7f4)
17:24:39.428 tsx0xb31035c4 ......Transaction created for Request msg BYE/cseq=15967 (tdta0xb311a7f4)
17:24:39.428 tsx0xb31035c4 .....Sending Request msg BYE/cseq=15967 (tdta0xb311a7f4) in state Null
17:24:39.428 sip_resolve.c ......Target '192.168.2.99:5060' type=Unspecified resolved to '192.168.2.99:5060' type=UDP (UDP transport)
17:24:39.428 pjsua_core.c ......TX 385 bytes Request msg BYE/cseq=15967 (tdta0xb311a7f4) to UDP 192.168.2.99:5060:
BYE sip:8002@192.168.2.98:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPjd6d7bf04-ffa8-4ffc-afc8-a21070f48589
Max-Forwards: 70
From: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
To: sip:8002@192.168.2.99;tag=1387706780
Call-ID: 202303011724071863651588
CSeq: 15967 BYE
Route: sip:8000@192.168.2.99:5060;lr
Content-Length: 0

--end msg--
17:24:39.428 tsx0xb31035c4 ......State changed from Null to Calling, event=TX_MSG
17:24:39.428 dlg0xb3100c6c .......Transaction tsx0xb31035c4 state changed to Calling
17:24:39.428 tsx0xb3106514 Timeout timer event
17:24:39.428 tsx0xb3106514 .State changed from Terminated to Destroyed, event=TIMER
17:24:39.428 tdta0xb311ca0c ..Destroying txdata Response msg 200/INVITE/cseq=20 (tdta0xb311ca0c)
17:24:39.428 tsx0xb3106514 Transaction destroyed!
17:24:39.430 sip_endpoint.c Processing incoming message: Response msg 200/BYE/cseq=15967 (rdata0xb3101424)
17:24:39.430 pjsua_core.c .RX 344 bytes Response msg 200/BYE/cseq=15967 (rdata0xb3101424) from UDP 192.168.2.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.140:10000;rport=10000;branch=z9hG4bKPjd6d7bf04-ffa8-4ffc-afc8-a21070f48589
From: sip:1000@192.168.2.99;tag=84a5c6b3-694b-4adb-8cd6-8942dfafd39c
To: sip:8002@192.168.2.99;tag=1387706780
Call-ID: 202303011724071863651588
CSeq: 15967 BYE
User-Agent: Dahua UAS/3.0 VTO3221D
Content-Length: 0

--end msg--
17:24:39.430 tsx0xb31035c4 .Incoming Response msg 200/BYE/cseq=15967 (rdata0xb3101424) in state Calling
17:24:39.430 tsx0xb31035c4 ..State changed from Calling to Completed, event=RX_MSG
17:24:39.430 dlg0xb3100c6c ...Received Response msg 200/BYE/cseq=15967 (rdata0xb3101424)
17:24:39.430 dlg0xb3100c6c ...Transaction tsx0xb31035c4 state changed to Completed
17:24:39.430 pjsua_media.c .....Call 0: deinitializing media..
17:24:39.431 pjsua_media.c ......
[DISCONNECTED] To: sip:8002@192.168.2.99;tag=1387706780
Call time: 00h:00m:00s, 1st res in 6 ms, conn in 0ms
#0 audio PCMU @8khz, sendrecv, peer=-
SRTP status: Not active Crypto-suite:
RX pt=0, last update:00h:00m:00.717s ago
total 0pkt 0B (0B +IP hdr) @AvG=0bps/0bps
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.000 0.000 0.000 0.000 0.000
TX pt=0, ptime=20, last update:never
total 1.6Kpkt 256.3KB (320.4KB +IP hdr) @AvG=63.9Kbps/79.9Kbps
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.000 0.000 0.000 0.000 0.000
RTT msec : 0.000 0.000 0.000 0.000 0.000
#1 video H264, sendrecv, peer=-
SRTP status: Not active Crypto-suite:
RX pt=96, size=720x480, fps=22.00, last update:00h:00m:32.031s ago
total 0pkt 0B (0B +IP hdr) @AvG=0bps/0bps
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.000 0.000 0.000 0.000 0.000
TX pt=96, size=192x128, fps=15.00, last update:never
total 0pkt 0B (0B +IP hdr) @AvG=0bps/0bps
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.000 0.000 0.000 0.000 0.000
RTT msec : 0.000 0.000 0.000 0.000 0.000
17:24:39.434 udp0xb310bda8 .......UDP media transport detached
17:24:39.434 strm0xb311f31c .......JB summary:
size=0/eff=0 prefetch=0 level=0
delay (min/max/avg/dev)=0/0/0/0 ms
burst (min/max/avg/dev)=0/0/0/0 frames
lost=0 discard=0 empty=1599
17:24:39.434 pjsua_media.c .......Media stream call00:0 is destroyed
17:24:39.434 pjsua_vid.c .......Stopping video stream..
17:24:39.434 sdl_dev.c ........Stopping sdl video stream
17:24:39.434 pjsua_vid.c ........Window 0: destroying..
17:24:39.434 vid_conf.c .........Port 0 remove requested
17:24:39.434 vid_conf.c .........Video port 0 remove queued
17:24:39.434 sdl_dev.c .........Stopping sdl video stream
17:24:39.434 vid_port.c .........Destroy request on SDL renderer..
17:24:39.434 pjsua_vid.c ........Call 0 media 1: Stream video window released
17:24:39.434 vid_conf.c ........Port 2 remove requested
17:24:39.434 vid_conf.c ........Video port 2 remove queued
17:24:39.434 vid_conf.c ........Port 1 remove requested
17:24:39.434 vid_conf.c ........Video port 1 remove queued
17:24:39.434 vid_stream.c ........Destroy request on vstrm0xb3124ee4..
17:24:39.434 udp0xb310c400 ........UDP media transport detached
17:24:39.434 pjsua_media.c .......Media stream call00:1 is destroyed
17:24:39.434 udp0xb310bda8 ......UDP media transport already stopped
17:24:39.434 udp0xb310bda8 ......UDP media transport destroyed
17:24:39.434 udp0xb310c400 ......UDP media transport already stopped
17:24:39.435 udp0xb310c400 ......UDP media transport destroyed
17:24:39.435 pjsua_app.c .....Call 0 is DISCONNECTED [reason=408 (Request Timeout)]
17:24:39.435 dlg0xb3100c6c .....Session count dec to 1 by mod-invite
17:24:39.441 vid_conf.c !Port 1 (vstdec0xb3124ee4) stop transmitting to port 0 (SDL renderer)
17:24:39.441 vid_conf.c Removed port 0 (SDL renderer)
17:24:39.441 vid_port.c Destroying SDL renderer..
17:24:39.441 sdl_dev.c Stopping sdl video stream
17:24:39.442 vid_conf.c Removed port 2 (vstenc0xb3124ee4)
17:24:39.442 vid_conf.c Removed port 1 (vstdec0xb3124ee4)
17:24:39.442 vid_stream.c Destroying vstrm0xb3124ee4..
17:24:39.444 vstdec0xb3124e JB summary:
size=0/eff=0 prefetch=0 level=0
delay (min/max/avg/dev)=0/0/0/0 ms
burst (min/max/avg/dev)=0/0/0/0 frames
lost=0 discard=0 empty=0
17:24:40.434 pjsua_aud.c !Closing sound device after idle for 1 second(s)
17:24:40.434 pjsua_app.c .Turning sound device -99 -99 OFF
17:24:40.434 pjsua_aud.c .Closing null sound device..
17:24:44.430 tsx0xb31035c4 Timeout timer event
17:24:44.430 tsx0xb31035c4 .State changed from Completed to Terminated, event=TIMER
17:24:44.430 dlg0xb3100c6c ..Transaction tsx0xb31035c4 state changed to Terminated
17:24:44.430 dlg0xb3100c6c ...Dialog destroyed!
17:24:44.430 tsx0xb31035c4 Timeout timer event
17:24:44.430 tsx0xb31035c4 .State changed from Terminated to Destroyed, event=TIMER
17:24:44.430 tdta0xb311a7f4 ..Destroying txdata Request msg BYE/cseq=15967 (tdta0xb311a7f4)
17:24:44.430 tsx0xb31035c4 Transaction destroyed!
17:24:46.228 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 192.168.2.99:5060
17:24:46.228 tdta0xb31590cc Destroying txdata raw
17:24:52.263 pjsua_aud.c !Destroying player 0..
17:24:52.263 pjsua_aud.c Destroying recorder 0..
17:24:52.263 pjsua_core.c Shutting down, flags=0...
17:24:52.263 pjsua_core.c PJSUA state changed: RUNNING --> CLOSING
17:24:52.269 pjsua_call.c .Hangup all calls..
17:24:52.269 pjsua_media.c .Call 0: deinitializing media..
17:24:52.269 pjsua_media.c .Call 1: deinitializing media..
17:24:52.269 pjsua_media.c .Call 2: deinitializing media..
17:24:52.269 pjsua_media.c .Call 3: deinitializing media..
17:24:52.269 pjsua_pres.c .Shutting down presence..
17:24:52.269 pjsua_acc.c .Acc 2: setting unregistration..
17:24:52.270 endpoint ..Request msg REGISTER/cseq=29552 (tdta0x1425174) created.
17:24:52.270 tsx0x142617c ...Transaction created for Request msg REGISTER/cseq=29553 (tdta0x1425174)
17:24:52.270 tsx0x142617c ..Sending Request msg REGISTER/cseq=29553 (tdta0x1425174) in state Null
17:24:52.270 sip_resolve.c ...DNS resolver not available, target 'VTO-GATE.local:0' type=Unspecified will be resolved with getaddrinfo()
17:24:52.380 sip_resolve.c ...Target 'VTO-GATE.local:0' type=Unspecified resolved to '192.168.2.99:5060' type=UDP (UDP transport)
17:24:52.380 pjsua_core.c ...TX 475 bytes Request msg REGISTER/cseq=29553 (tdta0x1425174) to UDP 192.168.2.99:5060:
REGISTER sip:VTO-GATE.local SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPj3f15774a-e311-44e5-9025-25b9cb01c549
Max-Forwards: 70
From: sip:1000%231@192.168.2.140;tag=1e4a1d50-cd49-4caa-9374-bd44ee0accae
To: sip:1000%231@192.168.2.140
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29553 REGISTER
User-Agent: PJSUA v2.13-dev Linux-5.15.84/armv7l/glibc-2.31
Contact: sip:1000%231@192.168.2.140:10000;ob
Expires: 0
Content-Length: 0

--end msg--
17:24:52.380 tsx0x142617c ...State changed from Null to Calling, event=TX_MSG
17:24:52.380 pjsua_acc.c ..Acc 2: Unregistration sent
17:24:52.880 tsx0x142617c .Retransmit timer event
17:24:52.880 tsx0x142617c ..Retransmiting Request msg REGISTER/cseq=29553 (tdta0x1425174), count=0, restart?=1
17:24:52.880 pjsua_core.c ..TX 475 bytes Request msg REGISTER/cseq=29553 (tdta0x1425174) to UDP 192.168.2.99:5060:
REGISTER sip:VTO-GATE.local SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPj3f15774a-e311-44e5-9025-25b9cb01c549
Max-Forwards: 70
From: sip:1000%231@192.168.2.140;tag=1e4a1d50-cd49-4caa-9374-bd44ee0accae
To: sip:1000%231@192.168.2.140
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29553 REGISTER
User-Agent: PJSUA v2.13-dev Linux-5.15.84/armv7l/glibc-2.31
Contact: sip:1000%231@192.168.2.140:10000;ob
Expires: 0
Content-Length: 0

--end msg--
17:24:53.880 tsx0x142617c .Retransmit timer event
17:24:53.880 tsx0x142617c ..Retransmiting Request msg REGISTER/cseq=29553 (tdta0x1425174), count=1, restart?=1
17:24:53.880 pjsua_core.c ..TX 475 bytes Request msg REGISTER/cseq=29553 (tdta0x1425174) to UDP 192.168.2.99:5060:
REGISTER sip:VTO-GATE.local SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPj3f15774a-e311-44e5-9025-25b9cb01c549
Max-Forwards: 70
From: sip:1000%231@192.168.2.140;tag=1e4a1d50-cd49-4caa-9374-bd44ee0accae
To: sip:1000%231@192.168.2.140
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29553 REGISTER
User-Agent: PJSUA v2.13-dev Linux-5.15.84/armv7l/glibc-2.31
Contact: sip:1000%231@192.168.2.140:10000;ob
Expires: 0
Content-Length: 0

--end msg--
17:24:55.880 tsx0x142617c .Retransmit timer event
17:24:55.880 tsx0x142617c ..Retransmiting Request msg REGISTER/cseq=29553 (tdta0x1425174), count=2, restart?=1
17:24:55.880 pjsua_core.c ..TX 475 bytes Request msg REGISTER/cseq=29553 (tdta0x1425174) to UDP 192.168.2.99:5060:
REGISTER sip:VTO-GATE.local SIP/2.0
Via: SIP/2.0/UDP 192.168.2.140:10000;rport;branch=z9hG4bKPj3f15774a-e311-44e5-9025-25b9cb01c549
Max-Forwards: 70
From: sip:1000%231@192.168.2.140;tag=1e4a1d50-cd49-4caa-9374-bd44ee0accae
To: sip:1000%231@192.168.2.140
Call-ID: 0f19d130-a51c-41f0-b045-6483bad79ef0
CSeq: 29553 REGISTER
User-Agent: PJSUA v2.13-dev Linux-5.15.84/armv7l/glibc-2.31
Contact: sip:1000%231@192.168.2.140:10000;ob
Expires: 0
Content-Length: 0

--end msg--
17:24:56.456 pjsua_core.c .Destroying...
17:24:56.456 sip_transport. .Transport udp0x141fe00 shutting down, force=0
17:24:56.456 tcptp:10000 .SIP TCP transport destroyed
17:24:56.456 pjsua_media.c .Shutting down media..
17:24:56.466 pjsua_vid.c ..Destroying video subsystem..
17:24:56.466 pjsua_vid.c ...Window 0: destroying..
17:24:56.466 pjsua_vid.c ...Window 1: destroying..
17:24:56.466 pjsua_vid.c ...Window 2: destroying..
17:24:56.466 pjsua_vid.c ...Window 3: destroying..
17:24:56.466 pjsua_vid.c ...Window 4: destroying..
17:24:56.466 pjsua_vid.c ...Window 5: destroying..
17:24:56.466 pjsua_vid.c ...Window 6: destroying..
17:24:56.466 pjsua_vid.c ...Window 7: destroying..
17:24:56.466 pjsua_vid.c ...Window 8: destroying..
17:24:56.466 pjsua_vid.c ...Window 9: destroying..
17:24:56.466 pjsua_vid.c ...Window 10: destroying..
17:24:56.466 pjsua_vid.c ...Window 11: destroying..
17:24:56.466 pjsua_vid.c ...Window 12: destroying..
17:24:56.466 pjsua_vid.c ...Window 13: destroying..
17:24:56.466 pjsua_vid.c ...Window 14: destroying..
17:24:56.466 pjsua_vid.c ...Window 15: destroying..
17:24:56.466 vid_conf.c ...Video conference bridge destroy requested
17:24:56.474 vid_conf.c ...Video conference bridge destroyed
17:24:56.475 sip_endpoint.c .Destroying endpoint instance..
17:24:56.475 sip_transactio .Stopping transaction layer module
17:24:56.475 tsx0x142617c .Request to terminate transaction
17:24:56.475 tsx0x142617c ..State changed from Calling to Terminated, event=USER
17:24:56.475 pjsua_acc.c ....SIP registration failed, status=503 (Service Unavailable)
17:24:56.475 pjsua_acc.c ....Scheduling re-registration retry for acc 2 in 60 seconds..
17:24:56.475 tdta0x1425174 .Destroying txdata Request msg REGISTER/cseq=29553 (tdta0x1425174)
17:24:56.475 tsx0x142617c .Transaction destroyed!
17:24:56.475 sip_transactio .Stopped transaction layer module
17:24:56.475 sip_endpoint.c .Module "mod-default-handler" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-unsolicited-mwi" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-pjsua-options" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-pjsua-im" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-pjsua-pres" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-pjsua" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-stateful-util" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-refer" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-mwi" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-presence" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-evsub" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-invite" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-100rel" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-ua" unregistered
17:24:56.475 sip_transactio .Transaction layer module destroyed
17:24:56.475 sip_endpoint.c .Module "mod-tsx-layer" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-msg-print" unregistered
17:24:56.475 sip_endpoint.c .Module "mod-pjsua-log" unregistered
17:24:56.475 sip_transport. .Destroying transport manager
17:24:56.477 udp0x141fe00 .SIP UDP transport destroyed
17:24:56.477 timer.c .Dumping timer heap:
17:24:56.477 timer.c . Cur size: 1 entries, max: 3070
17:24:56.477 timer.c . Entries:
17:24:56.477 timer.c . _id Id Elapsed Source
17:24:56.477 timer.c . ----------------------------------
17:24:56.477 timer.c . 6 1 60.011 ../src/pjsua-lib/pjsua_acc.c:4104
17:24:56.477 sip_endpoint.c .Endpoint 0x13d2e44 destroyed
17:24:56.477 pjsua_core.c .PJSUA state changed: CLOSING --> NULL
17:24:56.477 pjsua_core.c .PJSUA destroyed...

@trengginas
Copy link
Member

You can see from the call stat: (no received packet)

Call time: 00h:00m:00s, 1st res in 6 ms, conn in 0ms
#0 audio PCMU https://github.com/8khz, sendrecv, peer=-
SRTP status: Not active Crypto-suite:
RX pt=0, last update:00h:00m:00.717s ago
total 0pkt 0B (0B +IP hdr) @AvG=0bps/0bps
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.000 0.000 0.000 0.000 0.000

Refer to https://docs.pjsip.org/en/latest/specific-guides/index.html#audio-troubleshooting on how to troubleshoot

@altserg
Copy link
Author

altserg commented Mar 2, 2023

I'm not an expert in SIP protocol. Can you confirm my observations from the log:

  1. SDP codec negotiation is OK (Looks like it's OK)
  2. SIP signalling sequence from Dahua is OK ? (Not clear to me)
  3. pjsua command line options used are OK ?
    ./pjsua --registrar="sip:VTO-GATE.local"
    --id="sip:1000#1@192.168.2.140"
    --realm="VDP"
    --username="XXX"
    --password="XXX"
    --disable-stun
    --log-file="log.txt"
    --log-level=6
    --app-log-level=3
    --null-audio
    --play-file="t.wav"
    --auto-play
    --rec-file="rec.wav"
    --auto-rec
    --no-vad
    --local-port=10000
    --rtp-port=20000
    --auto-answer=200
    --video

@trengginas
Copy link
Member

There seems no issue with the SIP messages flow.
Again, refer to https://docs.pjsip.org/en/latest/specific-guides/index.html#audio-troubleshooting on how to troubleshoot

Currently the issue page is for bug report or feature request only. For further questions, please use any other means such as StackOverflow.

@trengginas trengginas closed this as not planned Won't fix, can't repro, duplicate, stale Mar 3, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants