rendered paste body<-- SIP read from 10.174.254.3:5060:
SUBSCRIBE sip:pcadach2@10.174.1.251 SIP/2.0
Via: SIP/2.0/UDP 10.174.254.3:5060;branch=z9hG4bKCE7C8EF9CCCF9F93
Route: <sip:10.174.1.251;lr>
From: <sip:pcadach2@10.174.1.251>;tag=3787479280
To: <sip:pcadach2@10.174.1.251>
Call-ID: B7A65E0B62862E76@10.174.254.3
CSeq: 4 SUBSCRIBE
Contact: <sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB>
Event: message-summary
Expires: 3600
Max-Forwards: 70
User-Agent: AVM FRITZ!Box Fon WLAN 7050 14.04.15 (Jul 12 2006)
Allow: NOTIFY
Accept: application/simple-message-summary
Content-Length: 0
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 0: SUBSCRIBE sip:pcadach2@10.174.1.251 SIP/2.0 (43)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 1: Via: SIP/2.0/UDP 10.174.254.3:5060;branch=z9hG4bKCE7C8EF9CCCF9F93 (65)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 2: Route: <sip:10.174.1.251;lr> (28)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 3: From: <sip:pcadach2@10.174.1.251>;tag=3787479280 (48)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 4: To: <sip:pcadach2@10.174.1.251> (31)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 5: Call-ID: B7A65E0B62862E76@10.174.254.3 (38)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 6: CSeq: 4 SUBSCRIBE (17)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 7: Contact: <sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB> (71)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 8: Event: message-summary (22)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 9: Expires: 3600 (13)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 10: Max-Forwards: 70 (16)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 11: User-Agent: AVM FRITZ!Box Fon WLAN 7050 14.04.15 (Jul 12 2006) (62)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 12: Allow: NOTIFY (13)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 13: Accept: application/simple-message-summary (42)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 14: Content-Length: 0 (17)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 15: (0)
--- (15 headers 0 lines)---
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4157 find_call: = No match Their Call ID: 47A716F3F7390640@10.174.254.3 Their Tag 1034567238 Our tag: as2ee6aa81
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4157 find_call: = No match Their Call ID: B20932CCCE21960F@10.174.254.3 Their Tag 43603938 Our tag: as193e98fe
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4109 sip_alloc: Allocating new SIP dialog for B7A65E0B62862E76@10.174.254.3 - SUBSCRIBE (No RTP)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:14007 handle_request: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE
Creating new subscription
Sending to 10.174.254.3 : 5060 (no NAT)
Found peer 'pcadach2'
Transmitting (NAT) to 10.174.254.3:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.174.254.3:5060;branch=z9hG4bKCE7C8EF9CCCF9F93;received=10.174.254.3
From: <sip:pcadach2@10.174.1.251>;tag=3787479280
To: <sip:pcadach2@10.174.1.251>;tag=as5894afe0
Call-ID: B7A65E0B62862E76@10.174.254.3
CSeq: 4 SUBSCRIBE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:pcadach2@10.174.1.251>
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="0d07f423"
Content-Length: 0
---
Scheduling destruction of SIP dialog 'B7A65E0B62862E76@10.174.254.3' in 32000 ms (Method: SUBSCRIBE)
<-- SIP read from 10.174.254.3:5060:
SUBSCRIBE sip:pcadach2@10.174.1.251 SIP/2.0
Via: SIP/2.0/UDP 10.174.254.3:5060;branch=z9hG4bK1A9A770259604463
Route: <sip:10.174.1.251;lr>
From: <sip:pcadach2@10.174.1.251>;tag=775120401
To: <sip:pcadach2@10.174.1.251>
Call-ID: B7A65E0B62862E76@10.174.254.3
CSeq: 5 SUBSCRIBE
Contact: <sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB>
Authorization: Digest username="pcadach2", realm="asterisk", nonce="0d07f423", uri="sip:pcadach2@10.174.1.251", response="98ae5afd0da0cad9fa5bc6837189499a", algorithm=MD5
Event: message-summary
Expires: 3600
Max-Forwards: 70
User-Agent: AVM FRITZ!Box Fon WLAN 7050 14.04.15 (Jul 12 2006)
Allow: NOTIFY
Accept: application/simple-message-summary
Content-Length: 0
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 0: SUBSCRIBE sip:pcadach2@10.174.1.251 SIP/2.0 (43)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 1: Via: SIP/2.0/UDP 10.174.254.3:5060;branch=z9hG4bK1A9A770259604463 (65)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 2: Route: <sip:10.174.1.251;lr> (28)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 3: From: <sip:pcadach2@10.174.1.251>;tag=775120401 (47)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 4: To: <sip:pcadach2@10.174.1.251> (31)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 5: Call-ID: B7A65E0B62862E76@10.174.254.3 (38)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 6: CSeq: 5 SUBSCRIBE (17)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 7: Contact: <sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB> (71)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 8: Authorization: Digest username="pcadach2", realm="asterisk", nonce="0d07f423", uri="sip:pcadach2@10.174.1.251", response="98ae5afd0da0cad9fa5bc6837189499a", algorithm=MD5 (170)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 9: Event: message-summary (22)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 10: Expires: 3600 (13)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 11: Max-Forwards: 70 (16)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 12: User-Agent: AVM FRITZ!Box Fon WLAN 7050 14.04.15 (Jul 12 2006) (62)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 13: Allow: NOTIFY (13)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 14: Accept: application/simple-message-summary (42)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 15: Content-Length: 0 (17)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 16: (0)
--- (16 headers 0 lines)---
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4157 find_call: = Found Their Call ID: B7A65E0B62862E76@10.174.254.3 Their Tag 3787479280 Our tag: as5894afe0
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:14007 handle_request: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:13659 handle_request_subscribe: Got a new subscription B7A65E0B62862E76@10.174.254.3 (possibly with auth)
Found peer 'pcadach2'
Looking for pcadach2 in pcadach2 (domain 10.174.1.251)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:13807 handle_request_subscribe: Adding subscription for mailbox notification - peer pcadach2 Mailbox 33
Scheduling destruction of SIP dialog 'B7A65E0B62862E76@10.174.254.3' in 3610000 ms (Method: SUBSCRIBE)
Transmitting (NAT) to 10.174.254.3:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.174.254.3:5060;branch=z9hG4bK1A9A770259604463;received=10.174.254.3
From: <sip:pcadach2@10.174.1.251>;tag=775120401
To: <sip:pcadach2@10.174.1.251>;tag=as5894afe0
Call-ID: B7A65E0B62862E76@10.174.254.3
CSeq: 5 SUBSCRIBE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:pcadach2@10.174.1.251>;expires=3600
Content-Length: 0
---
Reliably Transmitting (NAT) to 10.174.254.3:5060:
NOTIFY sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB SIP/2.0
Via: SIP/2.0/UDP 10.174.1.251:5060;branch=z9hG4bK2abfdab1;rport
From: "asterisk" <sip:asterisk@10.174.1.251>;tag=as5894afe0
To: <sip:sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB>;tag=3787479280
Contact: <sip:asterisk@10.174.1.251>
Call-ID: B7A65E0B62862E76@10.174.254.3
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Subscription-State: active
Content-Length: 92
Messages-Waiting: no
Message-Account: sip:asterisk@10.174.1.251
Voice-Message: 0/0 (0/0)
---
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:1902 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #163
<-- SIP read from 10.174.254.3:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.174.1.251:5060;branch=z9hG4bK2abfdab1;rport=5060;received=10.174.1.251
From: "asterisk" <sip:asterisk@10.174.1.251>;tag=as5894afe0
To: <sip:sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB>;tag=3787479280
Call-ID: B7A65E0B62862E76@10.174.254.3
CSeq: 102 NOTIFY
User-Agent: AVM FRITZ!Box Fon WLAN 7050 14.04.15 (Jul 12 2006)
Content-Length: 0
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 0: SIP/2.0 200 OK (14)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 1: Via: SIP/2.0/UDP 10.174.1.251:5060;branch=z9hG4bK2abfdab1;rport=5060;received=10.174.1.251 (90)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 2: From: "asterisk" <sip:asterisk@10.174.1.251>;tag=as5894afe0 (59)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 3: To: <sip:sip:pcadach2@10.174.254.3;uniq=5F9AECBFD3ECF8A74D1CEE3044AAB>;tag=3787479280 (85)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 4: Call-ID: B7A65E0B62862E76@10.174.254.3 (38)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 5: CSeq: 102 NOTIFY (16)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 6: User-Agent: AVM FRITZ!Box Fon WLAN 7050 14.04.15 (Jul 12 2006) (62)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 7: Content-Length: 0 (17)
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 8: (0)
--- (8 headers 0 lines)---
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4157 find_call: = Found Their Call ID: B7A65E0B62862E76@10.174.254.3 Their Tag 3787479280 Our tag: as5894afe0
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:1995 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #163
[Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:2005 __sip_ack: Stopping retransmission on 'B7A65E0B62862E76@10.174.254.3' of Request 102: Match Not Found
[Aug 29 07:21:01] DEBUG[19311]: chan_sip.c:1934 __sip_autodestruct: Auto destroying call 'B20932CCCE21960F@10.174.254.3'
[Aug 29 07:21:01] DEBUG[19311]: chan_sip.c:2976 sip_destroy: Destroying SIP dialog B20932CCCE21960F@10.174.254.3
Really destroying SIP dialog 'B20932CCCE21960F@10.174.254.3' Method: REGISTER
[Aug 29 07:21:07] DEBUG[19311]: chan_sip.c:1934 __sip_autodestruct: Auto destroying call '47A716F3F7390640@10.174.254.3'
[Aug 29 07:21:07] DEBUG[19311]: chan_sip.c:2976 sip_destroy: Destroying SIP dialog 47A716F3F7390640@10.174.254.3
Really destroying SIP dialog '47A716F3F7390640@10.174.254.3' Method: REGISTER