[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- Executing [852040000@from-trunk:1] Macro("SIP/trunk1-00000006", "users|fix|test") in new stack
[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:1] AGI("SIP/trunk1-00000006", "cid.php|fix|52040449") in new stack
[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/cid.php
[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- AGI Script cid.php completed, returning 0
[Nov 24 09:23:55] DEBUG[25586] app_macro.c: Executed application: AGI
[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:2] Set("SIP/trunk1-00000006", "TIMEOUT(absolute)=7200") in new stack
[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- Channel will hangup at 2009-11-24 09:23:55 UTC.
[Nov 24 09:23:55] DEBUG[25586] app_macro.c: Executed application: Set
[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:3] Dial("SIP/trunk1-00000006", "SIP/test|90") in new stack
[Nov 24 09:23:55] VERBOSE[25586] logger.c:     -- Called test
[Nov 24 09:24:27] NOTICE[25556] chan_sip.c: Auto-congesting SIP/test-00000007
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- SIP/test-00000007 is circuit-busy
[Nov 24 09:24:27] VERBOSE[25586] logger.c:   == Everyone is busy/congested at this time (1:0/1/0)
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Dial
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:4] NoOp("SIP/trunk1-00000006", "HANGUPCAUSE is 0") in new stack
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: NoOp
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:5] Set("SIP/trunk1-00000006", "CDR(HANGUPCAUSE)=0") in new stack
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Set
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:6] Macro("SIP/trunk1-00000006", "dial-result|0") in new stack
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-dial-result:1] GotoIf("SIP/trunk1-00000006", "0?4:2") in new stack
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Goto (macro-dial-result,s,2)
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: GotoIf
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-dial-result:2] Set("SIP/trunk1-00000006", "HCMAP=0") in new stack
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Set
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-dial-result:3] Goto("SIP/trunk1-00000006", "rc-0|1") in new stack
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Goto (macro-dial-result,rc-0,1)
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Goto
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [rc-0@macro-dial-result:1] Set("SIP/trunk1-00000006", "HCMAP=NOTDEFINED") in new stack
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Set
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Macro
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:7] Set("SIP/trunk1-00000006", "CDR(HCMAP)=NOTDEFINED") in new stack
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Set
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s@macro-users:8] Goto("SIP/trunk1-00000006", "s-CONGESTION|1") in new stack
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Goto (macro-users,s-CONGESTION,1)
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: Goto
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s-CONGESTION@macro-users:1] NoOp("SIP/trunk1-00000006", "DIALSTATUS: CONGESTION!") in new stack
[Nov 24 09:24:27] DEBUG[25586] app_macro.c: Executed application: NoOp
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [s-CONGESTION@macro-users:2] Congestion("SIP/trunk1-00000006", "") in new stack
[Nov 24 09:24:27] VERBOSE[25586] logger.c:   == Spawn extension (macro-users, s-CONGESTION, 2) exited non-zero on 'SIP/trunk1-00000006' in macro 'users'
[Nov 24 09:24:27] VERBOSE[25586] logger.c:   == Spawn extension (from-trunk, 852040000, 1) exited non-zero on 'SIP/trunk1-00000006'
[Nov 24 09:24:27] VERBOSE[25586] logger.c:     -- Executing [h@macro-users:1] Hangup("SIP/trunk1-00000006", "") in new stack
[Nov 24 09:24:27] VERBOSE[25586] logger.c:   == Spawn extension (macro-users, h, 1) exited non-zero on 'SIP/trunk1-00000006'