[root@kb-serge-laptop asterisk]# asterisk -Tvvvvvdddddc Parsing /etc/asterisk/asterisk.conf Parsing /etc/asterisk/extconfig.conf Asterisk SVN-trunk-r31443, Copyright (C) 1999 - 2006 Digium, Inc. and others. Created by Mark Spencer Asterisk comes with ABSOLUTELY NO WARRANTY; type 'show warranty' for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type 'show license' for details. ========================================================================= Asterisk Ready. *CLI> [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 2990b2b62451d7d7199a73a670f7e3c2@192.168.1.92 Really destroying SIP dialog '2990b2b62451d7d7199a73a670f7e3c2@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 5399f08a035b0e28169d7f79752f210b@192.168.1.92 Really destroying SIP dialog '5399f08a035b0e28169d7f79752f210b@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 3ab0f9181fa87374284805ae6080f9dd@192.168.1.92 Really destroying SIP dialog '3ab0f9181fa87374284805ae6080f9dd@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 4238ef883877562e7e936fa7609a29fe@192.168.1.92 Really destroying SIP dialog '4238ef883877562e7e936fa7609a29fe@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 5301e41c3b02de38292d13fe33db3ecd@192.168.1.92 Really destroying SIP dialog '5301e41c3b02de38292d13fe33db3ecd@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 42f1db440e1cb748426ef52373f5bcf8@192.168.1.92 Really destroying SIP dialog '42f1db440e1cb748426ef52373f5bcf8@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 707f40c7197b35f96a807fd80d60185a@192.168.1.92 Really destroying SIP dialog '707f40c7197b35f96a807fd80d60185a@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 7641da1166bd5ce11ca0b3c5243e7a8f@192.168.1.92 Really destroying SIP dialog '7641da1166bd5ce11ca0b3c5243e7a8f@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 0803626c097746ec0dc62f930408f0e6@192.168.1.92 Really destroying SIP dialog '0803626c097746ec0dc62f930408f0e6@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 29b191995c67e3776c11a2e71b7f5460@192.168.1.92 Really destroying SIP dialog '29b191995c67e3776c11a2e71b7f5460@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 02b7e5645946faa06efb9a50188c4d06@192.168.1.92 Really destroying SIP dialog '02b7e5645946faa06efb9a50188c4d06@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 47707c462a8c30735e6199e231fb8a6c@192.168.1.92 Really destroying SIP dialog '47707c462a8c30735e6199e231fb8a6c@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 412339196047166a4814335702c52c6f@192.168.1.92 Really destroying SIP dialog '412339196047166a4814335702c52c6f@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 2b369f706408f98410401b8d52790b15@192.168.1.92 Really destroying SIP dialog '2b369f706408f98410401b8d52790b15@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 6a1dfae97181e97831dc359504de19b1@192.168.1.92 Really destroying SIP dialog '6a1dfae97181e97831dc359504de19b1@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 1ce94ab65f5433b0265773ea69494f2f@192.168.1.92 Really destroying SIP dialog '1ce94ab65f5433b0265773ea69494f2f@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 2dcab89328d4b61e58eded34496d2dfb@192.168.1.92 Really destroying SIP dialog '2dcab89328d4b61e58eded34496d2dfb@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 5ac8fc2772b3ea3513329cc0097476d1@192.168.1.92 Really destroying SIP dialog '5ac8fc2772b3ea3513329cc0097476d1@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 311102070432b8370f9d0beb17a06e58@192.168.1.92 Really destroying SIP dialog '311102070432b8370f9d0beb17a06e58@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 32e6d5be36c10ce111eb5d173b9ceeb0@192.168.1.92 Really destroying SIP dialog '32e6d5be36c10ce111eb5d173b9ceeb0@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 1f95c96e169955892b22d5c92ddabb2e@192.168.1.92 Really destroying SIP dialog '1f95c96e169955892b22d5c92ddabb2e@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 0ea99d254b87c20222005a866970d6cd@192.168.1.92 Really destroying SIP dialog '0ea99d254b87c20222005a866970d6cd@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 245a2b8e59b0c42225a4dc2f66c81548@192.168.1.92 Really destroying SIP dialog '245a2b8e59b0c42225a4dc2f66c81548@192.168.1.92' Method: NOTIFY [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:35:52] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 11e88b7a34562b5e7f0bd0f02a427060@192.168.1.92 Really destroying SIP dialog '11e88b7a34562b5e7f0bd0f02a427060@192.168.1.92' Method: NOTIFY sip show peers Name/username Host Dyn Nat ACL Port Status 152 (Unspecified) D 0 UNKNOWN 151 (Unspecified) D 0 UNKNOWN 148 (Unspecified) D 0 UNKNOWN 142 (Unspecified) D 0 UNKNOWN 141 (Unspecified) D 0 UNKNOWN 137 (Unspecified) D 0 UNKNOWN 122 (Unspecified) D 0 UNKNOWN 118 (Unspecified) D 0 UNKNOWN 117 (Unspecified) D 0 UNKNOWN 115 (Unspecified) D 0 UNKNOWN 114 (Unspecified) D 0 UNKNOWN 113 (Unspecified) D 0 UNKNOWN 112 (Unspecified) D 0 UNKNOWN 111 (Unspecified) D 0 UNKNOWN 110 (Unspecified) D 0 UNKNOWN 109 (Unspecified) D 0 UNKNOWN 108 (Unspecified) D 0 UNKNOWN 107 (Unspecified) D 0 UNKNOWN 106 (Unspecified) D 0 UNKNOWN 105 (Unspecified) D 0 UNKNOWN 103 (Unspecified) D 0 UNKNOWN 102 (Unspecified) D 0 UNKNOWN 100 (Unspecified) D 0 UNKNOWN serge (Unspecified) D 0 UNKNOWN 24 sip peers [0 online , 24 offline] *CLI> sip debug SIP Debugging enabled *CLI> <-- SIP read from 192.168.1.97:45864: REGISTER sip:192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK9D21D4AA26A84ADF92A310D015BA3193 From: 151 To: 151 Contact: "151" Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 CSeq: 29850 REGISTER Expires: 0 Max-Forwards: 70 User-Agent: X-PRO build 1082 Content-Length: 0 [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 0: REGISTER sip:192.168.1.90 SIP/2.0 (33) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK9D21D4AA26A84ADF92A310D015BA3193 (88) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 2: From: 151 (32) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 3: To: 151 (30) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 4: Contact: "151" (43) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 5: Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 (54) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 6: CSeq: 29850 REGISTER (20) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 7: Expires: 0 (10) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 8: Max-Forwards: 70 (16) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 9: User-Agent: X-PRO build 1082 (28) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 10: Content-Length: 0 (17) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 11: (0) --- (11 headers 0 lines)--- [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 - REGISTER (No RTP) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:13069 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER Using latest REGISTER request as basis request Sending to 192.168.1.97 : 45864 (NAT) Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK9D21D4AA26A84ADF92A310D015BA3193;received=192.168.1.97 From: 151 To: 151 Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 CSeq: 29850 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK9D21D4AA26A84ADF92A310D015BA3193;received=192.168.1.97 From: 151 To: 151 ;tag=as1e8b8571 Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 CSeq: 29850 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: WWW-Authenticate: Digest realm="kb-serge-laptop.katherinebishop.com", nonce="71049087" Content-Length: 0 --- Scheduling destruction of SIP dialog '7074CCC644A4418EA4AFC890A2442A26@192.168.1.90' in 32000 ms (Method: REGISTER) <-- SIP read from 192.168.1.97:45864: REGISTER sip:192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK025A088ACC124807A4E6A4D1824AAB39 From: 151 To: 151 Contact: "151" Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 CSeq: 29851 REGISTER Expires: 0 Authorization: Digest username="151",realm="kb-serge-laptop.katherinebishop.com",nonce="71049087",response="344976e20af621b4077474a7f54836a2",uri="sip:192.168.1.90" Max-Forwards: 70 User-Agent: X-PRO build 1082 Content-Length: 0 [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 0: REGISTER sip:192.168.1.90 SIP/2.0 (33) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK025A088ACC124807A4E6A4D1824AAB39 (88) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 2: From: 151 (32) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 3: To: 151 (30) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 4: Contact: "151" (43) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 5: Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 (54) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 6: CSeq: 29851 REGISTER (20) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 7: Expires: 0 (10) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 8: Authorization: Digest username="151",realm="kb-serge-laptop.katherinebishop.com",nonce="71049087",response="344976e20af621b4077474a7f54836a2",uri="sip:192.168.1.90" (164) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 9: Max-Forwards: 70 (16) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 10: User-Agent: X-PRO build 1082 (28) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 11: Content-Length: 0 (17) [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 12: (0) --- (12 headers 0 lines)--- [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:3802 find_call: = Found Their Call ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 Their Tag Our tag: as1e8b8571 [Jun 9 17:36:36] DEBUG[4580]: chan_sip.c:13069 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER Using latest REGISTER request as basis request Sending to 192.168.1.97 : 45864 (NAT) Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK025A088ACC124807A4E6A4D1824AAB39;received=192.168.1.97 From: 151 To: 151 Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 CSeq: 29851 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Jun 9 17:36:36] DEBUG[4580]: db.c:237 ast_db_del: Unable to find key '151' in family 'SIP/Registry' -- Unregistered SIP '151' Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK025A088ACC124807A4E6A4D1824AAB39;received=192.168.1.97 From: 151 To: 151 ;tag=as1e8b8571 Call-ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 CSeq: 29851 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Expires: 0 Date: Fri, 09 Jun 2006 21:36:36 GMT Content-Length: 0 --- Scheduling destruction of SIP dialog '7074CCC644A4418EA4AFC890A2442A26@192.168.1.90' in 15000 ms (Method: REGISTER) [Jun 9 17:36:36] DEBUG[4577]: chan_sip.c:13636 sip_devicestate: Checking device state for peer 151 [Jun 9 17:36:36] DEBUG[4577]: devicestate.c:189 do_state_change: Changing state for SIP/151 - state 5 (Unavailable) [Jun 9 17:36:47] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:36:47] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 6f96bd9e6118a71712f4dcac17d2f65d@192.168.1.92 Really destroying SIP dialog '6f96bd9e6118a71712f4dcac17d2f65d@192.168.1.92' Method: NOTIFY <-- SIP read from 192.168.1.97:45864: REGISTER sip:192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK7CF39CFB577D487ABD0CF35E7F83C672 From: 151 To: 151 Contact: "151" Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 CSeq: 30451 REGISTER Expires: 1800 Max-Forwards: 70 User-Agent: X-PRO build 1082 Content-Length: 0 [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 0: REGISTER sip:192.168.1.90 SIP/2.0 (33) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK7CF39CFB577D487ABD0CF35E7F83C672 (88) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 2: From: 151 (32) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 3: To: 151 (30) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 4: Contact: "151" (43) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 5: Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 (54) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 6: CSeq: 30451 REGISTER (20) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 7: Expires: 1800 (13) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 8: Max-Forwards: 70 (16) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 9: User-Agent: X-PRO build 1082 (28) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 10: Content-Length: 0 (17) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 11: (0) --- (11 headers 0 lines)--- [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3802 find_call: = No match Their Call ID: 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 Their Tag Our tag: as1e8b8571 [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for 2722C77999834959A45A98A55832954D@192.168.1.90 - REGISTER (No RTP) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:13069 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER Using latest REGISTER request as basis request Sending to 192.168.1.97 : 45864 (NAT) Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK7CF39CFB577D487ABD0CF35E7F83C672;received=192.168.1.97 From: 151 To: 151 Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 CSeq: 30451 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bK7CF39CFB577D487ABD0CF35E7F83C672;received=192.168.1.97 From: 151 To: 151 ;tag=as40c1fa30 Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 CSeq: 30451 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: WWW-Authenticate: Digest realm="kb-serge-laptop.katherinebishop.com", nonce="5dab44e6" Content-Length: 0 --- Scheduling destruction of SIP dialog '2722C77999834959A45A98A55832954D@192.168.1.90' in 32000 ms (Method: REGISTER) <-- SIP read from 192.168.1.97:45864: REGISTER sip:192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bKBB72EA97C01D42E78F5EEB11BBA12ED6 From: 151 To: 151 Contact: "151" Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 CSeq: 30452 REGISTER Expires: 1800 Authorization: Digest username="151",realm="kb-serge-laptop.katherinebishop.com",nonce="5dab44e6",response="6f55d63e1aa7f7d4486b31c366022467",uri="sip:192.168.1.90" Max-Forwards: 70 User-Agent: X-PRO build 1082 Content-Length: 0 [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 0: REGISTER sip:192.168.1.90 SIP/2.0 (33) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bKBB72EA97C01D42E78F5EEB11BBA12ED6 (88) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 2: From: 151 (32) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 3: To: 151 (30) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 4: Contact: "151" (43) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 5: Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 (54) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 6: CSeq: 30452 REGISTER (20) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 7: Expires: 1800 (13) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 8: Authorization: Digest username="151",realm="kb-serge-laptop.katherinebishop.com",nonce="5dab44e6",response="6f55d63e1aa7f7d4486b31c366022467",uri="sip:192.168.1.90" (164) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 9: Max-Forwards: 70 (16) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 10: User-Agent: X-PRO build 1082 (28) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 11: Content-Length: 0 (17) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 12: (0) --- (12 headers 0 lines)--- [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3802 find_call: = Found Their Call ID: 2722C77999834959A45A98A55832954D@192.168.1.90 Their Tag Our tag: as40c1fa30 [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:13069 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER Using latest REGISTER request as basis request Sending to 192.168.1.97 : 45864 (NAT) Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bKBB72EA97C01D42E78F5EEB11BBA12ED6;received=192.168.1.97 From: 151 To: 151 Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 CSeq: 30452 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 0: OPTIONS sip:151@192.168.1.97:45864 SIP/2.0 (42) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.92:5060;branch=z9hG4bK1bd877f3;rport (63) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 2: From: "asterisk" ;tag=as6356ec80 (59) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 3: To: (32) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 4: Contact: (36) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 5: Call-ID: 547aac732d6899a776d5c7a359be2b98@192.168.1.92 (54) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 8: Max-Forwards: 70 (16) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 9: Date: Fri, 09 Jun 2006 21:36:49 GMT (35) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 11: Supported: replaces (19) [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 12: Content-Length: 0 (17) Reliably Transmitting (no NAT) to 192.168.1.97:45864: OPTIONS sip:151@192.168.1.97:45864 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.92:5060;branch=z9hG4bK1bd877f3;rport From: "asterisk" ;tag=as6356ec80 To: Contact: Call-ID: 547aac732d6899a776d5c7a359be2b98@192.168.1.92 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 09 Jun 2006 21:36:49 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Jun 9 17:36:49] DEBUG[4580]: chan_sip.c:1670 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #5 -- Registered SIP '151' at 192.168.1.97 port 45864 expires 1800 -- Saved useragent "X-PRO build 1082" for peer 151 Transmitting (no NAT) to 192.168.1.97:45864: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.97:45864;rport;branch=z9hG4bKBB72EA97C01D42E78F5EEB11BBA12ED6;received=192.168.1.97 From: 151 To: 151 ;tag=as40c1fa30 Call-ID: 2722C77999834959A45A98A55832954D@192.168.1.90 CSeq: 30452 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Expires: 1800 Contact: ;expires=1800 Date: Fri, 09 Jun 2006 21:36:49 GMT Content-Length: 0 --- Scheduling destruction of SIP dialog '2722C77999834959A45A98A55832954D@192.168.1.90' in 15000 ms (Method: REGISTER) [Jun 9 17:36:49] DEBUG[4577]: chan_sip.c:13636 sip_devicestate: Checking device state for peer 151 [Jun 9 17:36:49] DEBUG[4577]: devicestate.c:189 do_state_change: Changing state for SIP/151 - state 1 (Not in use) [Jun 9 17:36:50] DEBUG[4580]: chan_sip.c:1560 retrans_pkt: SIP TIMER: Not rescheduling id #5:OPTIONS (Method 3) (No timer T1) Retransmitting #1 (no NAT) to 192.168.1.97:45864: OPTIONS sip:151@192.168.1.97:45864 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.92:5060;branch=z9hG4bK1bd877f3;rport From: "asterisk" ;tag=as6356ec80 To: Contact: Call-ID: 547aac732d6899a776d5c7a359be2b98@192.168.1.92 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 09 Jun 2006 21:36:49 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Jun 9 17:36:51] DEBUG[4580]: chan_sip.c:1702 __sip_autodestruct: Auto destroying call '7074CCC644A4418EA4AFC890A2442A26@192.168.1.90' [Jun 9 17:36:51] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 7074CCC644A4418EA4AFC890A2442A26@192.168.1.90 Really destroying SIP dialog '7074CCC644A4418EA4AFC890A2442A26@192.168.1.90' Method: REGISTER [Jun 9 17:36:51] DEBUG[4580]: chan_sip.c:1560 retrans_pkt: SIP TIMER: Not rescheduling id #5:OPTIONS (Method 3) (No timer T1) Retransmitting #2 (no NAT) to 192.168.1.97:45864: OPTIONS sip:151@192.168.1.97:45864 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.92:5060;branch=z9hG4bK1bd877f3;rport From: "asterisk" ;tag=as6356ec80 To: Contact: Call-ID: 547aac732d6899a776d5c7a359be2b98@192.168.1.92 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 09 Jun 2006 21:36:49 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Jun 9 17:36:52] DEBUG[4580]: chan_sip.c:1560 retrans_pkt: SIP TIMER: Not rescheduling id #5:OPTIONS (Method 3) (No timer T1) Retransmitting #3 (no NAT) to 192.168.1.97:45864: OPTIONS sip:151@192.168.1.97:45864 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.92:5060;branch=z9hG4bK1bd877f3;rport From: "asterisk" ;tag=as6356ec80 To: Contact: Call-ID: 547aac732d6899a776d5c7a359be2b98@192.168.1.92 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 09 Jun 2006 21:36:49 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Jun 9 17:36:53] DEBUG[4580]: chan_sip.c:1560 retrans_pkt: SIP TIMER: Not rescheduling id #5:OPTIONS (Method 3) (No timer T1) Retransmitting #4 (no NAT) to 192.168.1.97:45864: OPTIONS sip:151@192.168.1.97:45864 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.92:5060;branch=z9hG4bK1bd877f3;rport From: "asterisk" ;tag=as6356ec80 To: Contact: Call-ID: 547aac732d6899a776d5c7a359be2b98@192.168.1.92 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 09 Jun 2006 21:36:49 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Jun 9 17:36:53] NOTICE[4580]: chan_sip.c:13522 sip_poke_noanswer: Peer '151' is now UNREACHABLE! Last qualify: 0 [Jun 9 17:36:53] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 547aac732d6899a776d5c7a359be2b98@192.168.1.92 Really destroying SIP dialog '547aac732d6899a776d5c7a359be2b98@192.168.1.92' Method: OPTIONS [Jun 9 17:36:53] DEBUG[4577]: chan_sip.c:13636 sip_devicestate: Checking device state for peer 151 [Jun 9 17:36:53] DEBUG[4577]: devicestate.c:189 do_state_change: Changing state for SIP/151 - state 1 (Not in use) sip no <-- SIP read from 192.168.1.97:45864: [Jun 9 17:36:58] DEBUG[4580]: chan_sip.c:3974 parse_request: Header 0: (0) --- (0 headers 0 lines) Nat keepalive --- [Jun 9 17:36:58] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) [Jun 9 17:36:58] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 07d69281114b0952017cdd050e4f647d@192.168.1.92 Really destroying SIP dialog '07d69281114b0952017cdd050e4f647d@192.168.1.92' Method: NOTIFY debug SIP Debugging Disabled *CLI> [Jun 9 17:37:03] DEBUG[4580]: chan_sip.c:3754 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Jun 9 17:37:04] DEBUG[4580]: chan_sip.c:1702 __sip_autodestruct: Auto destroying call '2722C77999834959A45A98A55832954D@192.168.1.90' [Jun 9 17:37:04] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 2722C77999834959A45A98A55832954D@192.168.1.90 Really destroying SIP dialog '2722C77999834959A45A98A55832954D@192.168.1.90' Method: REGISTER [Jun 9 17:37:07] DEBUG[4580]: chan_sip.c:2703 sip_destroy: Destroying SIP dialog 6dad08d9246e15bb365972d271f66182@192.168.1.92 Really destroying SIP dialog '6dad08d9246e15bb365972d271f66182@192.168.1.92' Method: OPTIONS [Jun 9 17:37:07] DEBUG[4577]: chan_sip.c:13636 sip_devicestate: Checking device state for peer 151 [Jun 9 17:37:07] DEBUG[4577]: devicestate.c:189 do_state_change: Changing state for SIP/151 - state 1 (Not in use)