un 30 00:05:01 VERBOSE[9596] logger.c: -- Executing Dial("SIP/205-9b4f", "IAX2/VoipJet/011447973212111|300|rWTt") in new stack
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-20.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable MACRO_DEPTH.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-19.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable custom.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-18.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable OUTNUM.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-17.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable DIAL_NUMBER.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-16.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-14.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable MACRO_PRIORITY.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable MACRO_CONTEXT.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable MACRO_EXTEN.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable ARG1.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-22.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-20.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-19.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-18.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-16.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-12.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable TRUNKOUTCID.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-11.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable EMERGENCYCID.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-10.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable DB_RESULT.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable USEROUTCID.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-9.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-4.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-3.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-outbound-callerid-s-1.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-13.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable DIAL_TRUNK_OPTIONS.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-12.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-11.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable ARG2.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-record-enable-s-5.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-record-enable-s-4.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-record-enable-s-1.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-10.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-21.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-12.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-11.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable REALCALLERIDNUM.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-10.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-9.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-8.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable AMPUSERCIDNAME.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-7.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable AMPUSER.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-6.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-5.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-4.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-3.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-2.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-user-callerid-s-1.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-9.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable GROUP.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-8.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-6.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-5.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable ROUTE_PASSWD.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-4.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-3.
Jun 30 00:05:01 DEBUG[9596] channel.c: Copying soft-transferable variable NODEST.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-2.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable DIAL_TRUNK.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-macro-dialout-trunk-s-1.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable ARG4.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable ARG3.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable STACK-from-internal-8807973212111-1.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable SIPCALLID.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable SIPUSERAGENT.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable SIPDOMAIN.
Jun 30 00:05:01 DEBUG[9596] channel.c: Not copying variable SIPURI.
Jun 30 00:05:01 VERBOSE[9596] logger.c: -- Called VoipJet/011447973212111
Jun 30 00:05:01 DEBUG[31944] chan_iax2.c: Checking device state for device VoipJet
Jun 30 00:05:01 DEBUG[31944] chan_iax2.c: iax2_devicestate(VoipJet): Found peer. What's device state of VoipJet? addr=-341570808, defaddr=0 maxms=0, lastms=0
Jun 30 00:05:01 DEBUG[31944] devicestate.c: Changing state for IAX2/VoipJet - state 6 (Ringing)
Jun 30 00:05:01 DEBUG[9602] app_queue.c: Device 'IAX2/VoipJet' changed to state '6' (Ringing)
Jun 30 00:05:02 VERBOSE[31956] logger.c: -- Call accepted by 8.11.164.235 (format ulaw)
Jun 30 00:05:02 VERBOSE[31956] logger.c: -- Format for call is ulaw
Jun 30 00:05:02 DEBUG[31956] channel.c: Set channel IAX2/VoipJet-2 to write format ulaw
Jun 30 00:05:02 DEBUG[31956] channel.c: Set channel IAX2/VoipJet-2 to read format ulaw
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- IAX2/VoipJet-2 is circuit-busy
Jun 30 00:05:02 DEBUG[9596] channel.c: Hanging up channel 'IAX2/VoipJet-2'
Jun 30 00:05:02 DEBUG[9596] chan_iax2.c: We're hanging up IAX2/VoipJet-2 now...
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- Hungup 'IAX2/VoipJet-2'
Jun 30 00:05:02 VERBOSE[9596] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
Jun 30 00:05:02 DEBUG[9596] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
Jun 30 00:05:02 DEBUG[9596] pbx.c: Launching 'Goto'
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- Executing Goto("SIP/205-9b4f", "s-CONGESTION|1") in new stack
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
Jun 30 00:05:02 DEBUG[9596] pbx.c: Launching 'NoOp'
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- Executing NoOp("SIP/205-9b4f", "Dial failed due to CONGESTION - failing through to other trunks") in new stack
Jun 30 00:05:02 DEBUG[31944] chan_iax2.c: Checking device state for device VoipJet
Jun 30 00:05:02 DEBUG[31944] chan_iax2.c: iax2_devicestate(VoipJet): Found peer. What's device state of VoipJet? addr=-341570808, defaddr=0 maxms=0, lastms=0
Jun 30 00:05:02 DEBUG[31944] devicestate.c: Changing state for IAX2/VoipJet - state 1 (Not in use)
Jun 30 00:05:02 DEBUG[9596] pbx.c: Launching 'Macro'
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- Executing Macro("SIP/205-9b4f", "outisbusy|") in new stack
Jun 30 00:05:02 DEBUG[9596] pbx.c: Launching 'Playback'
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- Executing Playback("SIP/205-9b4f", "all-circuits-busy-now|noanswer") in new stack
Jun 30 00:05:02 DEBUG[9596] channel.c: Set channel SIP/205-9b4f to write format slin
Jun 30 00:05:02 DEBUG[9596] rtp.c: Ooh, format changed from unknown to ulaw
Jun 30 00:05:02 DEBUG[9596] channel.c: Scheduling timer at 160 sample intervals
Jun 30 00:05:02 VERBOSE[9596] logger.c: -- Playing 'all-circuits-busy-now' (language 'en')
Jun 30 00:05:02 DEBUG[9603] app_queue.c: Device 'IAX2/VoipJet' changed to state '1' (Not in use)
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: Auto destroying call '2011cb4927131cb06095214c1925047e@127.0.0.1'
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: Auto destroying call '2b6c87f6227959957ef8cf9569e06d3f@127.0.0.1'
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: Auto destroying call '25c212ab464d0a2d1cddc6e005e97317@127.0.0.1'
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: Auto destroying call '1acf552b7d76d8397572c74b01f8b349@127.0.0.1'
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: = Found Their Call ID: 85a0399c-c236efb5@192.168.0.173 Their Tag 98618870b8cfcef1o0 Our tag: as3540ef42
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: **** Received CANCEL (14) - Command in SIP CANCEL
Jun 30 00:05:03 DEBUG[9596] channel.c: Scheduling timer at 0 sample intervals
Jun 30 00:05:03 DEBUG[9596] channel.c: Set channel SIP/205-9b4f to write format ulaw
Jun 30 00:05:03 DEBUG[9596] app_macro.c: Spawn extension (macro-outisbusy,s,1) exited non-zero on 'SIP/205-9b4f' in macro 'outisbusy'
Jun 30 00:05:03 DEBUG[9596] pbx.c: Spawn extension (macro-outisbusy,s,1) exited non-zero on 'SIP/205-9b4f'
Jun 30 00:05:03 DEBUG[9596] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jun 30 00:05:03 DEBUG[9596] 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 ('2007-06-30 00:05:01','00441827830022','00441827830022','8807973212111','from-internal', 'SIP/205-9b4f','IAX2/VoipJet-2','Playback','all-circuits-busy-now|noanswer',2,0,'FAILED',3,'','1183158301.46')
Jun 30 00:05:03 DEBUG[9596] channel.c: Hanging up channel 'SIP/205-9b4f'
Jun 30 00:05:03 DEBUG[9596] chan_sip.c: Hangup call SIP/205-9b4f, SIP callid 85a0399c-c236efb5@192.168.0.173)
Jun 30 00:05:03 DEBUG[9596] chan_sip.c: update_call_counter(205) - decrement call limit counter
Jun 30 00:05:03 DEBUG[9596] chan_sip.c: Updating call counter for incoming call
Jun 30 00:05:03 DEBUG[31944] chan_sip.c: Checking device state for peer 205
Jun 30 00:05:03 DEBUG[31944] devicestate.c: Changing state for SIP/205 - state 1 (Not in use)
Jun 30 00:05:03 DEBUG[31944] chan_sip.c: Checking device state for peer 205
Jun 30 00:05:03 DEBUG[9604] app_queue.c: Device 'SIP/205' changed to state '1' (Not in use)
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: = Found Their Call ID: 85a0399c-c236efb5@192.168.0.173 Their Tag 98618870b8cfcef1o0 Our tag: as3540ef42
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: Stopping retransmission on '85a0399c-c236efb5@192.168.0.173' of Response 102: Match Found
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: Auto destroying call '5fc97e98010c2d277fec52ab79173bf6@127.0.0.1'
Jun 30 00:05:03 DEBUG[31958] chan_sip.c: Auto destroying call '5fc97e98010c2d277fec52ab79173bf6@127.0.0.1'