Error 503 after one outgoing call

Tenere_750 · February 3, 2017, 8:33pm

Good evening everyone,

after my initial problems solved I have now a confusing problem: after exact one outbound call every other outgoing call is rejected with error 503.

-- Executing [01733538874@internalsip:1] Dial("PJSIP/21-00000000", "PJSIP/03_Telekom_out/sip:01234567890@tel.t-online.de,60") in new stack
-- Called PJSIP/03_Telekom_Joerg_out/sip:01234567890@tel.t-online.de
-- PJSIP/03_Telekom_Joerg_022528301471_out-00000001 is making progress passing it to PJSIP/21-00000000
   > 0x75f112b8 -- Probation passed - setting RTP source address to 192.168.178.57:5012
   > 0x75f1ae78 -- Probation passed - setting RTP source address to 217.0.7.71:25450
-- PJSIP/03_Telekom_out-00000001 is making progress passing it to PJSIP/21-00000000
-- PJSIP/03_Telekom_out-00000001 is ringing
-- Spawn extension (internalsip, 01234567890, 1) exited non-zero on 'PJSIP/21-00000000'

-- Executing [01234567890@internalsip:1] Dial("PJSIP/21-00000002", "PJSIP/03_Telekom_out/sip:01234567890@tel.t-online.de,60") in new stack
-- Called PJSIP/03_Telekom_out/sip:01234567890@tel.t-online.de
-- Everyone is busy/congested at this time (1:0/1/0)
-- Executing [01234567890@internalsip:2] Hangup("PJSIP/21-00000002", "") in new stack
-- Spawn extension (internalsip, 01234567890, 2) exited non-zero on 'PJSIP/21-00000002'

Any ideas??

david551 · February 3, 2017, 9:53pm

Your log does not provide any evidence of a 503.

It looks like the called party rejected the call. You need to provide SIP debugging output.

Tenere_750 · February 4, 2017, 9:16am

503 is what my SIP phone tells me…
The same error is on display when I try to make an internal call.

Please find attached a debug log from internal call (to 21) and external (to 01234567890).
As I am not able to post it directly (more than 2 links…) I hat to replace the @ with +at+

