rendered paste bodyJun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-outbound-callerid,s,15)
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '"gabriel" <1>'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "CallerID set to "gabriel" <1>") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "1?nomax") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-dialout-trunk,s,12)
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing AGI("SIP/1-3e7a", "fixlocalprefix") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "OUTNUM=19789870175") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is 'IAX2/voipjet'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "custom=IAX2/voipjet") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '0'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "0?customtrunk") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Not taking any branch
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Dial("SIP/1-3e7a", "IAX2/voipjet/19789870175|120|r") in new stack
Jun 16 11:33:07 VERBOSE[31723] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00004ms SCall: 00002 DCall: 00000 [64.34.45.100:4569]
Jun 16 11:33:07 VERBOSE[31723] logger.c: VERSION : 2
Jun 16 11:33:07 VERBOSE[31723] logger.c: CALLED NUMBER : 19789870175
Jun 16 11:33:07 VERBOSE[31723] logger.c: CODEC_PREFS : (ulaw|alaw|gsm)
Jun 16 11:33:07 VERBOSE[31723] logger.c: CALLING NUMBER : 1
Jun 16 11:33:07 VERBOSE[31723] logger.c: CALLING PRESNTN : 0
Jun 16 11:33:07 VERBOSE[31723] logger.c: CALLING TYPEOFN : 0
Jun 16 11:33:07 VERBOSE[31723] logger.c: CALLING TRANSIT : 0
Jun 16 11:33:07 VERBOSE[31723] logger.c: CALLING NAME : gabriel
Jun 16 11:33:07 VERBOSE[31723] logger.c: LANGUAGE : en
Jun 16 11:33:07 VERBOSE[31723] logger.c: USERNAME : 9560
Jun 16 11:33:07 VERBOSE[31723] logger.c: FORMAT : 4
Jun 16 11:33:07 VERBOSE[31723] logger.c: CAPABILITY : 63502
Jun 16 11:33:07 VERBOSE[31723] logger.c: ADSICPE : 2
Jun 16 11:33:07 VERBOSE[31723] logger.c: DATE TIME : 2006-06-16 11:33:06
Jun 16 11:33:07 VERBOSE[31723] logger.c:
Jun 16 11:33:07 DEBUG[31714] channel.c: Avoiding initial deadlock for 'IAX2/voipjet-2'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Called voipjet/19789870175
Jun 16 11:33:07 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00004ms SCall: 00394 DCall: 00002 [64.34.45.100:4569]
Jun 16 11:33:07 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00004ms SCall: 00394 DCall: 00002 [64.34.45.100:4569]
Jun 16 11:33:07 VERBOSE[31723] logger.c: AUTHMETHODS : 3
Jun 16 11:33:07 VERBOSE[31723] logger.c: CHALLENGE : 175834894
Jun 16 11:33:07 VERBOSE[31723] logger.c: USERNAME : 9560
Jun 16 11:33:07 VERBOSE[31723] logger.c:
Jun 16 11:33:07 VERBOSE[31723] logger.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00022ms SCall: 00002 DCall: 00394 [64.34.45.100:4569]
Jun 16 11:33:07 VERBOSE[31723] logger.c: MD5 RESULT : 0e81b8a52ec8103d752c95daa567b24b
Jun 16 11:33:07 VERBOSE[31723] logger.c:
Jun 16 11:33:07 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00022ms SCall: 00394 DCall: 00002 [64.34.45.100:4569]
Jun 16 11:33:07 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00018ms SCall: 00394 DCall: 00002 [64.34.45.100:4569]
Jun 16 11:33:07 VERBOSE[31723] logger.c: FORMAT : 4
Jun 16 11:33:07 VERBOSE[31723] logger.c:
Jun 16 11:33:07 VERBOSE[31723] logger.c: -- Call accepted by 64.34.45.100 (format ulaw)
Jun 16 11:33:07 VERBOSE[31723] logger.c: -- Format for call is ulaw
Jun 16 11:33:07 VERBOSE[31723] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00018ms SCall: 00002 DCall: 00394 [64.34.45.100:4569]
Jun 16 11:33:07 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: HANGUP
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00090ms SCall: 00394 DCall: 00002 [64.34.45.100:4569]
Jun 16 11:33:07 DEBUG[31723] chan_iax2.c: Immediately destroying 2, having received hangup
Jun 16 11:33:07 VERBOSE[31723] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK
Jun 16 11:33:07 VERBOSE[31723] logger.c: Timestamp: 00090ms SCall: 00002 DCall: 00394 [64.34.45.100:4569]
Jun 16 11:33:07 DEBUG[31885] chan_iax2.c: We're hanging up IAX2/voipjet-2 now...
Jun 16 11:33:07 DEBUG[31885] chan_iax2.c: Really destroying IAX2/voipjet-2 now...
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Hungup 'IAX2/voipjet-2'
Jun 16 11:33:07 VERBOSE[31885] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
Jun 16 11:33:07 DEBUG[31885] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Goto("SIP/1-3e7a", "s-CHANUNAVAIL|1") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "Dial failed due to CHANUNAVAIL") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Macro("SIP/1-3e7a", "dialout-trunk|2|19789870175||") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "DIAL_TRUNK=2") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "DIAL_NUMBER=19789870175") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "ROUTE_PASSWD=") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "1?noauth") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-dialout-trunk,s,6)
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "GROUP()=OUT_2") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Macro("SIP/1-3e7a", "user-callerid") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '0'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "0?report") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Not taking any branch
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '0'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "0?start") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Not taking any branch
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "REALCALLERIDNUM=1") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "REALCALLERIDNUM is 1") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "AMPUSER=1") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is 'gabriel'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "AMPUSERCIDNAME=gabriel") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '0'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "0?report") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Not taking any branch
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "CALLERID(all)=gabriel <1>") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '"gabriel" <1>'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "Using CallerID "gabriel" <1>") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Macro("SIP/1-3e7a", "record-enable|1|OUT") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '0'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "0 > 0?2:4") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-record-enable,s,4)
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing AGI("SIP/1-3e7a", "recordingcheck|20060616-113307|1150471987.6") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jun 16 11:33:07 VERBOSE[31885] logger.c: recordingcheck|20060616-113307|1150471987.6: Outbound recording not enabled
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- AGI Script recordingcheck completed, returning 0
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "No recording needed") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Macro("SIP/1-3e7a", "outbound-callerid|2") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '0'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "0?start") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Not taking any branch
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "REALCALLERIDNUM=1") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "REALCALLERIDNUM is 1") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is ''
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "USEROUTCID=") in new stack
Jun 16 11:33:07 DEBUG[31885] db.c: Unable to find key '1/emergency_cid' in family 'DEVICE'
Jun 16 11:33:07 DEBUG[31885] func_db.c: DB: DEVICE/1/emergency_cid not found in database.
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is ''
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "EMERGENCYCID=") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "TRUNKOUTCID=") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "1?trunkcid") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-outbound-callerid,s,11)
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "1?usercid") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-outbound-callerid,s,13)
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "1?report") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-outbound-callerid,s,15)
Jun 16 11:33:07 DEBUG[31885] pbx.c: Function result is '"gabriel" <1>'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "CallerID set to "gabriel" <1>") in new stack
Jun 16 11:33:07 DEBUG[31885] pbx.c: Expression result is '1'
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "1?nomax") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Goto (macro-dialout-trunk,s,12)
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Executing AGI("SIP/1-3e7a", "fixlocalprefix") in new stack
Jun 16 11:33:07 VERBOSE[31885] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "OUTNUM=19789870175") in new stack
Jun 16 11:33:08 DEBUG[31885] pbx.c: Function result is 'IAX2/voipjet'
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing Set("SIP/1-3e7a", "custom=IAX2/voipjet") in new stack
Jun 16 11:33:08 DEBUG[31885] pbx.c: Expression result is '0'
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing GotoIf("SIP/1-3e7a", "0?customtrunk") in new stack
Jun 16 11:33:08 DEBUG[31885] pbx.c: Not taking any branch
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing Dial("SIP/1-3e7a", "IAX2/voipjet/19789870175|120|r") in new stack
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Called voipjet/19789870175
Jun 16 11:33:08 VERBOSE[31723] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00019ms SCall: 00003 DCall: 00000 [64.34.45.100:4569]
Jun 16 11:33:08 VERBOSE[31723] logger.c: VERSION : 2
Jun 16 11:33:08 VERBOSE[31723] logger.c: CALLED NUMBER : 19789870175
Jun 16 11:33:08 VERBOSE[31723] logger.c: CODEC_PREFS : (ulaw|alaw|gsm)
Jun 16 11:33:08 VERBOSE[31723] logger.c: CALLING NUMBER : 1
Jun 16 11:33:08 VERBOSE[31723] logger.c: CALLING PRESNTN : 0
Jun 16 11:33:08 VERBOSE[31723] logger.c: CALLING TYPEOFN : 0
Jun 16 11:33:08 VERBOSE[31723] logger.c: CALLING TRANSIT : 0
Jun 16 11:33:08 VERBOSE[31723] logger.c: CALLING NAME : gabriel
Jun 16 11:33:08 VERBOSE[31723] logger.c: LANGUAGE : en
Jun 16 11:33:08 VERBOSE[31723] logger.c: USERNAME : 9560
Jun 16 11:33:08 VERBOSE[31723] logger.c: FORMAT : 4
Jun 16 11:33:08 VERBOSE[31723] logger.c: CAPABILITY : 63502
Jun 16 11:33:08 VERBOSE[31723] logger.c: ADSICPE : 2
Jun 16 11:33:08 VERBOSE[31723] logger.c: DATE TIME : 2006-06-16 11:33:08
Jun 16 11:33:08 VERBOSE[31723] logger.c:
Jun 16 11:33:08 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00019ms SCall: 00211 DCall: 00003 [64.34.45.100:4569]
Jun 16 11:33:08 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00004ms SCall: 00211 DCall: 00003 [64.34.45.100:4569]
Jun 16 11:33:08 VERBOSE[31723] logger.c: AUTHMETHODS : 3
Jun 16 11:33:08 VERBOSE[31723] logger.c: CHALLENGE : 107996589
Jun 16 11:33:08 VERBOSE[31723] logger.c: USERNAME : 9560
Jun 16 11:33:08 VERBOSE[31723] logger.c:
Jun 16 11:33:08 VERBOSE[31723] logger.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00042ms SCall: 00003 DCall: 00211 [64.34.45.100:4569]
Jun 16 11:33:08 VERBOSE[31723] logger.c: MD5 RESULT : 979d0f8cc56cb8311d07654219b32c1b
Jun 16 11:33:08 VERBOSE[31723] logger.c:
Jun 16 11:33:08 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00042ms SCall: 00211 DCall: 00003 [64.34.45.100:4569]
Jun 16 11:33:08 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00018ms SCall: 00211 DCall: 00003 [64.34.45.100:4569]
Jun 16 11:33:08 VERBOSE[31723] logger.c: FORMAT : 4
Jun 16 11:33:08 VERBOSE[31723] logger.c:
Jun 16 11:33:08 VERBOSE[31723] logger.c: -- Call accepted by 64.34.45.100 (format ulaw)
Jun 16 11:33:08 VERBOSE[31723] logger.c: -- Format for call is ulaw
Jun 16 11:33:08 VERBOSE[31723] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00018ms SCall: 00003 DCall: 00211 [64.34.45.100:4569]
Jun 16 11:33:08 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: HANGUP
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00106ms SCall: 00211 DCall: 00003 [64.34.45.100:4569]
Jun 16 11:33:08 DEBUG[31723] chan_iax2.c: Immediately destroying 3, having received hangup
Jun 16 11:33:08 VERBOSE[31723] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK
Jun 16 11:33:08 VERBOSE[31723] logger.c: Timestamp: 00106ms SCall: 00003 DCall: 00211 [64.34.45.100:4569]
Jun 16 11:33:08 DEBUG[31885] chan_iax2.c: We're hanging up IAX2/voipjet-3 now...
Jun 16 11:33:08 DEBUG[31885] chan_iax2.c: Really destroying IAX2/voipjet-3 now...
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Hungup 'IAX2/voipjet-3'
Jun 16 11:33:08 VERBOSE[31885] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
Jun 16 11:33:08 DEBUG[31885] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing Goto("SIP/1-3e7a", "s-CHANUNAVAIL|1") in new stack
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing NoOp("SIP/1-3e7a", "Dial failed due to CHANUNAVAIL") in new stack
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing Macro("SIP/1-3e7a", "outisbusy|") in new stack
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Executing Playback("SIP/1-3e7a", "all-circuits-busy-now") in new stack
Jun 16 11:33:08 VERBOSE[31885] logger.c: -- Playing 'all-circuits-busy-now' (language 'en')
Jun 16 11:33:08 DEBUG[31721] chan_sip.c: Stopping retransmission on '76b3668f-400cea5a@10.19.25.16' of Response 102: Match Found
Jun 16 11:33:09 VERBOSE[31885] logger.c: -- Executing Playback("SIP/1-3e7a", "pls-try-call-later") in new stack
Jun 16 11:33:09 VERBOSE[31885] logger.c: -- Playing 'pls-try-call-later' (language 'en')
Jun 16 11:33:12 VERBOSE[31885] logger.c: -- Executing Macro("SIP/1-3e7a", "hangupcall") in new stack
Jun 16 11:33:12 VERBOSE[31885] logger.c: -- Executing ResetCDR("SIP/1-3e7a", "w") in new stack
Jun 16 11:33:12 DEBUG[31885] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jun 16 11:33:12 DEBUG[31885] 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-06-16 11:33:07','\"gabriel\" <1>','1','19789870175','from-internal', 'SIP/1-3e7a','IAX2/voipjet-3','ResetCDR','w',5,4,'ANSWERED',3,'','1150471987.6')
Jun 16 11:33:12 VERBOSE[31885] logger.c: -- Executing NoCDR("SIP/1-3e7a", "") in new stack
Jun 16 11:33:12 WARNING[31885] cdr.c: CDR on channel 'SIP/1-3e7a' not posted
Jun 16 11:33:12 WARNING[31885] cdr.c: CDR on channel 'SIP/1-3e7a' lacks end
Jun 16 11:33:12 VERBOSE[31885] logger.c: -- Executing Wait("SIP/1-3e7a", "5") in new stack
Jun 16 11:33:13 VERBOSE[31885] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1-3e7a' in macro 'hangupcall'
Jun 16 11:33:13 VERBOSE[31885] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1-3e7a' in macro 'outisbusy'
Jun 16 11:33:13 VERBOSE[31885] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1-3e7a'
Jun 16 11:33:13 DEBUG[31885] chan_sip.c: update_call_counter(1) - decrement call limit counter
Jun 16 11:33:19 VERBOSE[31723] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ
Jun 16 11:33:19 VERBOSE[31723] logger.c: Timestamp: 00018ms SCall: 00004 DCall: 00000 [70.84.157.148:4569]
Jun 16 11:33:19 VERBOSE[31723] logger.c: USERNAME : missnebun
Jun 16 11:33:19 VERBOSE[31723] logger.c: REFRESH : 60
Jun 16 11:33:19 VERBOSE[31723] logger.c:
Jun 16 11:33:19 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH
Jun 16 11:33:19 VERBOSE[31723] logger.c: Timestamp: 00003ms SCall: 00691 DCall: 00004 [70.84.157.148:4569]
Jun 16 11:33:19 VERBOSE[31723] logger.c: AUTHMETHODS : 3
Jun 16 11:33:19 VERBOSE[31723] logger.c: CHALLENGE : 143040176
Jun 16 11:33:19 VERBOSE[31723] logger.c: USERNAME : missnebun
Jun 16 11:33:19 VERBOSE[31723] logger.c:
Jun 16 11:33:19 VERBOSE[31723] logger.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ
Jun 16 11:33:19 VERBOSE[31723] logger.c: Timestamp: 00100ms SCall: 00004 DCall: 00691 [70.84.157.148:4569]
Jun 16 11:33:19 VERBOSE[31723] logger.c: USERNAME : missnebun
Jun 16 11:33:19 VERBOSE[31723] logger.c: REFRESH : 60
Jun 16 11:33:19 VERBOSE[31723] logger.c: MD5 RESULT : 4d11968a457c6a77638ae65f6fa01ef6
Jun 16 11:33:19 VERBOSE[31723] logger.c:
Jun 16 11:33:19 VERBOSE[31723] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK
Jun 16 11:33:19 VERBOSE[31723] logger.c: Timestamp: 00063ms SCall: 00691 DCall: 00004 [70.84.157.148:4569]
Jun 16 11:33:19 VERBOSE[31723] logger.c: USERNAME : missnebun
Jun 16 11:33:19 VERBOSE[31723] logger.c: DATE TIME : 2006-06-16 05:44:00
Jun 16 11:33:19 VERBOSE[31723] logger.c: REFRESH : 60
Jun 16 11:33:19 VERBOSE[31723] logger.c: APPARENT ADDRES : IPV4 69.86.149.231:4569
Jun 16 11:33:19 VERBOSE[31723] logger.c:
Jun 16 11:33:19 VERBOSE[31723] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Jun 16 11:33:19 VERBOSE[31723] logger.c: Timestamp: 00063ms SCall: 00004 DCall: 00691 [70.84.157.148:4569]