Jun 8 11:13:23 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.114' - Username/auth name mismatch Jun 8 11:13:23 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.202' - Username/auth name mismatch Jun 8 11:13:25 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.112' - Username/auth name mismatch Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #376 Jun 8 11:13:38 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #378 Jun 8 11:13:38 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 123: Match Found Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Cancelling timeout 376 Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 123: Match Found Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Cancelling timeout 378 Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #384 Jun 8 11:13:38 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Auto destroying call '86444b39-b7fa76e7-6dd03926@192.168.3.114' Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 123: Match Found Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Cancelling timeout 384 Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for f6b487c-cc9277ea-ff37f08f@192.168.3.207 - REGISTER (No RTP) Jun 8 11:13:38 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:13:38 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:13:38 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.207' - Username/auth name mismatch Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #389 Jun 8 11:13:38 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 123: Match Found Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Cancelling timeout 389 Jun 8 11:13:38 DEBUG[2693] chan_sip.c: Auto destroying call '468c3ba2-1787f50c-cb48f75@192.168.3.202' Jun 8 11:13:40 DEBUG[2693] chan_sip.c: Auto destroying call 'b3b3e2e4-3612e9aa-6c185e4f@192.168.3.112' Jun 8 11:13:53 DEBUG[2693] chan_sip.c: Auto destroying call 'f6b487c-cc9277ea-ff37f08f@192.168.3.207' Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:13:54 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:13:54 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:13:54 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #393 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1078ef11;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as48136a7c (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:13:54 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1078ef11;rport From: ;tag=as48136a7c To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 124 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #394 Jun 8 11:13:54 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #395 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK7968c0ae;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as63bd971d (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:13:54 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK7968c0ae;rport From: ;tag=as63bd971d To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 124 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #396 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:13:54 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:13:54 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1078ef11;rport From: ;tag=as48136a7c To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 124 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1078ef11;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as48136a7c (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #394 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 124: Match Found Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Cancelling timeout 393 Jun 8 11:13:54 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:13:54 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:13:54 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK7968c0ae;rport From: ;tag=as63bd971d To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 124 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK7968c0ae;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as63bd971d (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #396 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 124: Match Found Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Cancelling timeout 395 Jun 8 11:13:54 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:13:54 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:13:54 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #401 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK609d88d2;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as090f8292 (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:13:54 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK609d88d2;rport From: ;tag=as090f8292 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 124 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #402 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:13:54 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:13:54 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK609d88d2;rport From: ;tag=as090f8292 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 124 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK609d88d2;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as090f8292 (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #402 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 124: Match Found Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Cancelling timeout 401 Jun 8 11:13:54 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:13:54 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:13:54 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #405 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK59e0ec87;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as21ca6b01 (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:13:54 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:13:54 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK59e0ec87;rport From: ;tag=as21ca6b01 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 124 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #406 Jun 8 11:13:54 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK59e0ec87;rport From: ;tag=as21ca6b01 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 124 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK59e0ec87;rport (63) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as21ca6b01 (61) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 5: CSeq: 124 REGISTER (18) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:13:54 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:13:54 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #406 Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 124: Match Found Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:13:54 DEBUG[2693] chan_sip.c: Cancelling timeout 405 Jun 8 11:13:54 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:13:54 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.112;branch=z9hG4bK90800a81E6C8C47C (61) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 2: From: "Ben" ;tag=369EAC73-8FA0EBC8 (55) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 5: Call-ID: 687b9367-e87d2c0d-43f6ca42@192.168.3.112 (49) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 687b9367-e87d2c0d-43f6ca42@192.168.3.112 - REGISTER (No RTP) Jun 8 11:14:00 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:14:00 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:14:00 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.112' - Username/auth name mismatch Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.114;branch=z9hG4bKe525974889408AD1 (61) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 2: From: "Darrin" ;tag=DD3DCDFA-CA66307D (58) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 5: Call-ID: 9162161e-2a9c7a84-9b7a42bf@192.168.3.114 (49) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 9162161e-2a9c7a84-9b7a42bf@192.168.3.114 - REGISTER (No RTP) Jun 8 11:14:06 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:14:06 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:14:06 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.114' - Username/auth name mismatch Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:10 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:10 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:10 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #411 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5fc9b6c5;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as15e659e1 (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:14:10 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5fc9b6c5;rport From: ;tag=as15e659e1 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 125 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #412 Jun 8 11:14:10 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #413 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK40e78754;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as460d9f1d (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:14:10 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK40e78754;rport From: ;tag=as460d9f1d To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 125 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #414 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:10 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:10 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5fc9b6c5;rport From: ;tag=as15e659e1 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 125 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5fc9b6c5;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as15e659e1 (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #412 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 125: Match Found Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Cancelling timeout 411 Jun 8 11:14:10 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:14:10 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:10 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK40e78754;rport From: ;tag=as460d9f1d To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 125 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK40e78754;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as460d9f1d (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #414 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 125: Match Found Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Cancelling timeout 413 Jun 8 11:14:10 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:14:10 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:10 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #419 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK20842672;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as06a3064e (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:14:10 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK20842672;rport From: ;tag=as06a3064e To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 125 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #420 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:10 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:10 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK20842672;rport From: ;tag=as06a3064e To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 125 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK20842672;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as06a3064e (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #420 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 125: Match Found Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Cancelling timeout 419 Jun 8 11:14:10 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:14:10 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:10 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #423 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25a72eb4;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as45968313 (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:10 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:14:10 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25a72eb4;rport From: ;tag=as45968313 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 125 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #424 Jun 8 11:14:10 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25a72eb4;rport From: ;tag=as45968313 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 125 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25a72eb4;rport (63) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as45968313 (61) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 5: CSeq: 125 REGISTER (18) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:10 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:10 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #424 Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 125: Match Found Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:10 DEBUG[2693] chan_sip.c: Cancelling timeout 423 Jun 8 11:14:10 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:14:10 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:15 DEBUG[2693] chan_sip.c: Auto destroying call '687b9367-e87d2c0d-43f6ca42@192.168.3.112' Jun 8 11:14:15 VERBOSE[2693] logger.c: Destroying call '687b9367-e87d2c0d-43f6ca42@192.168.3.112' Jun 8 11:14:18 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.202:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bKee69124b8E55DFAA From: "Kelly" ;tag=C227D371-F6FC830E To: CSeq: 1 REGISTER Call-ID: 24a68dd-9db95bbf-cf35e054@192.168.3.202 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_601-UA/1.6.2.0041 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bKee69124b8E55DFAA (61) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 2: From: "Kelly" ;tag=C227D371-F6FC830E (57) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 5: Call-ID: 24a68dd-9db95bbf-cf35e054@192.168.3.202 (48) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_601-UA/1.6.2.0041 (54) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:14:18 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:14:18 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:14:18 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 24a68dd-9db95bbf-cf35e054@192.168.3.202 - REGISTER (No RTP) Jun 8 11:14:18 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:14:18 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:14:18 VERBOSE[2693] logger.c: Sending to 192.168.3.202 : 5060 (non-NAT) Jun 8 11:14:18 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.202:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bKee69124b8E55DFAA;received=192.168.3.202 From: "Kelly" ;tag=C227D371-F6FC830E To: ;tag=as3369bf10 Call-ID: 24a68dd-9db95bbf-cf35e054@192.168.3.202 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:14:18 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:14:18 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.202' - Username/auth name mismatch Jun 8 11:14:18 VERBOSE[2693] logger.c: Scheduling destruction of call '24a68dd-9db95bbf-cf35e054@192.168.3.202' in 15000 ms Jun 8 11:14:21 DEBUG[2693] chan_sip.c: Auto destroying call '9162161e-2a9c7a84-9b7a42bf@192.168.3.114' Jun 8 11:14:21 VERBOSE[2693] logger.c: Destroying call '9162161e-2a9c7a84-9b7a42bf@192.168.3.114' Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:26 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:26 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:26 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #428 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4c8a3be7;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as035754c2 (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:14:26 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4c8a3be7;rport From: ;tag=as035754c2 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 126 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #429 Jun 8 11:14:26 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #430 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK47e19bf1;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as1cc64d1a (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:14:26 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK47e19bf1;rport From: ;tag=as1cc64d1a To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 126 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #431 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:26 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:26 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #432 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1723c0cd;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as3eaf6a6e (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:14:26 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1723c0cd;rport From: ;tag=as3eaf6a6e To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 126 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #433 Jun 8 11:14:26 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4c8a3be7;rport From: ;tag=as035754c2 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 126 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4c8a3be7;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as035754c2 (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #429 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 126: Match Found Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Cancelling timeout 428 Jun 8 11:14:26 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:14:26 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:26 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK47e19bf1;rport From: ;tag=as1cc64d1a To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 126 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK47e19bf1;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as1cc64d1a (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #431 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 126: Match Found Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Cancelling timeout 430 Jun 8 11:14:26 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:14:26 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:26 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:26 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1723c0cd;rport From: ;tag=as3eaf6a6e To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 126 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK1723c0cd;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as3eaf6a6e (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #433 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 126: Match Found Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Cancelling timeout 432 Jun 8 11:14:26 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:14:26 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:26 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #440 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4261a6bf;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as3c42486a (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:26 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:14:26 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4261a6bf;rport From: ;tag=as3c42486a To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 126 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #441 Jun 8 11:14:26 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4261a6bf;rport From: ;tag=as3c42486a To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 126 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4261a6bf;rport (63) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as3c42486a (61) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 5: CSeq: 126 REGISTER (18) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:26 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:26 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #441 Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 126: Match Found Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:26 DEBUG[2693] chan_sip.c: Cancelling timeout 440 Jun 8 11:14:26 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:14:26 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:27 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: INVITE sip:17653820296@24.123.18.101:5060 SIP/2.0 Via: SIP/2.0/UDP 216.115.20.41:5061 Via: SIP/2.0/UDP 216.115.30.26:5060 Via: SIP/2.0/UDP 208.48.204.17:5060;branch=z9hG4bKCE8 Record-Route: From: "LOWMILLER HEATH" ;tag=2143609300 To: Call-ID: 4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17 CSeq: 101 INVITE Contact: Max-Forwards: 14 Content-Type: application/sdp Content-Length: 361 v=0 o=CiscoSystemsSIP-GW-UserAgent 7774 4427 IN IP4 208.48.204.20 s=SIP Call c=IN IP4 208.48.204.20 t=0 0 m=audio 13600 RTP/AVP 0 18 2 100 101 c=IN IP4 208.48.204.20 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:2 G726-32/8000 a=rtpmap:100 X-NSE/8000 a=fmtp:100 192-194 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 0: INVITE sip:17653820296@24.123.18.101:5060 SIP/2.0 (49) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 216.115.20.41:5061 (35) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 2: Via: SIP/2.0/UDP 216.115.30.26:5060 (35) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 3: Via: SIP/2.0/UDP 208.48.204.17:5060;branch=z9hG4bKCE8 (53) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 4: Record-Route: (50) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 5: From: "LOWMILLER HEATH" ;tag=2143609300 (70) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 6: To: (41) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 7: Call-ID: 4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17 (58) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 8: CSeq: 101 INVITE (16) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 9: Contact: (45) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 10: Max-Forwards: 14 (16) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 11: Content-Type: application/sdp (29) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 12: Content-Length: 361 (21) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 13: (0) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: v=0 (3) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: o=CiscoSystemsSIP-GW-UserAgent 7774 4427 IN IP4 208.48.204.20 (61) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: s=SIP Call (10) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: c=IN IP4 208.48.204.20 (22) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: t=0 0 (5) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: m=audio 13600 RTP/AVP 0 18 2 100 101 (36) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: c=IN IP4 208.48.204.20 (22) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=fmtp:18 annexb=no (19) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=rtpmap:100 X-NSE/8000 (23) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=fmtp:100 192-194 (18) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Line: a=fmtp:101 0-16 (15) Jun 8 11:14:27 VERBOSE[2693] logger.c: --- (13 headers 15 lines)Jun 8 11:14:27 VERBOSE[2693] logger.c: --- (13 headers 15 lines)--- Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17 - INVITE (With RTP) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Jun 8 11:14:27 VERBOSE[2693] logger.c: Using INVITE request as basis request - 4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17 Jun 8 11:14:27 VERBOSE[2693] logger.c: Sending to 216.115.20.41 : 5061 (non-NAT) Jun 8 11:14:27 VERBOSE[2693] logger.c: Found peer 'VON' Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Setting NAT on RTP to 524288 Jun 8 11:14:27 VERBOSE[2693] logger.c: Reliably Transmitting (NAT) to 216.115.20.41:5061: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 216.115.20.41:5061;received=216.115.20.41 Via: SIP/2.0/UDP 216.115.30.26:5060 Via: SIP/2.0/UDP 208.48.204.17:5060;branch=z9hG4bKCE8 From: "LOWMILLER HEATH" ;tag=2143609300 To: ;tag=as788b0220 Call-ID: 4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="003e88b1" Content-Length: 0 --- Jun 8 11:14:27 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #444 Jun 8 11:14:27 VERBOSE[2693] logger.c: Scheduling destruction of call '4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17' in 15000 ms Jun 8 11:14:27 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: ACK sip:17653820296@24.123.18.101:5060 SIP/2.0 Via: SIP/2.0/UDP 216.115.20.41:5061 From: "LOWMILLER HEATH" ;tag=2143609300 To: ;tag=as788b0220 Call-ID: 4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17 CSeq: 101 ACK Content-Length: 0 Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 0: ACK sip:17653820296@24.123.18.101:5060 SIP/2.0 (46) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 216.115.20.41:5061 (35) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 2: From: "LOWMILLER HEATH" ;tag=2143609300 (70) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 3: To: ;tag=as788b0220 (56) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17 (58) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 5: CSeq: 101 ACK (13) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 6: Content-Length: 0 (17) Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Header 7: (0) Jun 8 11:14:27 VERBOSE[2693] logger.c: --- (7 headers 0 lines)Jun 8 11:14:27 VERBOSE[2693] logger.c: --- (7 headers 0 lines)--- Jun 8 11:14:27 DEBUG[2693] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Jun 8 11:14:27 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #444 Jun 8 11:14:27 DEBUG[2693] chan_sip.c: Stopping retransmission on '4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17' of Response 101: Match Found Jun 8 11:14:30 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.207:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.207;branch=z9hG4bKd791f829D1E5DAF4 From: "Jim" ;tag=199B51E3-1C4C94F0 To: CSeq: 1 REGISTER Call-ID: c8be9a47-feeee385-d4de06e2@192.168.3.207 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.207;branch=z9hG4bKd791f829D1E5DAF4 (61) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 2: From: "Jim" ;tag=199B51E3-1C4C94F0 (55) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 5: Call-ID: c8be9a47-feeee385-d4de06e2@192.168.3.207 (49) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:14:30 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:14:30 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:14:30 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for c8be9a47-feeee385-d4de06e2@192.168.3.207 - REGISTER (No RTP) Jun 8 11:14:30 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:14:30 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:14:30 VERBOSE[2693] logger.c: Sending to 192.168.3.207 : 5060 (non-NAT) Jun 8 11:14:30 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.207:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.207;branch=z9hG4bKd791f829D1E5DAF4;received=192.168.3.207 From: "Jim" ;tag=199B51E3-1C4C94F0 To: ;tag=as728e3697 Call-ID: c8be9a47-feeee385-d4de06e2@192.168.3.207 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:14:30 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:14:30 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.207' - Username/auth name mismatch Jun 8 11:14:30 VERBOSE[2693] logger.c: Scheduling destruction of call 'c8be9a47-feeee385-d4de06e2@192.168.3.207' in 15000 ms Jun 8 11:14:33 VERBOSE[2719] logger.c: -- Remote UNIX connection disconnected Jun 8 11:14:33 DEBUG[2693] chan_sip.c: Auto destroying call '24a68dd-9db95bbf-cf35e054@192.168.3.202' Jun 8 11:14:33 VERBOSE[2693] logger.c: Destroying call '24a68dd-9db95bbf-cf35e054@192.168.3.202' Jun 8 11:14:35 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.112:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.112;branch=z9hG4bK94c5e214B8CBC47F From: "Ben" ;tag=C34D3BA6-259D9B8B To: CSeq: 1 REGISTER Call-ID: b2e866da-91966760-214e0c25@192.168.3.112 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.112;branch=z9hG4bK94c5e214B8CBC47F (61) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 2: From: "Ben" ;tag=C34D3BA6-259D9B8B (55) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 5: Call-ID: b2e866da-91966760-214e0c25@192.168.3.112 (49) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:14:35 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:14:35 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:14:35 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for b2e866da-91966760-214e0c25@192.168.3.112 - REGISTER (No RTP) Jun 8 11:14:35 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:14:35 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:14:35 VERBOSE[2693] logger.c: Sending to 192.168.3.112 : 5060 (non-NAT) Jun 8 11:14:35 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.112:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.112;branch=z9hG4bK94c5e214B8CBC47F;received=192.168.3.112 From: "Ben" ;tag=C34D3BA6-259D9B8B To: ;tag=as65a95597 Call-ID: b2e866da-91966760-214e0c25@192.168.3.112 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:14:35 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:14:35 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.112' - Username/auth name mismatch Jun 8 11:14:35 VERBOSE[2693] logger.c: Scheduling destruction of call 'b2e866da-91966760-214e0c25@192.168.3.112' in 15000 ms Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Auto destroying call '4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17' Jun 8 11:14:42 VERBOSE[2693] logger.c: Destroying call '4F5C9874-F63811DA-A682ADA4-BC992F66@208.48.204.17' Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:42 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:42 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:42 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:42 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #448 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4a44a31e;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as25c2d74a (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:14:42 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4a44a31e;rport From: ;tag=as25c2d74a To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 127 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #449 Jun 8 11:14:42 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #450 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5971c27e;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as2bc510d8 (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:14:42 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5971c27e;rport From: ;tag=as2bc510d8 To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 127 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #451 Jun 8 11:14:42 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #452 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0bc45cee;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as30e41613 (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:14:42 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0bc45cee;rport From: ;tag=as30e41613 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 127 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #453 Jun 8 11:14:42 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4a44a31e;rport From: ;tag=as25c2d74a To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 127 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4a44a31e;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as25c2d74a (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #449 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 127: Match Found Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Cancelling timeout 448 Jun 8 11:14:42 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:14:42 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:42 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5971c27e;rport From: ;tag=as2bc510d8 To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 127 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5971c27e;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as2bc510d8 (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #451 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 127: Match Found Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Cancelling timeout 450 Jun 8 11:14:42 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:14:42 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:42 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:42 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0bc45cee;rport From: ;tag=as30e41613 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 127 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0bc45cee;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as30e41613 (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #453 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 127: Match Found Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Cancelling timeout 452 Jun 8 11:14:42 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:14:42 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:42 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #460 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK62df6a24;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as4233d012 (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:42 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:14:42 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK62df6a24;rport From: ;tag=as4233d012 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 127 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #461 Jun 8 11:14:42 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK62df6a24;rport From: ;tag=as4233d012 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 127 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK62df6a24;rport (63) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as4233d012 (61) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 5: CSeq: 127 REGISTER (18) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:42 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:42 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #461 Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 127: Match Found Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:42 DEBUG[2693] chan_sip.c: Cancelling timeout 460 Jun 8 11:14:42 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:14:42 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:45 DEBUG[2693] chan_sip.c: Auto destroying call 'c8be9a47-feeee385-d4de06e2@192.168.3.207' Jun 8 11:14:45 VERBOSE[2693] logger.c: Destroying call 'c8be9a47-feeee385-d4de06e2@192.168.3.207' Jun 8 11:14:50 DEBUG[2693] chan_sip.c: Auto destroying call 'b2e866da-91966760-214e0c25@192.168.3.112' Jun 8 11:14:50 VERBOSE[2693] logger.c: Destroying call 'b2e866da-91966760-214e0c25@192.168.3.112' Jun 8 11:14:54 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.114:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.114;branch=z9hG4bKd282ff693EB81C16 From: "Darrin" ;tag=1B1FA6E3-BC025C72 To: CSeq: 1 REGISTER Call-ID: 657c7b97-e3182395-cb98fdbc@192.168.3.114 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.114;branch=z9hG4bKd282ff693EB81C16 (61) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 2: From: "Darrin" ;tag=1B1FA6E3-BC025C72 (58) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 5: Call-ID: 657c7b97-e3182395-cb98fdbc@192.168.3.114 (49) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:14:54 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:14:54 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:14:54 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 657c7b97-e3182395-cb98fdbc@192.168.3.114 - REGISTER (No RTP) Jun 8 11:14:54 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:14:54 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:14:54 VERBOSE[2693] logger.c: Sending to 192.168.3.114 : 5060 (non-NAT) Jun 8 11:14:54 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.114:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.114;branch=z9hG4bKd282ff693EB81C16;received=192.168.3.114 From: "Darrin" ;tag=1B1FA6E3-BC025C72 To: ;tag=as22c84a64 Call-ID: 657c7b97-e3182395-cb98fdbc@192.168.3.114 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:14:54 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:14:54 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.114' - Username/auth name mismatch Jun 8 11:14:54 VERBOSE[2693] logger.c: Scheduling destruction of call '657c7b97-e3182395-cb98fdbc@192.168.3.114' in 15000 ms Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:58 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:58 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:58 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:14:58 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #465 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK180fe33c;rport (63) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as73d8ec2b (61) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:58 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:58 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:14:58 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK180fe33c;rport From: ;tag=as73d8ec2b To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 128 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:58 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #466 Jun 8 11:14:58 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #467 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0dc66fd7;rport (63) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as22cf90da (61) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:58 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:58 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:14:58 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0dc66fd7;rport From: ;tag=as22cf90da To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 128 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:58 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #468 Jun 8 11:14:58 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #469 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25d41a91;rport (63) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as18320f97 (61) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:58 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:58 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:14:58 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25d41a91;rport From: ;tag=as18320f97 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 128 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:58 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #470 Jun 8 11:14:58 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK180fe33c;rport From: ;tag=as73d8ec2b To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 128 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK180fe33c;rport (63) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as73d8ec2b (61) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:58 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:58 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:58 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #466 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 128: Match Found Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Cancelling timeout 465 Jun 8 11:14:58 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:14:58 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:58 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0dc66fd7;rport From: ;tag=as22cf90da To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 128 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0dc66fd7;rport (63) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as22cf90da (61) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:58 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:58 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:58 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #468 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 128: Match Found Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Cancelling timeout 467 Jun 8 11:14:58 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:14:58 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:58 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:14:58 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25d41a91;rport From: ;tag=as18320f97 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 128 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK25d41a91;rport (63) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as18320f97 (61) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:58 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:58 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:58 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #470 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 128: Match Found Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Cancelling timeout 469 Jun 8 11:14:58 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:14:58 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:14:58 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #477 Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK245493dd;rport (63) Jun 8 11:14:58 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as0b5b2cad (61) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:14:59 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:14:59 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:14:59 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK245493dd;rport From: ;tag=as0b5b2cad To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 128 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:14:59 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #478 Jun 8 11:14:59 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK245493dd;rport From: ;tag=as0b5b2cad To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 128 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK245493dd;rport (63) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as0b5b2cad (61) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 5: CSeq: 128 REGISTER (18) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:14:59 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:14:59 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:14:59 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #478 Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 128: Match Found Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:14:59 DEBUG[2693] chan_sip.c: Cancelling timeout 477 Jun 8 11:14:59 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:14:59 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:09 DEBUG[2693] chan_sip.c: Auto destroying call '657c7b97-e3182395-cb98fdbc@192.168.3.114' Jun 8 11:15:09 VERBOSE[2693] logger.c: Destroying call '657c7b97-e3182395-cb98fdbc@192.168.3.114' Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:15:14 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:15:14 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:15:14 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:15:14 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #481 Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK45a1074f;rport (63) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as33688c3c (61) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:14 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:14 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:15:14 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK45a1074f;rport From: ;tag=as33688c3c To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 129 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:14 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #482 Jun 8 11:15:14 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #483 Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK23f4953b;rport (63) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as1ae6f85a (61) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:14 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:14 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:15:14 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK23f4953b;rport From: ;tag=as1ae6f85a To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 129 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:14 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #484 Jun 8 11:15:14 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #485 Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK71902297;rport (63) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as7fc323f7 (61) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:14 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:14 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:15:14 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK71902297;rport From: ;tag=as7fc323f7 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 129 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:14 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #486 Jun 8 11:15:14 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK45a1074f;rport From: ;tag=as33688c3c To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 129 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK45a1074f;rport (63) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as33688c3c (61) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:14 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:14 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:14 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #482 Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 129: Match Found Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Cancelling timeout 481 Jun 8 11:15:14 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:15:14 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:14 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK23f4953b;rport From: ;tag=as1ae6f85a To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 129 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK23f4953b;rport (63) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as1ae6f85a (61) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:14 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:14 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:14 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #484 Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 129: Match Found Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Cancelling timeout 483 Jun 8 11:15:14 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:15:14 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:14 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:15:14 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:15:15 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK71902297;rport From: ;tag=as7fc323f7 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 129 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK71902297;rport (63) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as7fc323f7 (61) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:15 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:15 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:15 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #486 Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 129: Match Found Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Cancelling timeout 485 Jun 8 11:15:15 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:15:15 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:15 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #493 Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK11030216;rport (63) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as7715e279 (61) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:15 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:15 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:15:15 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK11030216;rport From: ;tag=as7715e279 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 129 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:15 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #494 Jun 8 11:15:15 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK11030216;rport From: ;tag=as7715e279 To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 129 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK11030216;rport (63) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as7715e279 (61) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 5: CSeq: 129 REGISTER (18) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:15 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:15 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:15 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #494 Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 129: Match Found Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:15 DEBUG[2693] chan_sip.c: Cancelling timeout 493 Jun 8 11:15:15 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:15:15 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:16 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.202:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bK9d63ccb2AD459745 From: "Kelly" ;tag=7988E720-57844859 To: CSeq: 1 REGISTER Call-ID: 639e3c9c-a93d2096-4321b867@192.168.3.202 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_601-UA/1.6.2.0041 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bK9d63ccb2AD459745 (61) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 2: From: "Kelly" ;tag=7988E720-57844859 (57) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 5: Call-ID: 639e3c9c-a93d2096-4321b867@192.168.3.202 (49) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_601-UA/1.6.2.0041 (54) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:15:16 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:15:16 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:15:16 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 639e3c9c-a93d2096-4321b867@192.168.3.202 - REGISTER (No RTP) Jun 8 11:15:16 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:15:16 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:15:16 VERBOSE[2693] logger.c: Sending to 192.168.3.202 : 5060 (non-NAT) Jun 8 11:15:16 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.202:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bK9d63ccb2AD459745;received=192.168.3.202 From: "Kelly" ;tag=7988E720-57844859 To: ;tag=as39551195 Call-ID: 639e3c9c-a93d2096-4321b867@192.168.3.202 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:15:16 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:15:16 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.202' - Username/auth name mismatch Jun 8 11:15:16 VERBOSE[2693] logger.c: Scheduling destruction of call '639e3c9c-a93d2096-4321b867@192.168.3.202' in 15000 ms Jun 8 11:15:18 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.112:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.112;branch=z9hG4bK881ef1971DDBF72 From: "Ben" ;tag=A27924C9-4010B23E To: CSeq: 1 REGISTER Call-ID: dfff683d-bca86a3-2ae51ef8@192.168.3.112 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.112;branch=z9hG4bK881ef1971DDBF72 (60) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 2: From: "Ben" ;tag=A27924C9-4010B23E (55) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 5: Call-ID: dfff683d-bca86a3-2ae51ef8@192.168.3.112 (48) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:15:18 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:15:18 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:15:18 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for dfff683d-bca86a3-2ae51ef8@192.168.3.112 - REGISTER (No RTP) Jun 8 11:15:18 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:15:18 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:15:18 VERBOSE[2693] logger.c: Sending to 192.168.3.112 : 5060 (non-NAT) Jun 8 11:15:18 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.112:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.112;branch=z9hG4bK881ef1971DDBF72;received=192.168.3.112 From: "Ben" ;tag=A27924C9-4010B23E To: ;tag=as63d2bfdd Call-ID: dfff683d-bca86a3-2ae51ef8@192.168.3.112 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:15:18 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:15:18 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.112' - Username/auth name mismatch Jun 8 11:15:18 VERBOSE[2693] logger.c: Scheduling destruction of call 'dfff683d-bca86a3-2ae51ef8@192.168.3.112' in 15000 ms Jun 8 11:15:29 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.207:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.207;branch=z9hG4bKe3f62f6cF3FE35FF From: "Jim" ;tag=738F2516-CA7DB69B To: CSeq: 1 REGISTER Call-ID: 6f2a37da-1c429a68-9e1347bd@192.168.3.207 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.207;branch=z9hG4bKe3f62f6cF3FE35FF (61) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 2: From: "Jim" ;tag=738F2516-CA7DB69B (55) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 5: Call-ID: 6f2a37da-1c429a68-9e1347bd@192.168.3.207 (49) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:15:29 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:15:29 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:15:29 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6f2a37da-1c429a68-9e1347bd@192.168.3.207 - REGISTER (No RTP) Jun 8 11:15:29 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:15:29 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:15:29 VERBOSE[2693] logger.c: Sending to 192.168.3.207 : 5060 (non-NAT) Jun 8 11:15:29 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.207:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.207;branch=z9hG4bKe3f62f6cF3FE35FF;received=192.168.3.207 From: "Jim" ;tag=738F2516-CA7DB69B To: ;tag=as22c3e96f Call-ID: 6f2a37da-1c429a68-9e1347bd@192.168.3.207 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:15:29 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:15:29 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.207' - Username/auth name mismatch Jun 8 11:15:29 VERBOSE[2693] logger.c: Scheduling destruction of call '6f2a37da-1c429a68-9e1347bd@192.168.3.207' in 15000 ms Jun 8 11:15:30 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.114:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.114;branch=z9hG4bK4ecc2b0e988E816F From: "Darrin" ;tag=F3CF5E70-61D3D73B To: CSeq: 1 REGISTER Call-ID: e728a1f4-7f1f63ea-3c125bad@192.168.3.114 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.114;branch=z9hG4bK4ecc2b0e988E816F (61) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 2: From: "Darrin" ;tag=F3CF5E70-61D3D73B (58) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 5: Call-ID: e728a1f4-7f1f63ea-3c125bad@192.168.3.114 (49) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.6.3.0067 (54) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:15:30 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:15:30 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for e728a1f4-7f1f63ea-3c125bad@192.168.3.114 - REGISTER (No RTP) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:15:30 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:15:30 VERBOSE[2693] logger.c: Sending to 192.168.3.114 : 5060 (non-NAT) Jun 8 11:15:30 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.114:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.114;branch=z9hG4bK4ecc2b0e988E816F;received=192.168.3.114 From: "Darrin" ;tag=F3CF5E70-61D3D73B To: ;tag=as191bd119 Call-ID: e728a1f4-7f1f63ea-3c125bad@192.168.3.114 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:15:30 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:15:30 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.114' - Username/auth name mismatch Jun 8 11:15:30 VERBOSE[2693] logger.c: Scheduling destruction of call 'e728a1f4-7f1f63ea-3c125bad@192.168.3.114' in 15000 ms Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:15:30 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:15:30 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:15:30 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:15:30 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #501 Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK306cf16d;rport (63) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as4937b986 (61) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:30 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:30 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:15:30 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK306cf16d;rport From: ;tag=as4937b986 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 130 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:30 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #502 Jun 8 11:15:30 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #503 Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5aa62932;rport (63) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as18871abf (61) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:30 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:30 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:30 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:15:30 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5aa62932;rport From: ;tag=as18871abf To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 130 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:30 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #504 Jun 8 11:15:31 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #505 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK472207df;rport (63) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as6192dc7d (61) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:31 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:31 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:15:31 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK472207df;rport From: ;tag=as6192dc7d To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 130 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:31 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #506 Jun 8 11:15:31 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK306cf16d;rport From: ;tag=as4937b986 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 130 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK306cf16d;rport (63) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as4937b986 (61) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:31 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #502 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 130: Match Found Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Cancelling timeout 501 Jun 8 11:15:31 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:15:31 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:31 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5aa62932;rport From: ;tag=as18871abf To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 130 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK5aa62932;rport (63) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as18871abf (61) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:31 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #504 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 130: Match Found Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Cancelling timeout 503 Jun 8 11:15:31 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:15:31 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:31 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK472207df;rport From: ;tag=as6192dc7d To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 130 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK472207df;rport (63) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as6192dc7d (61) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:31 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #506 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 130: Match Found Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Cancelling timeout 505 Jun 8 11:15:31 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:15:31 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:15:31 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:15:31 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #513 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4111bdbc;rport (63) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as7316462e (61) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:31 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:31 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:15:31 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4111bdbc;rport From: ;tag=as7316462e To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 130 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:31 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #514 Jun 8 11:15:31 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4111bdbc;rport From: ;tag=as7316462e To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 130 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4111bdbc;rport (63) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as7316462e (61) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 5: CSeq: 130 REGISTER (18) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:31 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:31 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #514 Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 130: Match Found Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Cancelling timeout 513 Jun 8 11:15:31 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:15:31 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:31 DEBUG[2693] chan_sip.c: Auto destroying call '639e3c9c-a93d2096-4321b867@192.168.3.202' Jun 8 11:15:31 VERBOSE[2693] logger.c: Destroying call '639e3c9c-a93d2096-4321b867@192.168.3.202' Jun 8 11:15:33 DEBUG[2693] chan_sip.c: Auto destroying call 'dfff683d-bca86a3-2ae51ef8@192.168.3.112' Jun 8 11:15:33 VERBOSE[2693] logger.c: Destroying call 'dfff683d-bca86a3-2ae51ef8@192.168.3.112' Jun 8 11:15:44 DEBUG[2693] chan_sip.c: Auto destroying call '6f2a37da-1c429a68-9e1347bd@192.168.3.207' Jun 8 11:15:44 VERBOSE[2693] logger.c: Destroying call '6f2a37da-1c429a68-9e1347bd@192.168.3.207' Jun 8 11:15:45 DEBUG[2693] chan_sip.c: Auto destroying call 'e728a1f4-7f1f63ea-3c125bad@192.168.3.114' Jun 8 11:15:45 VERBOSE[2693] logger.c: Destroying call 'e728a1f4-7f1f63ea-3c125bad@192.168.3.114' Jun 8 11:15:46 DEBUG[2693] chan_sip.c: Auto destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:15:46 VERBOSE[2693] logger.c: Destroying call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' Jun 8 11:15:46 DEBUG[2693] chan_sip.c: Auto destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:15:46 VERBOSE[2693] logger.c: Destroying call '173ff13378c011274051c09229acbe69@127.0.0.1' Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Auto destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:15:47 VERBOSE[2693] logger.c: Destroying call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' Jun 8 11:15:47 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820293@sphone.vopr.vonage.net Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #517 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0f6e70ce;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as400b7b19 (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820293@sphone.vopr.vonage.net Jun 8 11:15:47 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0f6e70ce;rport From: ;tag=as400b7b19 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 131 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #518 Jun 8 11:15:47 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820294@sphone.vopr.vonage.net Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 173ff13378c011274051c09229acbe69@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #519 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK24b45fe1;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as5d8e0a59 (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820294@sphone.vopr.vonage.net Jun 8 11:15:47 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK24b45fe1;rport From: ;tag=as5d8e0a59 To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 131 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #520 Jun 8 11:15:47 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820295@sphone.vopr.vonage.net Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 6bbf47710cf80958186767fa185ebb03@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #521 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK56f4f4af;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as389a0645 (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820295@sphone.vopr.vonage.net Jun 8 11:15:47 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK56f4f4af;rport From: ;tag=as389a0645 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 131 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #522 Jun 8 11:15:47 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0f6e70ce;rport From: ;tag=as400b7b19 To: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 CSeq: 131 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK0f6e70ce;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as400b7b19 (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 16407e723fc9b70e63a1998015f5efc0@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #518 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Stopping retransmission on '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' of Request 131: Match Found Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Cancelling timeout 517 Jun 8 11:15:47 VERBOSE[2693] logger.c: Scheduling destruction of call '16407e723fc9b70e63a1998015f5efc0@127.0.0.1' in 32000 ms Jun 8 11:15:47 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:47 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK24b45fe1;rport From: ;tag=as5d8e0a59 To: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 CSeq: 131 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK24b45fe1;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as5d8e0a59 (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 173ff13378c011274051c09229acbe69@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #520 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Stopping retransmission on '173ff13378c011274051c09229acbe69@127.0.0.1' of Request 131: Match Found Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Cancelling timeout 519 Jun 8 11:15:47 VERBOSE[2693] logger.c: Scheduling destruction of call '173ff13378c011274051c09229acbe69@127.0.0.1' in 32000 ms Jun 8 11:15:47 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:47 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK56f4f4af;rport From: ;tag=as389a0645 To: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 CSeq: 131 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK56f4f4af;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as389a0645 (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 6bbf47710cf80958186767fa185ebb03@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #522 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Stopping retransmission on '6bbf47710cf80958186767fa185ebb03@127.0.0.1' of Request 131: Match Found Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Cancelling timeout 521 Jun 8 11:15:47 VERBOSE[2693] logger.c: Scheduling destruction of call '6bbf47710cf80958186767fa185ebb03@127.0.0.1' in 32000 ms Jun 8 11:15:47 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Auto destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:15:47 VERBOSE[2693] logger.c: Destroying call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' Jun 8 11:15:47 NOTICE[2693] chan_sip.c: -- Re-registration for 17653820296@sphone.vopr.vonage.net Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 - REGISTER (No RTP) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Scheduled a registration timeout for sphone.vopr.vonage.net id #529 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 (43) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK78aec6a1;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as09e9399e (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: User-Agent: Asterisk PBX (24) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Max-Forwards: 70 (16) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: Expires: 120 (12) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 9: Contact: (29) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 10: Event: registration (19) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 12: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER 12 headers, 0 lines Jun 8 11:15:47 VERBOSE[2693] logger.c: REGISTER attempt 1 to 17653820296@sphone.vopr.vonage.net Jun 8 11:15:47 VERBOSE[2693] logger.c: Reliably Transmitting (no NAT) to 216.115.20.41:5061: REGISTER sip:sphone.vopr.vonage.net SIP/2.0 Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK78aec6a1;rport From: ;tag=as09e9399e To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 131 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #530 Jun 8 11:15:47 VERBOSE[2693] logger.c: <-- SIP read from 216.115.20.41:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK78aec6a1;rport From: ;tag=as09e9399e To: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 CSeq: 131 REGISTER Contact: ;expires=20 Content-Length: 0 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK78aec6a1;rport (63) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 2: From: ;tag=as09e9399e (61) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 3: To: (44) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 4: Call-ID: 0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1 (51) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 5: CSeq: 131 REGISTER (18) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 6: Contact: ;expires=20 (40) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 7: Content-Length: 0 (17) Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Header 8: (0) Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)Jun 8 11:15:47 VERBOSE[2693] logger.c: --- (8 headers 0 lines)--- Jun 8 11:15:47 DEBUG[2693] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #530 Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Stopping retransmission on '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' of Request 131: Match Found Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Registration successful Jun 8 11:15:47 DEBUG[2693] chan_sip.c: Cancelling timeout 529 Jun 8 11:15:47 VERBOSE[2693] logger.c: Scheduling destruction of call '0829c5b10e3f9d130a9c5d4e4d7ffe71@127.0.0.1' in 32000 ms Jun 8 11:15:47 NOTICE[2693] chan_sip.c: Outbound Registration: Expiry for sphone.vopr.vonage.net is 20 sec (Scheduling reregistration in 15 s) Jun 8 11:15:51 VERBOSE[2693] logger.c: <-- SIP read from 192.168.3.202:5060: REGISTER sip:192.168.3.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bK247dbaad6C7829E4 From: "Kelly" ;tag=F1E47C3-7A9488E8 To: CSeq: 1 REGISTER Call-ID: c789120f-9e190241-4f0eb71e@192.168.3.202 Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" User-Agent: PolycomSoundPointIP-SPIP_601-UA/1.6.2.0041 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 0: REGISTER sip:192.168.3.1:5060 SIP/2.0 (37) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bK247dbaad6C7829E4 (61) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 2: From: "Kelly" ;tag=F1E47C3-7A9488E8 (56) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 3: To: (25) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 4: CSeq: 1 REGISTER (16) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 5: Call-ID: c789120f-9e190241-4f0eb71e@192.168.3.202 (49) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 6: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (132) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 7: User-Agent: PolycomSoundPointIP-SPIP_601-UA/1.6.2.0041 (54) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 9: Expires: 3600 (13) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 10: Content-Length: 0 (17) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Header 11: (0) Jun 8 11:15:51 VERBOSE[2693] logger.c: --- (11 headers 0 lines)Jun 8 11:15:51 VERBOSE[2693] logger.c: --- (11 headers 0 lines)--- Jun 8 11:15:51 DEBUG[2693] chan_sip.c: Allocating new SIP dialog for c789120f-9e190241-4f0eb71e@192.168.3.202 - REGISTER (No RTP) Jun 8 11:15:51 DEBUG[2693] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Jun 8 11:15:51 VERBOSE[2693] logger.c: Using latest REGISTER request as basis request Jun 8 11:15:51 VERBOSE[2693] logger.c: Sending to 192.168.3.202 : 5060 (non-NAT) Jun 8 11:15:51 VERBOSE[2693] logger.c: Transmitting (no NAT) to 192.168.3.202:5060: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.3.202;branch=z9hG4bK247dbaad6C7829E4;received=192.168.3.202 From: "Kelly" ;tag=F1E47C3-7A9488E8 To: ;tag=as66c3b2c0 Call-ID: c789120f-9e190241-4f0eb71e@192.168.3.202 CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jun 8 11:15:51 DEBUG[2693] chan_sip.c: SIP REGISTER attempt failed for (null) : Bad digest user Jun 8 11:15:51 NOTICE[2693] chan_sip.c: Registration from '' failed for '192.168.3.202' - Username/auth name mismatch Jun 8 11:15:51 VERBOSE[2693] logger.c: Scheduling destruction of call 'c789120f-9e190241-4f0eb71e@192.168.3.202' in 15000 ms