<— Received SIP request (1000 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:12+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bKc228021d14185d16de8776222f565505;rport
From: “21” sip:21+at+domain.local;tag=4245688262
To: sip:12+at+domain.local;user=phone
Call-ID: 2448109767+at+192_168_178_57
CSeq: 2 INVITE
Contact: sip:21+at+192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5004 70 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5004 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

<— Transmitting SIP response (535 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bKc228021d14185d16de8776222f565505
Call-ID: 2448109767+at+192_168_178_57
From: “21” sip:21+at+domain.local;tag=4245688262
To: sip:12+at+domain.local;user=phone;tag=z9hG4bKc228021d14185d16de8776222f565505
CSeq: 2 INVITE
WWW-Authenticate: Digest realm=“domain.local”,nonce=“1486165253/2fa062998254ddc6a4c63ad15eff2dc0”,opaque=“6745de2467e8da5d”,algorithm=md5,qop=“auth”
Server: Rambla
Content-Length: 0

<— Received SIP request (451 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:12+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bKc228021d14185d16de8776222f565505;rport
From: “21” sip:21+at+domain.local;tag=4245688262
To: sip:12+at+domain.local;user=phone;tag=z9hG4bKc228021d14185d16de8776222f565505
Call-ID: 2448109767+at+192_168_178_57
CSeq: 2 ACK
Contact: sip:21+at+192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

<— Received SIP request (1309 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:12+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK7696fd721cecce5c60b936bd1bdb73a1;rport
From: “21” sip:21+at+domain.local;tag=4245688262
To: sip:12+at+domain.local;user=phone
Call-ID: 2448109767+at+192_168_178_57
CSeq: 3 INVITE
Contact: sip:21+at+192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:12+at+domain.local;user=phone”, nonce=“1486165253/2fa062998254ddc6a4c63ad15eff2dc0”, nc=00000001, cnonce=“812cf3eda551d28a9ea6c593b46d301b”, opaque=“6745de2467e8da5d”, response=“05d7b07d81cd22aea0d8ffeba604a12f”
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5004 70 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5004 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

<— Transmitting SIP response (331 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK7696fd721cecce5c60b936bd1bdb73a1
Call-ID: 2448109767+at+192_168_178_57
From: “21” sip:21+at+domain.local;tag=4245688262
To: sip:12+at+domain.local;user=phone
CSeq: 3 INVITE
Server: Rambla
Content-Length: 0

-- Executing [12+at+internalsip:1] Dial("PJSIP/21-0000000a", "PJSIP/03_Telekom_Joerg_022528301471_out/sip:0225212+at+tel.t-online.de,60") in new stack
-- Called PJSIP/03_Telekom_Joerg_022528301471_out/sip:0225212+at+tel.t-online.de
== Everyone is busy/congested at this time (1:0/1/0)
– Executing [12+at+internalsip:2] Hangup(“PJSIP/21-0000000a”, “”) in new stack
== Spawn extension (internalsip, 12, 2) exited non-zero on ‘PJSIP/21-0000000a’
<— Transmitting SIP response (409 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK7696fd721cecce5c60b936bd1bdb73a1
Call-ID: 2448109767+at+192_168_178_57
From: “21” sip:21+at+domain.local;tag=4245688262
To: sip:12+at+domain.local;user=phone;tag=ae222e10-70dc-4b1b-9d79-dad487dae980
CSeq: 3 INVITE
Server: Rambla
Reason: Q.850;cause=34
Content-Length: 0

<— Received SIP request (757 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:12+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK7696fd721cecce5c60b936bd1bdb73a1;rport
From: “21” sip:21+at+domain.local;tag=4245688262
To: sip:12+at+domain.local;user=phone;tag=ae222e10-70dc-4b1b-9d79-dad487dae980
Call-ID: 2448109767+at+192_168_178_57
CSeq: 3 ACK
Contact: sip:21+at+192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:12+at+domain.local;user=phone”, nonce=“1486165253/2fa062998254ddc6a4c63ad15eff2dc0”, nc=00000001, cnonce=“812cf3eda551d28a9ea6c593b46d301b”, opaque=“6745de2467e8da5d”, response=“05d7b07d81cd22aea0d8ffeba604a12f”
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

<— Received SIP request (1017 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:01234567890+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK1ecec7fe9ec76576d21f9cebef5bdb8;rport
From: “21” sip:21+at+domain.local;tag=2336928485
To: sip:01234567890+at+domain.local;user=phone
Call-ID: 3184192503+at+192_168_178_57
CSeq: 2 INVITE
Contact: sip:21+at+192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5006 71 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5006 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

<— Transmitting SIP response (542 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK1ecec7fe9ec76576d21f9cebef5bdb8
Call-ID: 3184192503+at+192_168_178_57
From: “21” sip:21+at+domain.local;tag=2336928485
To: sip:01234567890+at+domain.local;user=phone;tag=z9hG4bK1ecec7fe9ec76576d21f9cebef5bdb8
CSeq: 2 INVITE
WWW-Authenticate: Digest realm=“domain.local”,nonce=“1486165259/c1a04708a693af52f9a5fc8b45696885”,opaque=“71d2cfc83297f7d6”,algorithm=md5,qop=“auth”
Server: Rambla
Content-Length: 0

<— Received SIP request (467 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:01234567890+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK1ecec7fe9ec76576d21f9cebef5bdb8;rport
From: “21” sip:21+at+domain.local;tag=2336928485
To: sip:01234567890+at+domain.local;user=phone;tag=z9hG4bK1ecec7fe9ec76576d21f9cebef5bdb8
Call-ID: 3184192503+at+192_168_178_57
CSeq: 2 ACK
Contact: sip:21+at+192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

<— Received SIP request (1332 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:01234567890+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK39bc5d3a2c2fca9d41a81e4ba7fdd;rport
From: “21” sip:21+at+domain.local;tag=2336928485
To: sip:01234567890+at+domain.local;user=phone
Call-ID: 3184192503+at+192_168_178_57
CSeq: 3 INVITE
Contact: sip:21+at+192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:01234567890+at+domain.local;user=phone”, nonce=“1486165259/c1a04708a693af52f9a5fc8b45696885”, nc=00000001, cnonce=“bc90bc01cf2d195ac0ae515d9b5d856”, opaque=“71d2cfc83297f7d6”, response=“8dc702a440f678d14b0213d8ea7c47b7”
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5006 71 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5006 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

<— Transmitting SIP response (337 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK39bc5d3a2c2fca9d41a81e4ba7fdd
Call-ID: 3184192503+at+192_168_178_57
From: “21” sip:21+at+domain.local;tag=2336928485
To: sip:01234567890+at+domain.local;user=phone
CSeq: 3 INVITE
Server: Rambla
Content-Length: 0

-- Executing [01234567890+at+internalsip:1] Dial("PJSIP/21-0000000c", "PJSIP/03_Telekom_Joerg_022528301471_out/sip:01234567890+at+tel.t-online.de,60") in new stack
-- Called PJSIP/03_Telekom_Joerg_022528301471_out/sip:01234567890+at+tel.t-online.de
== Everyone is busy/congested at this time (1:0/1/0)
– Executing [01234567890+at+internalsip:2] Hangup(“PJSIP/21-0000000c”, “”) in new stack
== Spawn extension (internalsip, 01234567890, 2) exited non-zero on ‘PJSIP/21-0000000c’
<— Transmitting SIP response (415 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK39bc5d3a2c2fca9d41a81e4ba7fdd
Call-ID: 3184192503+at+192_168_178_57
From: “21” sip:21+at+domain.local;tag=2336928485
To: sip:01234567890+at+domain.local;user=phone;tag=5d128aec-7db0-4d35-aaf2-a273779f8557
CSeq: 3 INVITE
Server: Rambla
Reason: Q.850;cause=34
Content-Length: 0

<— Received SIP request (780 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:01234567890+at+domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK39bc5d3a2c2fca9d41a81e4ba7fdd;rport
From: “21” sip:21+at+domain.local;tag=2336928485
To: sip:01234567890+at+domain.local;user=phone;tag=5d128aec-7db0-4d35-aaf2-a273779f8557
Call-ID: 3184192503+at+192_168_178_57
CSeq: 3 ACK
Contact: sip:21+at+192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:01234567890+at+domain.local;user=phone”, nonce=“1486165259/c1a04708a693af52f9a5fc8b45696885”, nc=00000001, cnonce=“bc90bc01cf2d195ac0ae515d9b5d856”, opaque=“71d2cfc83297f7d6”, response=“8dc702a440f678d14b0213d8ea7c47b7”
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

Best regards,

Joerg

david551 · February 4, 2017, 9:54am

You haven’t included the failing side of the call. It is the outgoing side that is failing. The 503 just indicates there has been a failure.

Tenere_750 · February 4, 2017, 10:06am

I connect to the asterisk server.
Whether internal or external call it doens’t matter.
Please advise what to do to provide you with the necessary logfiles.

david551 · February 4, 2017, 10:18am

https://wiki.asterisk.org/wiki/display/AST/Collecting+Debug+Information

Tenere_750 · February 4, 2017, 10:52am

[Feb 4 11:35:19] Asterisk 13.13.1 built by pi @ raspberrypi on a armv7l running Linux on 2017-01-31 07:52:49 UTC
[Feb 4 11:35:19] DEBUG[19952] config.c: Parsing /etc/asterisk/logger.conf
[Feb 4 11:35:21] DEBUG[15851] res_pjsip_registrar_expire.c: Woke up at 1486204521 Interval: 30
[Feb 4 11:35:21] DEBUG[15851] res_pjsip_registrar_expire.c: Expiring 0 contacts
[Feb 4 11:35:37] DEBUG[19955] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:35:37] DEBUG[15815] threadpool.c: Destroying worker thread 3262
[Feb 4 11:35:37] DEBUG[19956] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:35:37] DEBUG[15815] threadpool.c: Destroying worker thread 3263
[Feb 4 11:35:37] DEBUG[19957] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:35:37] DEBUG[15815] threadpool.c: Destroying worker thread 3264
[Feb 4 11:35:37] DEBUG[19954] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:35:37] DEBUG[15815] threadpool.c: Destroying worker thread 3261
[Feb 4 11:35:37] DEBUG[19953] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:35:37] DEBUG[15815] threadpool.c: Destroying worker thread 3260
[Feb 4 11:35:43] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (503 bytes) from UDP:192.168.178.57:5060 —>
REGISTER sip:domain.local SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK3e412f1a9839a57a6a45eb2121d2806b;rport
From: “Wohnzimmer” sip:21@domain.local;tag=2246956230
To: “Wohnzimmer” sip:21@domain.local
Call-ID: 804778743@192_168_178_57
CSeq: 1839 REGISTER
Contact: sip:21@192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Length: 0

[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=1839 (rdata0x1bc9b24)
[Feb 4 11:35:43] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000020 to use for Request msg REGISTER/cseq=1839 (rdata0x1bc9b24)
[Feb 4 11:35:43] DEBUG[15815] threadpool.c: Increasing threadpool SIP’s size by 5
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:35:43] VERBOSE[19959] res_pjsip_logger.c: <— Transmitting SIP response (541 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK3e412f1a9839a57a6a45eb2121d2806b
Call-ID: 804778743@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=2246956230
To: “Wohnzimmer” sip:21@domain.local;tag=z9hG4bK3e412f1a9839a57a6a45eb2121d2806b
CSeq: 1839 REGISTER
WWW-Authenticate: Digest realm=“domain.local”,nonce=“1486204543/2d4602b90dabc72921faeb9f3d05654a”,opaque=“0b990622498a284e”,algorithm=md5,qop="auth"
Server: Rambla
Content-Length: 0

[Feb 4 11:35:43] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (797 bytes) from UDP:192.168.178.57:5060 —>
REGISTER sip:domain.local SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bKbf3bd13638b7c1d8a14c9a8df336d7e;rport
From: “Wohnzimmer” sip:21@domain.local;tag=2246956230
To: “Wohnzimmer” sip:21@domain.local
Call-ID: 804778743@192_168_178_57
CSeq: 1840 REGISTER
Contact: sip:21@192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:domain.local”, nonce=“1486204543/2d4602b90dabc72921faeb9f3d05654a”, nc=00000001, cnonce=“8b5e8b8d9ed7ec8fa3b78fe3830bad67”, opaque=“0b990622498a284e”, response="a2127b84c77069bacd7fea2fb2b277e1"
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Length: 0

[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=1840 (rdata0x1bc9b24)
[Feb 4 11:35:43] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000020 to use for Request msg REGISTER/cseq=1840 (rdata0x1bc9b24)
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_authenticator_digest.c: Calculated nonce 1486204543/2d4602b90dabc72921faeb9f3d05654a. Actual nonce is 1486204543/2d4602b90dabc72921faeb9f3d05654a
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_registrar.c: Matched id ‘21’ to aor ‘21’
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_registrar.c: Matched aor ‘21’ by To username
[Feb 4 11:35:43] DEBUG[19959] config.c: extract double from [3.0] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [0] in [0, 4294967295] gives 0
[Feb 4 11:35:43] DEBUG[19959] config.c: extract double from [3.000000] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [0] in [0, 86400] gives 0
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [5060] in [0, 4294967295] gives 5060
[Feb 4 11:35:43] DEBUG[19959] config.c: extract double from [3.0] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [0] in [0, 4294967295] gives 0
[Feb 4 11:35:43] DEBUG[19959] config.c: extract double from [3.000000] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [0] in [0, 86400] gives 0
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [5060] in [0, 4294967295] gives 5060
[Feb 4 11:35:43] DEBUG[19959] res_pjsip_registrar.c: Refreshed contact ‘sip:21@192.168.178.57:5060’ on AOR ‘21’ with new expiration of 180 seconds
[Feb 4 11:35:43] DEBUG[15792] threadpool.c: Increasing threadpool Sorcery’s size by 1
[Feb 4 11:35:43] DEBUG[19964] config.c: extract uint from [3] in [0, 4294967295] gives 3
[Feb 4 11:35:43] DEBUG[19964] config.c: extract uint from [0] in [0, 4294967295] gives 0
[Feb 4 11:35:43] DEBUG[19964] config.c: extract uint from [2] in [0, 4294967295] gives 2
[Feb 4 11:35:43] DEBUG[15792] threadpool.c: Increasing threadpool Sorcery’s size by 1
[Feb 4 11:35:43] DEBUG[15793] threadpool.c: Increasing threadpool stasis-core’s size by 1
[Feb 4 11:35:43] DEBUG[19960] res_pjsip_mwi.c: Sending unsolicited MWI NOTIFY to endpoint 21, new messages: 0, old messages: 0
[Feb 4 11:35:43] DEBUG[19959] config.c: extract double from [3.0] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [0] in [0, 4294967295] gives 0
[Feb 4 11:35:43] DEBUG[19959] config.c: extract double from [3.000000] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [0] in [0, 86400] gives 0
[Feb 4 11:35:43] DEBUG[19959] config.c: extract uint from [5060] in [0, 4294967295] gives 5060
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:35:43] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:35:43] VERBOSE[19959] res_pjsip_logger.c: <— Transmitting SIP response (477 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bKbf3bd13638b7c1d8a14c9a8df336d7e
Call-ID: 804778743@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=2246956230
To: “Wohnzimmer” sip:21@domain.local;tag=z9hG4bKbf3bd13638b7c1d8a14c9a8df336d7e
CSeq: 1840 REGISTER
Date: Sat, 04 Feb 2017 10:35:43 GMT
Contact: sip:21@192.168.178.57:5060;expires=179
Expires: 180
Server: Rambla
Content-Length: 0

[Feb 4 11:35:43] DEBUG[19960] config.c: extract double from [3.0] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19960] config.c: extract uint from [0] in [0, 4294967295] gives 0
[Feb 4 11:35:43] DEBUG[19960] config.c: extract double from [3.000000] in [-inf, inf] gives 3.000000
[Feb 4 11:35:43] DEBUG[19960] config.c: extract uint from [0] in [0, 86400] gives 0
[Feb 4 11:35:43] DEBUG[19960] config.c: extract uint from [5060] in [0, 4294967295] gives 5060
[Feb 4 11:35:43] DEBUG[19960] res_pjsip.c: 0x75b116e0: Wrapper created
[Feb 4 11:35:43] DEBUG[19960] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 192.168.178.207:5060
[Feb 4 11:35:43] DEBUG[19960] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:35:43] DEBUG[19960] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:35:43] DEBUG[19960] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:35:43] DEBUG[19960] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[19960] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:35:43] DEBUG[19960] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:35:43] VERBOSE[19960] res_pjsip_logger.c: <— Transmitting SIP request (623 bytes) to UDP:192.168.178.57:5060 —>
NOTIFY sip:21@192.168.178.57:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.178.207:5060;rport;branch=z9hG4bKPj6ffcc521-31ec-4474-91dd-1fd6adab2747
From: sip:21@192.168.178.207;tag=792abf21-21d0-4dff-bac8-4083cfae6277
To: sip:21@192.168.178.57
Contact: sip:21@192.168.178.207:5060
Call-ID: e134e923-db5e-4122-a0eb-2ed3f78c6125
CSeq: 47773 NOTIFY
Subscription-State: terminated
Event: message-summary
Allow-Events: message-summary, presence, dialog, refer
Max-Forwards: 70
User-Agent: Rambla
Content-Type: application/simple-message-summary
Content-Length: 48

Messages-Waiting: no
Voice-Message: 0/0 (0/0)

[Feb 4 11:35:43] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP response (391 bytes) from UDP:192.168.178.57:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.178.207:5060;rport=5060;branch=z9hG4bKPj6ffcc521-31ec-4474-91dd-1fd6adab2747
From: sip:21@192.168.178.207;tag=792abf21-21d0-4dff-bac8-4083cfae6277
To: sip:21@192.168.178.57;tag=683acg30-30e1-5egg-cab9-5192bgad7366
Call-ID: e134e923-db5e-4122-a0eb-2ed3f78c6125
CSeq: 47773 NOTIFY
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:35:43] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:35:43] DEBUG[15817] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/NOTIFY/cseq=47773 (rdata0x1bc9b24). Using request transaction as basis
[Feb 4 11:35:43] DEBUG[15817] res_pjsip/pjsip_distributor.c: Found serializer pjsip/mwi-00000045 on transaction tsx0x75f2440c
[Feb 4 11:35:43] DEBUG[19959] res_pjsip.c: 0x75b116e0: PJSIP tsx response received
[Feb 4 11:35:43] DEBUG[19959] res_pjsip.c: 0x75b116e0: Callbacks executed
[Feb 4 11:35:43] DEBUG[19959] res_pjsip.c: 0x75b116e0: wrapper destroyed
[Feb 4 11:35:51] DEBUG[15851] res_pjsip_registrar_expire.c: Woke up at 1486204551 Interval: 30
[Feb 4 11:35:51] DEBUG[15851] res_pjsip_registrar_expire.c: Expiring 0 contacts
[Feb 4 11:36:03] DEBUG[19966] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:36:03] DEBUG[15793] threadpool.c: Destroying worker thread 3272
[Feb 4 11:36:05] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (1017 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:01234567890@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bKd2951258419686f8986232a13ce68f1c;rport
From: “Wohnzimmer” sip:21@domain.local;tag=3121394847
To: sip:01234567890@domain.local;user=phone
Call-ID: 382544389@192_168_178_57
CSeq: 2 INVITE
Contact: sip:21@192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5004 79 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5004 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002f to use for Request msg INVITE/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:05] VERBOSE[19959] res_pjsip_logger.c: <— Transmitting SIP response (543 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bKd2951258419686f8986232a13ce68f1c
Call-ID: 382544389@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=3121394847
To: sip:01234567890@domain.local;user=phone;tag=z9hG4bKd2951258419686f8986232a13ce68f1c
CSeq: 2 INVITE
WWW-Authenticate: Digest realm=“domain.local”,nonce=“1486204565/4f1aed5316c31b85275e2ac6214fb578”,opaque=“0640b62a2b82fbc1”,algorithm=md5,qop="auth"
Server: Rambla
Content-Length: 0

[Feb 4 11:36:05] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (468 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:01234567890@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bKd2951258419686f8986232a13ce68f1c;rport
From: “Wohnzimmer” sip:21@domain.local;tag=3121394847
To: sip:01234567890@domain.local;user=phone;tag=z9hG4bKd2951258419686f8986232a13ce68f1c
Call-ID: 382544389@192_168_178_57
CSeq: 2 ACK
Contact: sip:21@192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg ACK/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002f to use for Request msg ACK/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:36:05] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (1335 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:01234567890@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bKd5ddb000639f6542a65d27ff2909574b;rport
From: “Wohnzimmer” sip:21@domain.local;tag=3121394847
To: sip:01234567890@domain.local;user=phone
Call-ID: 382544389@192_168_178_57
CSeq: 3 INVITE
Contact: sip:21@192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:01234567890@domain.local;user=phone”, nonce=“1486204565/4f1aed5316c31b85275e2ac6214fb578”, nc=00000001, cnonce=“a85505ba3b79602af1a7bbe8e1f65426”, opaque=“0640b62a2b82fbc1”, response="ca140e20f7d378610ca856337e52acb2"
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5004 79 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5004 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Tenere_750 · February 4, 2017, 10:53am

[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=3 (rdata0x1bc9b24)
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002f to use for Request msg INVITE/cseq=3 (rdata0x1bc9b24)
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_authenticator_digest.c: Calculated nonce 1486204565/4f1aed5316c31b85275e2ac6214fb578. Actual nonce is 1486204565/4f1aed5316c31b85275e2ac6214fb578
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002f to use for Request msg INVITE/cseq=3 (rdata0x1daf674)
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Negotiating incoming SDP media stream ‘audio’ using audio SDP handler
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Using engine ‘asterisk’ for RTP instance ‘0x75b00820’
[Feb 4 11:36:05] DEBUG[19959] res_rtp_asterisk.c: Allocated port 30732 for RTP instance ‘0x75b00820’
[Feb 4 11:36:05] DEBUG[19959] res_rtp_asterisk.c: Creating ICE session [::]:30732 (30732) for RTP instance ‘0x75b00820’
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: RTP instance ‘0x75b00820’ is setup and ready to go
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘AsteriskPi’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘AsteriskPi’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] res_rtp_asterisk.c: Setup RTCP on RTP instance ‘0x75b00820’
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Setting tx payload type 9 based on m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Don’t have a default tx payload type 96 format for m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Setting tx payload type 97 based on m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Don’t have a default tx payload type 2 format for m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Setting tx payload type 18 based on m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7459d510
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 0 (0x75b00eb0) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 2 (0x75b019e0) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 8 (0x75b036a0) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 9 (0x75b03808) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 18 (0x75b004a8) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 96 (0x75b02370) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 97 (0x75b11b00) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Copying payload 101 (0x75b01a08) from 0x7459d510 to 0x75b009cc
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Media stream ‘audio’ handled by audio
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Method is INVITE, Response is 100 Trying
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:05] VERBOSE[19959] res_pjsip_logger.c: <— Transmitting SIP response (339 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bKd5ddb000639f6542a65d27ff2909574b
Call-ID: 382544389@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=3121394847
To: sip:01234567890@domain.local;user=phone
CSeq: 3 INVITE
Server: Rambla
Content-Length: 0

[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current inv state is INCOMING
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current transaction state is Proceeding
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The transaction state change event is TX_MSG
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current inv state is INCOMING
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Method is INVITE
[Feb 4 11:36:05] DEBUG[15793] threadpool.c: Increasing threadpool stasis-core’s size by 1
[Feb 4 11:36:05] DEBUG[19959] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/21-0000001c
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] pbx_variables.c: Result of ‘EXTEN’ is ‘01234567890’
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] pbx.c: Launching ‘Dial’
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Using engine ‘asterisk’ for RTP instance ‘0x75b1d7e0’
[Feb 4 11:36:05] DEBUG[19959] res_rtp_asterisk.c: Allocated port 30808 for RTP instance ‘0x75b1d7e0’
[Feb 4 11:36:05] DEBUG[19959] res_rtp_asterisk.c: Creating ICE session [::]:30808 (30808) for RTP instance ‘0x75b1d7e0’
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: RTP instance ‘0x75b1d7e0’ is setup and ready to go
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘AsteriskPi’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘AsteriskPi’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] res_rtp_asterisk.c: Setup RTCP on RTP instance ‘0x75b1d7e0’
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:05] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Method is INVITE
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] channel.c: PJSIP/21-0000001c: Dropping redundant connected line update “” <022528301471>.
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001d)’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f07ecc)
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current inv state is CALLING
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001d)’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f07ecc)
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x75f07ecc
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current transaction state is Calling
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The transaction state change event is TX_MSG
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current inv state is CALLING
[Feb 4 11:36:05] DEBUG[15817] pjproject: tsx0x75f07ecc Failed to send Request msg INVITE/cseq=24087 (tdta0x75f0abc8)! err=320047 (No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC))
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001d)’
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f07ecc)
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: Source of transaction state change is TRANSPORT_ERROR
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001d)’
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x75f07ecc
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The current transaction state is Terminated
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The transaction state change event is TRANSPORT_ERROR
[Feb 4 11:36:05] DEBUG[15817] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] channel.c: Hanging up channel ‘PJSIP/03_Telekom_Joerg_022528301471_out-0000001d’
[Feb 4 11:36:05] DEBUG[15808] cdr.c: Finalized CDR for PJSIP/21-0000001c - start 1486204565.167369 answer 0.000000 end 1486204565.223422 dispo FAILED
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Destroying SIP session with endpoint 03_Telekom_Joerg_022528301471_out
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] pbx.c: Launching ‘Hangup’
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] channel.c: Soft-Hanging (0x20) up channel ‘PJSIP/21-0000001c’
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] pbx.c: Spawn extension (internalsip,01234567890,2) exited non-zero on ‘PJSIP/21-0000001c’
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] channel.c: Soft-Hanging (0x10) up channel ‘PJSIP/21-0000001c’
[Feb 4 11:36:05] DEBUG[19968][C-0000000e] channel.c: Hanging up channel ‘PJSIP/21-0000001c’
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: Method is INVITE, Response is 503 Service Unavailable
[Feb 4 11:36:05] DEBUG[19959] res_rtp_asterisk.c: Setting RTCP address on RTP instance ‘0x75b1d7e0’
[Feb 4 11:36:05] DEBUG[19960] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[19960] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[19959] rtp_engine.c: Destroyed RTP instance ‘0x75b1d7e0’
[Feb 4 11:36:05] DEBUG[19960] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:05] DEBUG[19960] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[19960] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:05] DEBUG[19960] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:05] VERBOSE[19960] res_pjsip_logger.c: <— Transmitting SIP response (417 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bKd5ddb000639f6542a65d27ff2909574b
Call-ID: 382544389@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=3121394847
To: sip:01234567890@domain.local;user=phone;tag=48a5abb9-524b-4d28-8286-95390d8a2785
CSeq: 3 INVITE
Server: Rambla
Reason: Q.850;cause=34
Content-Length: 0

[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The state change pertains to the endpoint ‘21(PJSIP/21-0000001c)’
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The state change pertains to the endpoint ‘21(PJSIP/21-0000001c)’
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The current transaction state is Completed
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The transaction state change event is TX_MSG
[Feb 4 11:36:05] DEBUG[19960] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:05] DEBUG[15808] cdr.c: Finalized CDR for PJSIP/03_Telekom_Joerg_022528301471_out-0000001d - start 1486204565.172266 answer 0.000000 end 1486204565.227947 dispo FAILED
[Feb 4 11:36:05] DEBUG[15808] cdr.c: Finalized CDR for PJSIP/21-0000001c - start 1486204565.224644 answer 0.000000 end 1486204565.228081 dispo FAILED
[Feb 4 11:36:05] DEBUG[15804] devicestate.c: No provider found, checking channel drivers for PJSIP - 21
[Feb 4 11:36:05] DEBUG[15804] devicestate.c: Changing state for PJSIP/21 - state 1 (Not in use)
[Feb 4 11:36:05] DEBUG[15808] cdr.c: Skipping CDR for PJSIP/21-0000001c since we weren’t answered
[Feb 4 11:36:05] DEBUG[15808] cdr.c: CDR for PJSIP/03_Telekom_Joerg_022528301471_out-0000001d is dialed and has no Party B; discarding
[Feb 4 11:36:05] DEBUG[15804] devicestate.c: No provider found, checking channel drivers for PJSIP - 03_Telekom_Joerg_022528301471_out
[Feb 4 11:36:05] DEBUG[15804] devicestate.c: Changing state for PJSIP/03_Telekom_Joerg_022528301471_out - state 1 (Not in use)
[Feb 4 11:36:05] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (783 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:01234567890@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bKd5ddb000639f6542a65d27ff2909574b;rport
From: “Wohnzimmer” sip:21@domain.local;tag=3121394847
To: sip:01234567890@domain.local;user=phone;tag=48a5abb9-524b-4d28-8286-95390d8a2785
Call-ID: 382544389@192_168_178_57
CSeq: 3 ACK
Contact: sip:21@192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:01234567890@domain.local;user=phone”, nonce=“1486204565/4f1aed5316c31b85275e2ac6214fb578”, nc=00000001, cnonce=“a85505ba3b79602af1a7bbe8e1f65426”, opaque=“0640b62a2b82fbc1”, response="ca140e20f7d378610ca856337e52acb2"
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:05] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x75f2440c for Request msg ACK/cseq=3 (rdata0x1bc9b24)
[Feb 4 11:36:05] DEBUG[15817] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000002f on dialog dlg0x75f2440c
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current transaction state is Confirmed
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The transaction state change event is RX_MSG
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Received request
[Feb 4 11:36:05] DEBUG[19959] res_pjsip_session.c: Method is ACK
[Feb 4 11:36:10] DEBUG[15817] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:10] DEBUG[15817] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:10] DEBUG[15817] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Feb 4 11:36:10] DEBUG[15817] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:10] DEBUG[15817] res_pjsip_session.c: The current transaction state is Terminated
[Feb 4 11:36:10] DEBUG[15817] res_pjsip_session.c: The transaction state change event is TIMER
[Feb 4 11:36:10] DEBUG[15817] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:10] DEBUG[19959] res_pjsip_session.c: Destroying SIP session with endpoint 21
[Feb 4 11:36:10] DEBUG[19959] res_rtp_asterisk.c: Setting RTCP address on RTP instance ‘0x75b00820’
[Feb 4 11:36:10] DEBUG[19959] rtp_engine.c: Destroyed RTP instance ‘0x75b00820’
[Feb 4 11:36:16] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (999 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:12@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK8e6925fe24715b885dd78445da301c7;rport
From: “Wohnzimmer” sip:21@domain.local;tag=1473587716
To: sip:12@domain.local;user=phone
Call-ID: 1659678815@192_168_178_57
CSeq: 2 INVITE
Contact: sip:21@192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5006 80 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5006 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

Tenere_750 · February 4, 2017, 10:54am

[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000035 to use for Request msg INVITE/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:16] VERBOSE[19959] res_pjsip_logger.c: <— Transmitting SIP response (533 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK8e6925fe24715b885dd78445da301c7
Call-ID: 1659678815@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=1473587716
To: sip:12@domain.local;user=phone;tag=z9hG4bK8e6925fe24715b885dd78445da301c7
CSeq: 2 INVITE
WWW-Authenticate: Digest realm=“domain.local”,nonce=“1486204576/56661eb17481a37d1d984f6de9bafaa8”,opaque=“5419e86c15a68e23”,algorithm=md5,qop="auth"
Server: Rambla
Content-Length: 0

[Feb 4 11:36:16] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (449 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:12@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK8e6925fe24715b885dd78445da301c7;rport
From: “Wohnzimmer” sip:21@domain.local;tag=1473587716
To: sip:12@domain.local;user=phone;tag=z9hG4bK8e6925fe24715b885dd78445da301c7
Call-ID: 1659678815@192_168_178_57
CSeq: 2 ACK
Contact: sip:21@192.168.178.57:5060
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg ACK/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000035 to use for Request msg ACK/cseq=2 (rdata0x1bc9b24)
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:36:16] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (1309 bytes) from UDP:192.168.178.57:5060 —>
INVITE sip:12@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK640957f63494daa6dc3746262b02eac3;rport
From: “Wohnzimmer” sip:21@domain.local;tag=1473587716
To: sip:12@domain.local;user=phone
Call-ID: 1659678815@192_168_178_57
CSeq: 3 INVITE
Contact: sip:21@192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:12@domain.local;user=phone”, nonce=“1486204576/56661eb17481a37d1d984f6de9bafaa8”, nc=00000001, cnonce=“31e9b316e688c064a20a8eabe7303b48”, opaque=“5419e86c15a68e23”, response="1501ba366e6a2fe0a2b0490cb6b6fe89"
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Supported: replaces
Allow-Events: message-summary, refer, ua-profile, talk, check-sync
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, UPDATE
Content-Type: application/sdp
Content-Length: 383

v=0
o=21 5006 80 IN IP4 192.168.178.57
s=Mapping
c=IN IP4 192.168.178.57
t=0 0
m=audio 5006 RTP/AVP 9 8 0 96 97 2 18 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:97 AAL2-G726-32/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=3 (rdata0x1bc9b24)
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000035 to use for Request msg INVITE/cseq=3 (rdata0x1bc9b24)
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘03_Telekom_in’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 does not match identify ‘04_sipgate_Joerg_identity’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.178.57:5060 matches identify ‘21’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_endpoint_identifier_ip.c: Retrieved endpoint 21
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_authenticator_digest.c: Calculated nonce 1486204576/56661eb17481a37d1d984f6de9bafaa8. Actual nonce is 1486204576/56661eb17481a37d1d984f6de9bafaa8
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000035 to use for Request msg INVITE/cseq=3 (rdata0x1db05d4)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Negotiating incoming SDP media stream ‘audio’ using audio SDP handler
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Using engine ‘asterisk’ for RTP instance ‘0x75b11748’
[Feb 4 11:36:16] DEBUG[19959] res_rtp_asterisk.c: Allocated port 30492 for RTP instance ‘0x75b11748’
[Feb 4 11:36:16] DEBUG[19959] res_rtp_asterisk.c: Creating ICE session [::]:30492 (30492) for RTP instance ‘0x75b11748’
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: RTP instance ‘0x75b11748’ is setup and ready to go
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘AsteriskPi’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘AsteriskPi’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] res_rtp_asterisk.c: Setup RTCP on RTP instance ‘0x75b11748’
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Setting tx payload type 9 based on m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Don’t have a default tx payload type 96 format for m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Setting tx payload type 97 based on m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Don’t have a default tx payload type 2 format for m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Setting tx payload type 18 based on m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7459d510
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 0 (0x75b11c68) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 2 (0x75b02390) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 8 (0x75b09120) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 9 (0x75b036a0) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 18 (0x75b004d0) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 96 (0x75b02340) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 97 (0x75b02368) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Copying payload 101 (0x75b004f8) from 0x7459d510 to 0x75b118f4
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Media stream ‘audio’ handled by audio
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Method is INVITE, Response is 100 Trying
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:16] VERBOSE[19959] res_pjsip_logger.c: <— Transmitting SIP response (331 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK640957f63494daa6dc3746262b02eac3
Call-ID: 1659678815@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=1473587716
To: sip:12@domain.local;user=phone
CSeq: 3 INVITE
Server: Rambla
Content-Length: 0

[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current inv state is INCOMING
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current transaction state is Proceeding
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The transaction state change event is TX_MSG
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current inv state is INCOMING
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Method is INVITE
[Feb 4 11:36:16] DEBUG[19959] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/21-0000001e
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] pbx_variables.c: Result of ‘EXTEN’ is ‘12’
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] pbx.c: Launching ‘Dial’
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Using engine ‘asterisk’ for RTP instance ‘0x75b043d8’
[Feb 4 11:36:16] DEBUG[19959] res_rtp_asterisk.c: Allocated port 30562 for RTP instance ‘0x75b043d8’
[Feb 4 11:36:16] DEBUG[19959] res_rtp_asterisk.c: Creating ICE session [::]:30562 (30562) for RTP instance ‘0x75b043d8’
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: RTP instance ‘0x75b043d8’ is setup and ready to go
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘AsteriskPi’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘AsteriskPi’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] res_rtp_asterisk.c: Setup RTCP on RTP instance ‘0x75b043d8’
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Method is INVITE
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] channel.c: PJSIP/21-0000001e: Dropping redundant connected line update “” <022528301471>.
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001f)’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f0df84)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current inv state is CALLING
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001f)’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f0df84)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x75f0df84
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current transaction state is Calling
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The transaction state change event is TX_MSG
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current inv state is CALLING
[Feb 4 11:36:16] DEBUG[15817] pjproject: tsx0x75f0df84 Failed to send Request msg INVITE/cseq=29764 (tdta0x75f14478)! err=320047 (No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC))
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001f)’
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f0df84)
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: Source of transaction state change is TRANSPORT_ERROR
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The state change pertains to the endpoint ‘03_Telekom_Joerg_022528301471_out(PJSIP/03_Telekom_Joerg_022528301471_out-0000001f)’
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x75f0df84
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The current transaction state is Terminated
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The transaction state change event is TRANSPORT_ERROR
[Feb 4 11:36:16] DEBUG[15817] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:16] DEBUG[15808] cdr.c: Finalized CDR for PJSIP/21-0000001e - start 1486204576.637006 answer 0.000000 end 1486204576.683715 dispo FAILED
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] channel.c: Hanging up channel ‘PJSIP/03_Telekom_Joerg_022528301471_out-0000001f’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Destroying SIP session with endpoint 03_Telekom_Joerg_022528301471_out
[Feb 4 11:36:16] DEBUG[19959] res_rtp_asterisk.c: Setting RTCP address on RTP instance ‘0x75b043d8’
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] pbx.c: Launching ‘Hangup’
[Feb 4 11:36:16] DEBUG[19959] rtp_engine.c: Destroyed RTP instance ‘0x75b043d8’
[Feb 4 11:36:16] DEBUG[15808] cdr.c: Finalized CDR for PJSIP/03_Telekom_Joerg_022528301471_out-0000001f - start 1486204576.642181 answer 0.000000 end 1486204576.685188 dispo FAILED
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] channel.c: Soft-Hanging (0x20) up channel ‘PJSIP/21-0000001e’
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] pbx.c: Spawn extension (internalsip,12,2) exited non-zero on ‘PJSIP/21-0000001e’
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] channel.c: Soft-Hanging (0x10) up channel ‘PJSIP/21-0000001e’
[Feb 4 11:36:16] DEBUG[15808] cdr.c: CDR for PJSIP/03_Telekom_Joerg_022528301471_out-0000001f is dialed and has no Party B; discarding
[Feb 4 11:36:16] DEBUG[15793] threadpool.c: Increasing threadpool stasis-core’s size by 1
[Feb 4 11:36:16] DEBUG[19969][C-0000000f] channel.c: Hanging up channel ‘PJSIP/21-0000001e’
[Feb 4 11:36:16] DEBUG[15804] devicestate.c: No provider found, checking channel drivers for PJSIP - 03_Telekom_Joerg_022528301471_out
[Feb 4 11:36:16] DEBUG[15804] devicestate.c: Changing state for PJSIP/03_Telekom_Joerg_022528301471_out - state 1 (Not in use)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Method is INVITE, Response is 503 Service Unavailable
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:16] DEBUG[19959] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:16] VERBOSE[19959] res_pjsip_logger.c: <— Transmitting SIP response (409 bytes) to UDP:192.168.178.57:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.178.57:5060;rport=5060;received=192.168.178.57;branch=z9hG4bK640957f63494daa6dc3746262b02eac3
Call-ID: 1659678815@192_168_178_57
From: “Wohnzimmer” sip:21@domain.local;tag=1473587716
To: sip:12@domain.local;user=phone;tag=65b4c8fc-f944-44ae-a11e-8ba5164b66b7
CSeq: 3 INVITE
Server: Rambla
Reason: Q.850;cause=34
Content-Length: 0

Tenere_750 · February 4, 2017, 10:54am

[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21(PJSIP/21-0000001e)’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: There is no transaction involved in this state change
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21(PJSIP/21-0000001e)’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current transaction state is Completed
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The transaction state change event is TX_MSG
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:16] DEBUG[15808] cdr.c: Finalized CDR for PJSIP/21-0000001e - start 1486204576.685017 answer 0.000000 end 1486204576.689814 dispo FAILED
[Feb 4 11:36:16] DEBUG[15808] cdr.c: Skipping CDR for PJSIP/21-0000001e since we weren’t answered
[Feb 4 11:36:16] DEBUG[15804] devicestate.c: No provider found, checking channel drivers for PJSIP - 21
[Feb 4 11:36:16] DEBUG[15804] devicestate.c: Changing state for PJSIP/21 - state 1 (Not in use)
[Feb 4 11:36:16] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP request (757 bytes) from UDP:192.168.178.57:5060 —>
ACK sip:12@domain.local;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.178.57:5060;branch=z9hG4bK640957f63494daa6dc3746262b02eac3;rport
From: “Wohnzimmer” sip:21@domain.local;tag=1473587716
To: sip:12@domain.local;user=phone;tag=65b4c8fc-f944-44ae-a11e-8ba5164b66b7
Call-ID: 1659678815@192_168_178_57
CSeq: 3 ACK
Contact: sip:21@192.168.178.57:5060
Authorization: Digest username=“21”, realm=“domain.local”, qop=auth, algorithm=md5, uri=“sip:12@domain.local;user=phone”, nonce=“1486204576/56661eb17481a37d1d984f6de9bafaa8”, nc=00000001, cnonce=“31e9b316e688c064a20a8eabe7303b48”, opaque=“5419e86c15a68e23”, response="1501ba366e6a2fe0a2b0490cb6b6fe89"
Max-Forwards: 70
User-Agent: SL400A GO/42.240.00.000.000
Content-Length: 0

