Jul 13 07:57:45 DEBUG[3667] chan_sip.c: Outgoing Call for 91989*******
Jul 13 07:57:45 DEBUG[3667] chan_sip.c: Updating call counter for outgoing call
Jul 13 07:57:45 VERBOSE[3667] logger.c:     -- Called 123-Net-Line1/91989*******
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '4063510a18b0850529cd5ec637e45959@10.1.2.2' Request 102: Found
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: SIP response 100 to standard invite
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: Acked pending invite 102
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: Stopping retransmission on '4063510a18b0850529cd5ec637e45959@10.1.2.2' of Request 102: Match Found
Jul 13 07:57:45 VERBOSE[2764] logger.c:     -- Got SIP response 604 "Does not exist anywhere" back from 66.103.238.68
Jul 13 07:57:45 DEBUG[3667] channel.c: Hanging up channel 'SIP/123-Net-Line1-719e'
Jul 13 07:57:45 DEBUG[3667] chan_sip.c: Hangup call SIP/123-Net-Line1-719e, SIP callid 4063510a18b0850529cd5ec637e45959@10.1.2.2)
Jul 13 07:57:45 DEBUG[3667] chan_sip.c: update_call_counter(91989*******) - decrement call limit counter
Jul 13 07:57:45 DEBUG[3667] chan_sip.c: Updating call counter for outgoing call
Jul 13 07:57:45 VERBOSE[3667] logger.c:   == No one is available to answer at this time (1:0/0/0)
Jul 13 07:57:45 DEBUG[3667] app_dial.c: Exiting with DIALSTATUS=NOANSWER.
Jul 13 07:57:45 DEBUG[3667] pbx.c: Launching 'Goto'
Jul 13 07:57:45 VERBOSE[3667] logger.c:     -- Executing Goto("SIP/300-4af6", "s-NOANSWER|1") in new stack
Jul 13 07:57:45 VERBOSE[3667] logger.c:     -- Goto (macro-dialout-trunk,s-NOANSWER,1)
Jul 13 07:57:45 DEBUG[3667] pbx.c: Launching 'NoOp'
Jul 13 07:57:45 VERBOSE[3667] logger.c:     -- Executing NoOp("SIP/300-4af6", "Dial failed due to NOANSWER") in new stack
Jul 13 07:57:45 DEBUG[3667] pbx.c: Launching 'Macro'
Jul 13 07:57:45 VERBOSE[3667] logger.c:     -- Executing Macro("SIP/300-4af6", "outisbusy|") in new stack
Jul 13 07:57:45 DEBUG[3667] pbx.c: Launching 'Playback'
Jul 13 07:57:45 VERBOSE[3667] logger.c:     -- Executing Playback("SIP/300-4af6", "all-circuits-busy-now") in new stack
Jul 13 07:57:45 DEBUG[3667] chan_sip.c: sip_answer(SIP/300-4af6)
Jul 13 07:57:45 DEBUG[2752] chan_sip.c: Checking device state for peer 123-Net-Line1
Jul 13 07:57:45 DEBUG[2752] devicestate.c: Changing state for SIP/123-Net-Line1 - state 1 (Not in use)
Jul 13 07:57:45 DEBUG[2752] chan_sip.c: Checking device state for peer 300
Jul 13 07:57:45 DEBUG[2752] devicestate.c: Changing state for SIP/300 - state 2 (In use)
Jul 13 07:57:45 DEBUG[2752] chan_sip.c: Checking device state for peer 300
Jul 13 07:57:45 DEBUG[3667] channel.c: Set channel SIP/300-4af6 to write format gsm
Jul 13 07:57:45 DEBUG[3667] rtp.c: Ooh, format changed from unknown to alaw
Jul 13 07:57:45 DEBUG[3667] channel.c: Scheduling timer at 160 sample intervals
Jul 13 07:57:45 VERBOSE[3667] logger.c:     -- Playing 'all-circuits-busy-now' (language 'en')
Jul 13 07:57:45 DEBUG[3671] app_queue.c: Device 'SIP/123-Net-Line1' changed to state '1' (Not in use)
Jul 13 07:57:45 DEBUG[3672] app_queue.c: Device 'SIP/300' changed to state '2' (In use)
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: Stopping retransmission on '143B876C-D761-4000-928F-4C682A5F1E42@10.1.2.101' of Response 20946: Match Found
Jul 13 07:57:45 DEBUG[3667] rtp.c: Got RTCP report of 84 bytes
Jul 13 07:57:45 DEBUG[3667] rtp.c: Got RTCP report of 114 bytes
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 4063510a18b0850529cd5ec637e45959@10.1.2.2 - SIP/2.0 (No RTP)
Jul 13 07:57:45 DEBUG[2764] chan_sip.c: That's odd...  Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Jul 13 07:57:47 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 4063510a18b0850529cd5ec637e45959@10.1.2.2 - SIP/2.0 (No RTP)
Jul 13 07:57:47 DEBUG[2764] chan_sip.c: That's odd...  Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Jul 13 07:57:47 DEBUG[3667] channel.c: Scheduling timer at 0 sample intervals
Jul 13 07:57:47 DEBUG[3667] channel.c: Scheduling timer at 0 sample intervals
Jul 13 07:57:47 DEBUG[3667] channel.c: Set channel SIP/300-4af6 to write format alaw
Jul 13 07:57:47 DEBUG[3667] pbx.c: Launching 'Playback'
Jul 13 07:57:47 VERBOSE[3667] logger.c:     -- Executing Playback("SIP/300-4af6", "pls-try-call-later") in new stack
Jul 13 07:57:47 DEBUG[3667] channel.c: Set channel SIP/300-4af6 to write format gsm
Jul 13 07:57:47 DEBUG[3667] channel.c: Scheduling timer at 160 sample intervals
Jul 13 07:57:47 VERBOSE[3667] logger.c:     -- Playing 'pls-try-call-later' (language 'en')
Jul 13 07:57:49 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 4063510a18b0850529cd5ec637e45959@10.1.2.2 - SIP/2.0 (No RTP)
Jul 13 07:57:49 DEBUG[2764] chan_sip.c: That's odd...  Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Jul 13 07:57:49 DEBUG[3667] channel.c: Scheduling timer at 0 sample intervals
Jul 13 07:57:49 DEBUG[3667] channel.c: Scheduling timer at 0 sample intervals
Jul 13 07:57:49 DEBUG[3667] channel.c: Set channel SIP/300-4af6 to write format alaw
Jul 13 07:57:49 DEBUG[3667] pbx.c: Launching 'Macro'
Jul 13 07:57:49 VERBOSE[3667] logger.c:     -- Executing Macro("SIP/300-4af6", "hangupcall") in new stack
Jul 13 07:57:49 DEBUG[3667] pbx.c: Launching 'ResetCDR'
Jul 13 07:57:49 VERBOSE[3667] logger.c:     -- Executing ResetCDR("SIP/300-4af6", "w") in new stack
Jul 13 07:57:49 DEBUG[3667] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jul 13 07:57:49 DEBUG[3667] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2006-07-13 07:57:44','\"Andrew Lemstrom\" <300>','300','91989*******','from-internal', 'SIP/300-4af6','SIP/123-Net-Line1-719e','ResetCDR','w',5,4,'ANSWERED',3,'','1152791864.32')
Jul 13 07:57:49 DEBUG[3667] pbx.c: Launching 'NoCDR'
Jul 13 07:57:49 VERBOSE[3667] logger.c:     -- Executing NoCDR("SIP/300-4af6", "") in new stack
Jul 13 07:57:49 WARNING[3667] cdr.c: CDR on channel 'SIP/300-4af6' not posted
Jul 13 07:57:49 WARNING[3667] cdr.c: CDR on channel 'SIP/300-4af6' lacks end
Jul 13 07:57:49 DEBUG[3667] pbx.c: Launching 'Wait'
Jul 13 07:57:49 VERBOSE[3667] logger.c:     -- Executing Wait("SIP/300-4af6", "5") in new stack
Jul 13 07:57:50 DEBUG[3667] rtp.c: Got RTCP report of 84 bytes
Jul 13 07:57:52 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 43423a4e-d7e08d84@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:57:52 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
Jul 13 07:57:53 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 4063510a18b0850529cd5ec637e45959@10.1.2.2 - SIP/2.0 (No RTP)
Jul 13 07:57:53 DEBUG[2764] chan_sip.c: That's odd...  Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Jul 13 07:57:54 DEBUG[3667] pbx.c: Launching 'Hangup'
Jul 13 07:57:54 VERBOSE[3667] logger.c:     -- Executing Hangup("SIP/300-4af6", "") in new stack
Jul 13 07:57:54 DEBUG[3667] app_macro.c: Spawn extension (macro-hangupcall,s,4) exited non-zero on 'SIP/300-4af6' in macro 'hangupcall'
Jul 13 07:57:54 DEBUG[3667] app_macro.c: Spawn extension (macro-hangupcall,s,4) exited non-zero on 'SIP/300-4af6' in macro 'outisbusy'
Jul 13 07:57:54 DEBUG[3667] pbx.c: Spawn extension (macro-hangupcall,s,4) exited non-zero on 'SIP/300-4af6'
Jul 13 07:57:54 DEBUG[3667] channel.c: Hanging up channel 'SIP/300-4af6'
Jul 13 07:57:54 DEBUG[3667] chan_sip.c: Hangup call SIP/300-4af6, SIP callid 143B876C-D761-4000-928F-4C682A5F1E42@10.1.2.101)
Jul 13 07:57:54 DEBUG[3667] chan_sip.c: update_call_counter(300) - decrement call limit counter
Jul 13 07:57:54 DEBUG[3667] chan_sip.c: Updating call counter for incoming call
Jul 13 07:57:54 DEBUG[2752] chan_sip.c: Checking device state for peer 300
Jul 13 07:57:54 DEBUG[2752] devicestate.c: Changing state for SIP/300 - state 1 (Not in use)
Jul 13 07:57:54 DEBUG[2752] chan_sip.c: Checking device state for peer 300
Jul 13 07:57:54 DEBUG[3673] app_queue.c: Device 'SIP/300' changed to state '1' (Not in use)
Jul 13 07:57:54 DEBUG[2764] chan_sip.c: Stopping retransmission on '143B876C-D761-4000-928F-4C682A5F1E42@10.1.2.101' of Request 102: Match Found
Jul 13 07:57:56 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 845063c6-defb26c@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:57:56 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
Jul 13 07:58:01 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 4063510a18b0850529cd5ec637e45959@10.1.2.2 - SIP/2.0 (No RTP)
Jul 13 07:58:01 DEBUG[2764] chan_sip.c: That's odd...  Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Jul 13 07:58:07 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 43423a4e-d7e08d84@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:58:07 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
Jul 13 07:58:11 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 845063c6-defb26c@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:58:11 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
Jul 13 07:58:17 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 4063510a18b0850529cd5ec637e45959@10.1.2.2 - SIP/2.0 (No RTP)
Jul 13 07:58:17 DEBUG[2764] chan_sip.c: That's odd...  Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Jul 13 07:58:22 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 43423a4e-d7e08d84@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:58:22 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
Jul 13 07:58:26 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 845063c6-defb26c@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:58:26 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
Jul 13 07:58:37 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 43423a4e-d7e08d84@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:58:37 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY
Jul 13 07:58:41 DEBUG[2764] chan_sip.c: Allocating new SIP dialog for 845063c6-defb26c@10.1.2.3 - NOTIFY (No RTP)
Jul 13 07:58:41 DEBUG[2764] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY