[Klab*CLI> 
    -- Accepting call from '9057122221' to '6000' on channel 0/23, span 1
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:1] [1;36;40mAnswer[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:2] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mCHANNEL(musicclass)=wavfolder[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:3] [1;36;40mNoOp[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m"INCOMING CALL FROM CALLER ID: 9057122221 (BCM502221)"[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:4] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m__INCOMING_DNIS=6000[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:5] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mCDR(userfield)=6000[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:6] [1;36;40mGoto[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m10[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Goto (zap-incoming,6000,10)
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:10] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m__INCOMINGLINE=1[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:11] [1;36;40mGotoIf[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m0?12:13[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Goto (zap-incoming,6000,13)
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:13] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mCHANNEL(language)=en[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:14] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mRING_DEST=[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:15] [1;36;40mGotoIf[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m1?18[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Goto (zap-incoming,6000,18)
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:18] [1;36;40mDial[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mLocal/6000@default-local|[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Called 6000@default-local
 
[Klab*CLI> 
    -- Executing [6000@default-local:1] [1;36;40mGotoIf[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40m0?3[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:2] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mGROUP(OUTGOING)=9057122221[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:3] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mOUTBOUND_GROUP_ONCE=6000@INCOMING[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:4] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mGROUPCOUNT=0[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:5] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mGROUPCOUNT2=0[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:6] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mDB(default/wrapup/6000/lastcall)=1202405332.62[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:7] [1;36;40mMacro[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mdefault-dial|SIP/6000|6000|default|20|en|6000@default|wWkKM(all-tapi^1202405332.62)||default||Local/0@default-local|Local/s@default-aa-servicemainmenubutton1englishsubmenu[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:1] [1;36;40mNoOp[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40m"CALL TO LOCAL EXTENSION FROM 9057122221(BCM502221)"[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:2] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: LINE_NEWCALL default[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:3] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: LINE_CALLSTATE LINECALLSTATE_OFFERING[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:4] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: SET CALLERID [0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:5] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: LINE_CALLINFO LINECALLINFOSTATE_CALLERID[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:6] [1;36;40mAGI[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40m/var/www/scopserv/telephony/scripts/agi/dial.php[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Launched AGI Script /var/www/scopserv/telephony/scripts/agi/dial.php
 
[Klab*CLI> 
    -- AGI Script Executing Application: (SetMusicOnHold) Options: (default)
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
  /var/www/scopserv/telephony/scripts/agi/dial.php: Extension State for '6000' is '0'.
 
[Klab*CLI> 
    -- AGI Script Executing Application: (NoOp) Options: (STATUS:)
 
[Klab*CLI> 
    --  /var/www/scopserv/telephony/scripts/agi/dial.php: Doing the action dial with params : 6000
 
[Klab*CLI> 
  /var/www/scopserv/telephony/scripts/agi/dial.php: Dial string is SIP/6000|20|wWkKM(all-tapi^1202405332.62)|.
 
[Klab*CLI> 
    -- AGI Script Executing Application: (Dial) Options: (SIP/6000|20|wWkKM(all-tapi^1202405332.62)|)
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state Ringing for Notify User 6001 
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state Ringing for Notify User 6003 
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state Ringing for Notify User 6000 
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state Ringing for Notify User 6002 
 
[Klab*CLI> 
    -- Called 6000
 
[Klab*CLI> 
    -- SIP/6000-0a000da0 is ringing
 
[Klab*CLI> 
    -- Local/6000@default-local-7360,1 is ringing
 
[Klab*CLI> 
    -- SIP/6000-0a000da0 is ringing
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state InUse for Notify User 6001 
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state InUse for Notify User 6003 
  Extension Changed 6000[default-local] new state InUse for Notify User 6000 
  Extension Changed 6000[default-local] new state InUse for Notify User 6002 
 
[Klab*CLI> 
    -- SIP/6000-0a000da0 answered Local/6000@default-local-7360,2
 
[Klab*CLI> 
    -- Executing [s@macro-all-tapi:1] [1;36;40mUserEvent[0;37;40m("[1;35;40mSIP/6000-0a000da0[0;37;40m", "[1;35;40mTAPI|TAPIEVENT [~1202405332.62] LINE_CALLSTATE LINECALLSTATE_CONNECTED[0;37;40m") in new stack
     -- Local/6000@default-local-7360,1 stopped sounds
     -- Local/6000@default-local-7360,1 answered Zap/23-1
 
[Klab*CLI> 
  == Spawn extension (macro-default-dial, s, 6) exited non-zero on 'Local/6000@default-local-7360,2' in macro 'default-dial'
   == Spawn extension (macro-default-dial, s, 6) exited non-zero on 'Local/6000@default-local-7360,2'
     -- Executing [h@macro-default-dial:1] [1;36;40mResetCDR[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mw[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [h@macro-default-dial:2] [1;36;40mNoCDR[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40m[0;37;40m") in new stack
     -- Executing [h@macro-default-dial:3] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: LINE_CALLSTATE LINECALLSTATE_IDLE[0;37;40m") in new stack
     -- Executing [h@macro-default-dial:4] [1;36;40mSystem[0;37;40m("[1;35;40mLocal/6000@default-local-7360,2[0;37;40m", "[1;35;40m/var/www/scopserv/telephony/scripts/billing/cdr.sh 1202405332.62[0;37;40m") in new stack
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
[Feb  7 12:28:56] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '*' received on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:56] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '*' on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:56] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '*' received on SIP/6000-0a000da0, duration 100 ms
 [Feb  7 12:28:56] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '*' on SIP/6000-0a000da0
 [Feb  7 12:28:56] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2143[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end passthrough '*' on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '9' received on SIP/6000-0a000da0
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '9' on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '9' received on SIP/6000-0a000da0, duration 120 ms
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '9' on SIP/6000-0a000da0
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2143[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end passthrough '9' on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '9' received on SIP/6000-0a000da0
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '9' on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '9' received on SIP/6000-0a000da0, duration 120 ms
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '9' on SIP/6000-0a000da0
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2143[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end passthrough '9' on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '9' received on SIP/6000-0a000da0
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '9' on SIP/6000-0a000da0
 
[Klab*CLI> 
[Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '9' received on SIP/6000-0a000da0, duration 120 ms
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '9' on SIP/6000-0a000da0
 [Feb  7 12:28:57] [1;32;40mDTMF[0;37;40m[12687]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2143[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end passthrough '9' on SIP/6000-0a000da0
 
[Klab*CLI> 
  == Spawn extension (zap-incoming, 6000, 18) exited non-zero on 'Zap/23-1'
     -- Executing [h@zap-incoming:1] [1;36;40mGoto[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mall-hangup|s|1[0;37;40m") in new stack
     -- Goto (all-hangup,s,1)
     -- Executing [s@all-hangup:1] [1;36;40mGotoIf[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m0?all-faxnotify|s|1:2[0;37;40m") in new stack
     -- Goto (all-hangup,s,2)
     -- Executing [s@all-hangup:2] [1;36;40mResetCDR[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mw[0;37;40m") in new stack
  Extension Changed 6000[default-local] new state Idle for Notify User 6001 
  Extension Changed 6000[default-local] new state Idle for Notify User 6003 
  Extension Changed 6000[default-local] new state Idle for Notify User 6000 
  Extension Changed 6000[default-local] new state Idle for Notify User 6002 
 
[Klab*CLI> 
    -- Executing [s@all-hangup:3] [1;36;40mNoCDR[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m[0;37;40m") in new stack
     -- Executing [s@all-hangup:4] [1;36;40mSystem[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m/var/www/scopserv/telephony/scripts/billing/cdr.sh 1202405332.60[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Hungup 'Zap/23-1'
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
    -- Accepting call from '9057122221' to '6000' on channel 0/23, span 1
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:1] [1;36;40mAnswer[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:2] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mCHANNEL(musicclass)=wavfolder[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:3] [1;36;40mNoOp[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m"INCOMING CALL FROM CALLER ID: 9057122221 (BCM502221)"[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:4] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m__INCOMING_DNIS=6000[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:5] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mCDR(userfield)=6000[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:6] [1;36;40mGoto[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m10[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Goto (zap-incoming,6000,10)
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:10] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m__INCOMINGLINE=1[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:11] [1;36;40mGotoIf[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m0?12:13[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Goto (zap-incoming,6000,13)
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:13] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mCHANNEL(language)=en[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:14] [1;36;40mSet[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mRING_DEST=[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:15] [1;36;40mGotoIf[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m1?18[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Goto (zap-incoming,6000,18)
 
[Klab*CLI> 
    -- Executing [6000@zap-incoming:18] [1;36;40mDial[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mLocal/6000@default-local|[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Called 6000@default-local
 
[Klab*CLI> 
    -- Executing [6000@default-local:1] [1;36;40mGotoIf[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40m0?3[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:2] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mGROUP(OUTGOING)=9057122221[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:3] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mOUTBOUND_GROUP_ONCE=6000@INCOMING[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:4] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mGROUPCOUNT=0[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:5] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mGROUPCOUNT2=0[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:6] [1;36;40mSet[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mDB(default/wrapup/6000/lastcall)=1202405348.66[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [6000@default-local:7] [1;36;40mMacro[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mdefault-dial|SIP/6000|6000|default|20|en|6000@default|wWkKM(all-tapi^1202405348.66)||default||Local/0@default-local|Local/s@default-aa-servicemainmenubutton1englishsubmenu[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:1] [1;36;40mNoOp[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40m"CALL TO LOCAL EXTENSION FROM 9057122221(BCM502221)"[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:2] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: LINE_NEWCALL default[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:3] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: LINE_CALLSTATE LINECALLSTATE_OFFERING[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:4] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: SET CALLERID [0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:5] [1;36;40mUserEvent[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40mTAPI|TAPIEVENT: LINE_CALLINFO LINECALLINFOSTATE_CALLERID[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Executing [s@macro-default-dial:6] [1;36;40mAGI[0;37;40m("[1;35;40mLocal/6000@default-local-c444,2[0;37;40m", "[1;35;40m/var/www/scopserv/telephony/scripts/agi/dial.php[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Launched AGI Script /var/www/scopserv/telephony/scripts/agi/dial.php
 
[Klab*CLI> 
    -- AGI Script Executing Application: (SetMusicOnHold) Options: (default)
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
  /var/www/scopserv/telephony/scripts/agi/dial.php: Extension State for '6000' is '0'.
 
[Klab*CLI> 
    -- AGI Script Executing Application: (NoOp) Options: (STATUS:)
 
[Klab*CLI> 
    --  /var/www/scopserv/telephony/scripts/agi/dial.php: Doing the action dial with params : 6000
 
[Klab*CLI> 
  /var/www/scopserv/telephony/scripts/agi/dial.php: Dial string is SIP/6000|20|wWkKM(all-tapi^1202405348.66)|.
 
[Klab*CLI> 
    -- AGI Script Executing Application: (Dial) Options: (SIP/6000|20|wWkKM(all-tapi^1202405348.66)|)
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state Ringing for Notify User 6001 
  Extension Changed 6000[default-local] new state Ringing for Notify User 6003 
  Extension Changed 6000[default-local] new state Ringing for Notify User 6000 
  Extension Changed 6000[default-local] new state Ringing for Notify User 6002 
 
[Klab*CLI> 
    -- Called 6000
 
[Klab*CLI> 
    -- SIP/6000-09fabdf8 is ringing
 
[Klab*CLI> 
    -- Local/6000@default-local-c444,1 is ringing
 
[Klab*CLI> 
    -- SIP/6000-09fabdf8 is ringing
 
[Klab*CLI> 
    -- SIP/6000-09fabdf8 answered Local/6000@default-local-c444,2
     -- Executing [s@macro-all-tapi:1] [1;36;40mUserEvent[0;37;40m("[1;35;40mSIP/6000-09fabdf8[0;37;40m", "[1;35;40mTAPI|TAPIEVENT [~1202405348.66] LINE_CALLSTATE LINECALLSTATE_CONNECTED[0;37;40m") in new stack
 
[Klab*CLI> 
    -- Local/6000@default-local-c444,1 stopped sounds
     -- Local/6000@default-local-c444,1 answered Zap/23-1
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state InUse for Notify User 6001 
 
[Klab*CLI> 
 Extension Changed 6000[default-local] new state InUse for Notify User 6003 
  Extension Changed 6000[default-local] new state InUse for Notify User 6000 
  Extension Changed 6000[default-local] new state InUse for Notify User 6002 
 
[Klab*CLI> 
    -- AGI Script /var/www/scopserv/telephony/scripts/agi/dial.php completed, returning 0
 
[Klab*CLI> 
[Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '*' received on SIP/6000-09fabdf8
 [Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '*' on SIP/6000-09fabdf8
 
[Klab*CLI> 
[Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '*' received on SIP/6000-09fabdf8, duration 80 ms
 [Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '*' on SIP/6000-09fabdf8
 [Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2143[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end passthrough '*' on SIP/6000-09fabdf8
 
[Klab*CLI> 
[Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '2' received on SIP/6000-09fabdf8
 [Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '2' on SIP/6000-09fabdf8
 
[Klab*CLI> 
[Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '2' received on SIP/6000-09fabdf8, duration 80 ms
 [Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '2' on SIP/6000-09fabdf8
 [Feb  7 12:29:11] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2143[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end passthrough '2' on SIP/6000-09fabdf8
 
[Klab*CLI> 
  == Parsing '/etc/asterisk/manager.conf':  Found
 
[Klab*CLI> 
[Feb  7 12:29:12] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '*' received on SIP/6000-09fabdf8
 [Feb  7 12:29:12] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '*' on SIP/6000-09fabdf8
 
[Klab*CLI> 
[Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '*' received on SIP/6000-09fabdf8, duration 60 ms
 [Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '*' on SIP/6000-09fabdf8
 [Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2136[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '*' has duration 60 but want minimum 80, emulating on SIP/6000-09fabdf8
 [Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2181[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end emulation of '*' queued on SIP/6000-09fabdf8
 
[Klab*CLI> 
[Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2155[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin '2' received on SIP/6000-09fabdf8
 [Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2165[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF begin passthrough '2' on SIP/6000-09fabdf8
 
[Klab*CLI> 
[Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2080[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '2' received on SIP/6000-09fabdf8, duration 60 ms
 [Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2127[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end accepted with begin '2' on SIP/6000-09fabdf8
 [Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2136[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end '2' has duration 60 but want minimum 80, emulating on SIP/6000-09fabdf8
 
[Klab*CLI> 
[Feb  7 12:29:13] [1;32;40mDTMF[0;37;40m[12983]: [1;37;40mchannel.c[0;37;40m:[1;37;40m2181[0;37;40m [1;37;40m__ast_read[0;37;40m:  DTMF end emulation of '2' queued on SIP/6000-09fabdf8
 
[Klab*CLI> 
  == Spawn extension (zap-incoming, 6000, 18) exited non-zero on 'Zap/23-1'
     -- Executing [h@zap-incoming:1] [1;36;40mGoto[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mall-hangup|s|1[0;37;40m") in new stack
  Extension Changed 6000[default-local] new state Idle for Notify User 6001 
     -- Goto (all-hangup,s,1)
  Extension Changed 6000[default-local] new state Idle for Notify User 6003 
     -- Executing [s@all-hangup:1] [1;36;40mGotoIf[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m0?all-faxnotify|s|1:2[0;37;40m") in new stack
  Extension Changed 6000[default-local] new state Idle for Notify User 6000 
  Extension Changed 6000[default-local] new state Idle for Notify User 6002 
     -- Goto (all-hangup,s,2)
     -- Executing [s@all-hangup:2] [1;36;40mResetCDR[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40mw[0;37;40m") in new stack
     -- Executing [s@all-hangup:3] [1;36;40mNoCDR[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m[0;37;40m") in new stack
     -- Executing [s@all-hangup:4] [1;36;40mSystem[0;37;40m("[1;35;40mZap/23-1[0;37;40m", "[1;35;40m/var/www/scopserv/telephony/scripts/billing/cdr.sh 1202405348.64[0;37;40m") in new stack
 
[Klab*CLI> 
[Feb  7 12:29:14] [1;33;40mNOTICE[0;37;40m[25121]: [1;37;40mchan_iax2.c[0;37;40m:[1;37;40m6021[0;37;40m [1;37;40mupdate_registry[0;37;40m:  Restricting registration for peer 'iaxmodem0' to 60 seconds (requested 300)
 
[Klab*CLI> 
    -- Hungup 'Zap/23-1'