[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.57’ into…
[Feb 4 11:36:16] DEBUG[15817] netsock2.c: …host ‘192.168.178.57’ and port ‘’.
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x75f25524 for Request msg ACK/cseq=3 (rdata0x1bc9b24)
[Feb 4 11:36:16] DEBUG[15817] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000035 on dialog dlg0x75f25524
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The inv session still has an invite_tsx (0x75f29ddc)
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current transaction state is Confirmed
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The transaction state change event is RX_MSG
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Received request
[Feb 4 11:36:16] DEBUG[19959] res_pjsip_session.c: Method is ACK
[Feb 4 11:36:21] DEBUG[15851] res_pjsip_registrar_expire.c: Woke up at 1486204581 Interval: 30
[Feb 4 11:36:21] DEBUG[15851] res_pjsip_registrar_expire.c: Expiring 0 contacts
[Feb 4 11:36:21] DEBUG[15817] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 4 11:36:21] DEBUG[15817] res_pjsip_session.c: The state change pertains to the endpoint ‘21()’
[Feb 4 11:36:21] DEBUG[15817] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Feb 4 11:36:21] DEBUG[15817] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x75f29ddc
[Feb 4 11:36:21] DEBUG[15817] res_pjsip_session.c: The current transaction state is Terminated
[Feb 4 11:36:21] DEBUG[15817] res_pjsip_session.c: The transaction state change event is TIMER
[Feb 4 11:36:21] DEBUG[15817] res_pjsip_session.c: The current inv state is DISCONNCTD
[Feb 4 11:36:21] DEBUG[19959] res_pjsip_session.c: Destroying SIP session with endpoint 21
[Feb 4 11:36:21] DEBUG[19959] res_rtp_asterisk.c: Setting RTCP address on RTP instance ‘0x75b11748’
[Feb 4 11:36:21] DEBUG[19959] rtp_engine.c: Destroyed RTP instance ‘0x75b11748’
[Feb 4 11:36:27] DEBUG[19959] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 1 to ‘sip:sipgate.de:5060’ with client ‘sip:1234567@sipgate.de:5060’
[Feb 4 11:36:27] DEBUG[15817] pjproject: tsx0x75f25524 Temporary failure in sending Request msg REGISTER/cseq=20963 (tdta0x1dadea8), will try next server: Unsuitable transport selected (PJSIP_ETPNOTSUITABLE)
[Feb 4 11:36:27] DEBUG[15817] pjproject: tsx0x75f25524 Temporary failure in sending Request msg REGISTER/cseq=20963 (tdta0x1dadea8), will try next server: Unsuitable transport selected (PJSIP_ETPNOTSUITABLE)
[Feb 4 11:36:27] DEBUG[15817] pjproject: tsx0x75f25524 Temporary failure in sending Request msg REGISTER/cseq=20963 (tdta0x1dadea8), will try next server: Unsuitable transport selected (PJSIP_ETPNOTSUITABLE)
[Feb 4 11:36:27] DEBUG[15817] pjproject: tsx0x75f25524 Temporary failure in sending Request msg REGISTER/cseq=20963 (tdta0x1dadea8), will try next server: Unsuitable transport selected (PJSIP_ETPNOTSUITABLE)
[Feb 4 11:36:27] DEBUG[15817] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 192.168.178.207:5060
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: Splitting ‘217.10.79.9’ into…
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: …host ‘217.10.79.9’ and port ‘’.
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: Splitting ‘217.10.79.9:5060’ into…
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: …host ‘217.10.79.9’ and port ‘5060’.
[Feb 4 11:36:27] VERBOSE[15817] res_pjsip_logger.c: <— Transmitting SIP request (532 bytes) to UDP:217.10.79.9:5060 —>
REGISTER sip:sipgate.de:5060 SIP/2.0
Via: SIP/2.0/UDP 217.89.9.154:5060;rport;branch=z9hG4bKPj81335aa0-cdaa-4baf-9de4-978101e79ed6
From: sip:1234567@sipgate.de;tag=76b1d561-a362-4848-a464-be2d694a1afb
To: sip:1234567@sipgate.de
Call-ID: 4b567ac6-8386-487c-8257-999756ace68b
CSeq: 20963 REGISTER
Contact: sip:1234567@217.89.9.154:5060
Expires: 120
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
Max-Forwards: 70
User-Agent: Rambla
Content-Length: 0

