Sep 18 10:43:02 DEBUG[2963] chan_sip.c: Setting NAT on RTP to 524288 Sep 18 10:43:02 DEBUG[2963] chan_sip.c: Setting NAT on VRTP to 524288 Sep 18 10:43:02 DEBUG[2963] chan_sip.c: Stopping retransmission on 'dc7062e4-842bff8a-bdd414b@192.168.1.77' of Response 1: Match Found Sep 18 10:43:02 DEBUG[2963] chan_sip.c: Setting NAT on RTP to 524288 Sep 18 10:43:02 DEBUG[2963] chan_sip.c: Setting NAT on VRTP to 524288 Sep 18 10:43:02 DEBUG[2963] chan_sip.c: Checking SIP call limits for device TPN-p0905 Sep 18 10:43:02 DEBUG[2963] chan_sip.c: build_route: Contact hop: Sep 18 10:43:02 VERBOSE[3072] logger.c: -- Executing Macro("SIP/TPN-p0905-b7b41920", "checkmessage|MAILBOX_NUMBER_REMOVED|NAME_REMOVED") in new stack Sep 18 10:43:02 VERBOSE[3072] logger.c: -- Executing BackGround("SIP/TPN-p0905-b7b41920", "silence/1") in new stack Sep 18 10:43:02 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:02 VERBOSE[3072] logger.c: -- Playing 'silence/1' (language 'en') Sep 18 10:43:02 DEBUG[2963] chan_sip.c: Stopping retransmission on 'dc7062e4-842bff8a-bdd414b@192.168.1.77' of Response 2: Match Found Sep 18 10:43:03 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:03 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:03 VERBOSE[3072] logger.c: -- Executing VoiceMailMain("SIP/TPN-p0905-b7b41920", "MAILBOX_NUMBER_REMOVED@NAME_REMOVED") in new stack Sep 18 10:43:03 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:03 VERBOSE[3072] logger.c: -- Playing 'vm-password' (language 'en') Sep 18 10:43:04 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:04 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:06 DEBUG[3072] app.c: Locked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] app.c: Unlocked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] app.c: Locked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] app.c: Unlocked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] app.c: Locked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/INBOX' Sep 18 10:43:06 DEBUG[3072] app.c: Unlocked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/INBOX' Sep 18 10:43:06 DEBUG[3072] app.c: Locked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/INBOX' Sep 18 10:43:06 DEBUG[3072] app.c: Unlocked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/INBOX' Sep 18 10:43:06 DEBUG[3072] app.c: Locked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] app.c: Unlocked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] app.c: Locked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] app.c: Unlocked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:06 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:06 VERBOSE[3072] logger.c: -- Playing 'vm-youhave' (language 'en') Sep 18 10:43:07 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:07 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:07 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:07 VERBOSE[3072] logger.c: -- Playing 'digits/1' (language 'en') Sep 18 10:43:07 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:07 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:07 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:07 VERBOSE[3072] logger.c: -- Playing 'vm-Old' (language 'en') Sep 18 10:43:08 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:08 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:08 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:08 VERBOSE[3072] logger.c: -- Playing 'vm-message' (language 'en') Sep 18 10:43:09 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:09 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:09 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:09 VERBOSE[3072] logger.c: -- Playing 'vm-onefor' (language 'en') Sep 18 10:43:10 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:10 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:10 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:10 VERBOSE[3072] logger.c: -- Playing 'vm-Old' (language 'en') Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:11 VERBOSE[3072] logger.c: -- Playing 'vm-messages' (language 'en') Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:11 VERBOSE[3072] logger.c: -- Playing 'vm-first' (language 'en') Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:11 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:11 VERBOSE[3072] logger.c: -- Playing 'vm-message' (language 'en') Sep 18 10:43:12 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:12 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:12 VERBOSE[3072] logger.c: == Parsing '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old/msg0000.txt': Sep 18 10:43:12 VERBOSE[3072] logger.c: == Parsing '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old/msg0000.txt': Found Sep 18 10:43:12 DEBUG[3072] say.c: Parsing ' (offset 0) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:12 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:12 VERBOSE[3072] logger.c: -- Playing 'vm-received' (language 'en') Sep 18 10:43:13 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:13 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:13 DEBUG[3072] say.c: Parsing (offset 13) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:13 DEBUG[3072] say.c: Parsing q (offset 14) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:13 DEBUG[3072] say.c: Parsing (offset 15) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:13 DEBUG[3072] say.c: Parsing ' (offset 16) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:13 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:13 VERBOSE[3072] logger.c: -- Playing 'digits/at' (language 'en') Sep 18 10:43:14 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:14 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:14 DEBUG[3072] say.c: Parsing (offset 27) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:14 DEBUG[3072] say.c: Parsing I (offset 28) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:14 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:14 VERBOSE[3072] logger.c: -- Playing 'digits/10' (language 'en') Sep 18 10:43:14 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:14 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:14 DEBUG[3072] say.c: Parsing M (offset 29) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:14 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:14 VERBOSE[3072] logger.c: -- Playing 'digits/20' (language 'en') Sep 18 10:43:15 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:15 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:15 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:15 VERBOSE[3072] logger.c: -- Playing 'digits/8' (language 'en') Sep 18 10:43:15 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:15 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:15 DEBUG[3072] say.c: Parsing p (offset 30) in 'vm-received' q 'digits/at' IMp Sep 18 10:43:15 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:15 VERBOSE[3072] logger.c: -- Playing 'digits/a-m' (language 'en') Sep 18 10:43:17 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:17 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:17 DEBUG[3072] app_voicemail.c: VM-Duration: duration is: 5 seconds converted to: 0 minutes Sep 18 10:43:17 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:17 VERBOSE[3072] logger.c: -- Playing '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old/msg0000' (language 'en') Sep 18 10:43:22 DEBUG[3072] channel.c: Scheduling timer at 138 sample intervals Sep 18 10:43:22 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:22 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:22 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:22 VERBOSE[3072] logger.c: -- Playing 'vm-advopts' (language 'en') Sep 18 10:43:23 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:23 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:23 VERBOSE[3072] logger.c: -- Playing 'vm-toreply' (language 'en') Sep 18 10:43:23 DEBUG[2963] chan_sip.c: Auto destroying call '1ca5f6ea28ab59cf4b5c04bb38a3136c@127.0.0.1' Sep 18 10:43:26 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:26 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:26 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:26 VERBOSE[3072] logger.c: -- Playing 'vm-tocallback' (language 'en') Sep 18 10:43:26 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:26 VERBOSE[3072] logger.c: -- Callback Requested Sep 18 10:43:26 VERBOSE[3072] logger.c: == Parsing '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old/msg0000.txt': Sep 18 10:43:26 VERBOSE[3072] logger.c: == Parsing '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old/msg0000.txt': Found Sep 18 10:43:26 VERBOSE[3072] logger.c: -- Confirm CID number '6173543948' is number to use for callback Sep 18 10:43:26 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:26 VERBOSE[3072] logger.c: -- Playing 'vm-num-i-have' (language 'en') Sep 18 10:43:28 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:28 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:28 DEBUG[3072] app_voicemail.c: VM-CID: composite caller ID received: 617354394H, context: NAME_REMOVED Sep 18 10:43:28 DEBUG[3072] app_voicemail.c: VM-CID: From an unknown number Sep 18 10:43:28 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:28 VERBOSE[3072] logger.c: -- Playing 'vm-unknown-caller' (language 'en') Sep 18 10:43:29 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:29 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:29 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:29 VERBOSE[3072] logger.c: -- Playing 'vm-tocallnum' (language 'en') Sep 18 10:43:32 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:32 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:32 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:32 VERBOSE[3072] logger.c: -- Playing 'vm-calldiffnum' (language 'en') Sep 18 10:43:33 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:33 VERBOSE[3072] logger.c: -- Destination number is CID number '617354394H' Sep 18 10:43:33 VERBOSE[3072] logger.c: -- Placing outgoing call to extension '617354394H' in context 'NAME_REMOVED' from context 'macro-checkmessage' Sep 18 10:43:33 DEBUG[3072] channel.c: Scheduling timer at 160 sample intervals Sep 18 10:43:33 VERBOSE[3072] logger.c: -- Playing 'vm-dialout' (language 'en') Sep 18 10:43:35 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:35 DEBUG[3072] channel.c: Scheduling timer at 0 sample intervals Sep 18 10:43:35 DEBUG[3072] app.c: Locked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:35 DEBUG[3072] app.c: Unlocked path '/var/spool/asterisk/voicemail/NAME_REMOVED/MAILBOX_NUMBER_REMOVED/Old' Sep 18 10:43:35 DEBUG[3072] app_voicemail.c: Executing /usr/sbin/post_vm.pl NAME_REMOVED MAILBOX_NUMBER_REMOVED 0& Sep 18 10:43:35 VERBOSE[3072] logger.c: == Channel 'SIP/TPN-p0905-b7b41920' jumping out of macro 'checkmessage' Sep 18 10:43:35 WARNING[3072] pbx.c: Channel 'SIP/TPN-p0905-b7b41920' sent into invalid extension '617354394H' in context 'NAME_REMOVED', but no invalid handler Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '"TPN-0905" ' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'MAILBOX_NUMBER_REMOVED' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '*123' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'NAME_REMOVED' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'SIP/TPN-p0905-b7b41920' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '(null)' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'VoiceMailMain' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'MAILBOX_NUMBER_REMOVED@NAME_REMOVED' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '2006-09-18 10:43:02' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '2006-09-18 10:43:02' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '2006-09-18 10:43:35' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '33' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '33' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'ANSWERED' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'DOCUMENTATION' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is 'NAME_REMOVED' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '1158590582.5' Sep 18 10:43:35 DEBUG[3072] pbx.c: Function result is '(null)' Sep 18 10:43:35 DEBUG[3072] chan_sip.c: update_call_counter(TPN-p0905) - decrement call limit counter Sep 18 10:43:35 DEBUG[2963] chan_sip.c: Stopping retransmission on 'dc7062e4-842bff8a-bdd414b@192.168.1.77' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '5cc098b22a532aff1c1d812e441c87ce@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '29840b0e0e0046f46fb30d1273ef41f6@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '5cdeecfa4ba63d2958c24b1376d74be2@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '24ab68db526b12a47e31c6fd1cd09a86@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '0c5ed23e460b7c3445f618e2640b1a57@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '1418dc936a8606cb16b9206c7e99de30@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '1c89b33e5034e40c3f52ca230d99d2d0@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '716c0b7f3e10c0e370afe6ba0e384051@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 14, historicms 14, maxms 2000 Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 14, historicms 14, maxms 2000 Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '53a7bcdf4232762c7c7c0ec9688e0ae9@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 22, historicms 22, maxms 2000 Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '04c1ed9b1bc917ba20f1508b0fe5ecea@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '0870ec4252a55c0e415b2f6f5258147f@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '1b8f149b2fa792a61a76bcad31fbbde1@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '479c2da2007a5498312717ff7c81bb1e@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 25, historicms 25, maxms 2000 Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 33, historicms 33, maxms 2000 Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '0c3c148351cf8c256587945c25e3381c@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 28, historicms 28, maxms 2000 Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '15970047169034a532243d15104e817a@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2965] chan_iax2.c: Peer lastms 49, historicms 49, maxms 2000 Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '3d5f2d81440cebfb54a63f3517a0426b@65.90.46.166' of Request 102: Match Found Sep 18 10:43:36 DEBUG[2963] chan_sip.c: Stopping retransmission on '2da901053ef658ac5efe4d40213401dd@65.90.46.166' of Request 102: Match Found Sep 18 10:43:37 DEBUG[2963] chan_sip.c: Stopping retransmission on '7f61b1ee6699565f5fcd7a1215514807@65.90.46.166' of Request 102: Match Found