<-- 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: From: ;tag=3787479280 To: Call-ID: B7A65E0B62862E76@10.174.254.3 CSeq: 4 SUBSCRIBE Contact: 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: (28) [Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 3: From: ;tag=3787479280 (48) [Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 4: To: (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: (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: ;tag=3787479280 To: ;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: 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: From: ;tag=775120401 To: Call-ID: B7A65E0B62862E76@10.174.254.3 CSeq: 5 SUBSCRIBE Contact: 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: (28) [Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 3: From: ;tag=775120401 (47) [Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 4: To: (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: (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: ;tag=775120401 To: ;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: ;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" ;tag=as5894afe0 To: ;tag=3787479280 Contact: 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" ;tag=as5894afe0 To: ;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" ;tag=as5894afe0 (59) [Aug 29 07:20:52] DEBUG[19311]: chan_sip.c:4329 parse_request: Header 3: To: ;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