[Feb 4 11:36:27] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP response (471 bytes) from UDP:217.10.79.9:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 217.89.9.154:5060;received=217.89.12.211;rport=1024;branch=z9hG4bKPj81335aa0-cdaa-4baf-9de4-978101e79ed6
From: sip:1234567@sipgate.de;tag=76b1d561-a362-4848-a464-be2d694a1afb
To: sip:1234567@sipgate.de;tag=86e53dd608d1c001e0b8060625977563.7d20
Call-ID: 4b567ac6-8386-487c-8257-999756ace68b
CSeq: 20963 REGISTER
WWW-Authenticate: Digest realm=“sipgate.de”, nonce="WJWv11iVrquUer8PxAMij3cxiUKYJbOu"
Content-Length: 0

[Feb 4 11:36:27] DEBUG[15817] netsock2.c: Splitting ‘217.10.79.9:5060’ into…
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: …host ‘217.10.79.9’ and port ‘5060’.
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:27] DEBUG[15817] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 401/REGISTER/cseq=20963 (rdata0x1bc9b24). Using request transaction as basis
[Feb 4 11:36:27] DEBUG[15817] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000021 to use for Response msg 401/REGISTER/cseq=20963 (rdata0x1bc9b24)
[Feb 4 11:36:27] DEBUG[19959] res_pjsip_outbound_registration.c: Received REGISTER response 401(Unauthorized)
[Feb 4 11:36:27] DEBUG[19960] res_pjsip_outbound_registration.c: Processing REGISTER response 401 from server ‘sip:sipgate.de:5060’ for client ‘sip:1234567@sipgate.de:5060’
[Feb 4 11:36:27] DEBUG[19960] res_pjsip_outbound_registration.c: Sending authenticated REGISTER to server ‘sip:sipgate.de:5060’ from client ‘sip:1234567@sipgate.de:5060’
[Feb 4 11:36:27] DEBUG[19960] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 192.168.178.207:5060
[Feb 4 11:36:27] DEBUG[19960] netsock2.c: Splitting ‘217.10.79.9’ into…
[Feb 4 11:36:27] DEBUG[19960] netsock2.c: …host ‘217.10.79.9’ and port ‘’.
[Feb 4 11:36:27] DEBUG[19960] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:27] DEBUG[19960] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:27] DEBUG[19960] netsock2.c: Splitting ‘217.10.79.9:5060’ into…
[Feb 4 11:36:27] DEBUG[19960] netsock2.c: …host ‘217.10.79.9’ and port ‘5060’.
[Feb 4 11:36:27] VERBOSE[19960] res_pjsip_logger.c: <— Transmitting SIP request (708 bytes) to UDP:217.10.79.9:5060 —>
REGISTER sip:sipgate.de:5060 SIP/2.0
Via: SIP/2.0/UDP 217.89.9.154:5060;rport;branch=z9hG4bKPje54afca3-50af-4d85-b2e1-eecd9eb77576
From: sip:1234567@sipgate.de;tag=76b1d561-a362-4848-a464-be2d694a1afb
To: sip:1234567@sipgate.de
Call-ID: 4b567ac6-8386-487c-8257-999756ace68b
CSeq: 20964 REGISTER
Contact: sip:1234567@217.89.9.154:5060
Expires: 120
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
Max-Forwards: 70
User-Agent: Rambla
Authorization: Digest username=“1234567”, realm=“sipgate.de”, nonce=“WJWv11iVrquUer8PxAMij3cxiUKYJbOu”, uri=“sip:sipgate.de:5060”, response="dbfaaa1d224d8ee7a5cbcaee98f21db4"
Content-Length: 0

[Feb 4 11:36:27] VERBOSE[15817] res_pjsip_logger.c: <— Received SIP response (462 bytes) from UDP:217.10.79.9:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 217.89.9.154:5060;received=217.89.12.211;rport=1024;branch=z9hG4bKPje54afca3-50af-4d85-b2e1-eecd9eb77576
From: sip:1234567@sipgate.de;tag=76b1d561-a362-4848-a464-be2d694a1afb
To: sip:1234567@sipgate.de;tag=86e53dd608d1c001e0b8060625977563.9b72
Call-ID: 4b567ac6-8386-487c-8257-999756ace68b
CSeq: 20964 REGISTER
Contact: sip:1234567@217.89.9.154:5060;expires=120;received="sip:217.89.12.211:1024"
Content-Length: 0

[Feb 4 11:36:27] DEBUG[15817] netsock2.c: Splitting ‘217.10.79.9:5060’ into…
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: …host ‘217.10.79.9’ and port ‘5060’.
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: Splitting ‘192.168.178.207:5060’ into…
[Feb 4 11:36:27] DEBUG[15817] netsock2.c: …host ‘192.168.178.207’ and port ‘5060’.
[Feb 4 11:36:27] DEBUG[15817] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/REGISTER/cseq=20964 (rdata0x1bc9b24). Using request transaction as basis
[Feb 4 11:36:27] DEBUG[15817] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outreg/04_sipgate_Joerg-00000054 on transaction tsx0x75f29ddc
[Feb 4 11:36:27] DEBUG[19959] res_pjsip_outbound_registration.c: Received REGISTER response 200(OK)
[Feb 4 11:36:27] DEBUG[19959] res_pjsip_outbound_registration.c: Processing REGISTER response 200 from server ‘sip:sipgate.de:5060’ for client ‘sip:1234567@sipgate.de:5060’
[Feb 4 11:36:27] DEBUG[19959] res_pjsip_outbound_registration.c: Outbound registration to ‘sip:sipgate.de:5060’ with client ‘sip:1234567@sipgate.de:5060’ successful
[Feb 4 11:36:27] DEBUG[19959] res_pjsip_outbound_registration.c: Scheduling outbound registration to server ‘sip:sipgate.de:5060’ from client ‘sip:1234567@sipgate.de:5060’ in 110 seconds
[Feb 4 11:36:36] DEBUG[19970] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:36:36] DEBUG[15793] threadpool.c: Destroying worker thread 3274
[Feb 4 11:36:36] DEBUG[19967] threadpool.c: Worker thread idle timeout reached. Dying.
[Feb 4 11:36:36] DEBUG[15793] threadpool.c: Destroying worker thread 3273

david551 · February 4, 2017, 11:23am

I’m more familiar with chan_sip logs, but this looks to me like a domain name server configuration error or other DNS fault.

Tenere_750 · February 4, 2017, 4:14pm

Sorry bit I don’t get it…

As i have the extensions locally registered and I have trouble in the internal sip context why should it be a DNS problem…?

david551 · February 4, 2017, 5:08pm

You still need valid DNS for local peers, or at least /etc/hosts.

Tenere_750 · February 4, 2017, 6:23pm

Thanks for the answer but I still dont get it…

Every client (VoIP-Phone) needs an DNS entry? But I dont register the phone itself. I just register an account with the Asterisk server.
In pjsip.conf I matched only IP adresses. There is only a local domain mentioned. But I do not have a local DNS server as I use only IP adresses.

Topic		Replies	Views
SIP 503 Response Asterisk SIP	5	195	December 27, 2022
Pjsip outgoing calls giving error Asterisk SIP	24	107	January 29, 2025
Not able dial outgoing call Asterisk SIP	8	72	October 2, 2024
[resolve] Call Failed : Reason Code 503 on my Ip Phone Asterisk Support	26	3034	March 19, 2018
503 Server Error Asterisk Support	1	265	April 4, 2007

Error 503 after one outgoing call

Related topics