[2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:1] Set("SIP/5555551234-00000034", "CDR(tenant)=commzilla") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:2] Set("SIP/5555551234-00000034", "__INCOMING_DNIS=5555551234") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:3] Set("SIP/5555551234-00000034", "LINE_TENANT=commzilla") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:4] Set("SIP/5555551234-00000034", "LINE_PROGRESS=") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:5] Set("SIP/5555551234-00000034", "LINE_SIPHEADER=") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:6] Set("SIP/5555551234-00000034", "LINE_RDNIS=") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:7] Set("SIP/5555551234-00000034", "LINE_SPAMFILTER=") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:8] Set("SIP/5555551234-00000034", "LINE_CHANSPY=") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:9] Set("SIP/5555551234-00000034", "LINE_PICKUPMARK=") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:10] Set("SIP/5555551234-00000034", "LINE_LANGUAGE=en") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:11] Set("SIP/5555551234-00000034", "LINE_CLID_NUM=4164148967") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:12] Set("SIP/5555551234-00000034", "LINE_CLID_NAME=UNKNOWN") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:13] Set("SIP/5555551234-00000034", "LINE_CLID_NUM_DEF=94164148967") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:14] Set("SIP/5555551234-00000034", "LINE_FAXRING=1") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:15] Set("SIP/5555551234-00000034", "LINE_FAXDETECT=4") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:16] Set("SIP/5555551234-00000034", "LINE_MOH=default") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:17] Set("SIP/5555551234-00000034", "LINE_CLID_PRES=allowed_not_screened") in new stack [2012-09-10 15:05:51] -- Executing [5555551234@commzilla-5555551234-incoming:18] Gosub("SIP/5555551234-00000034", "all-incoming-check,s,1") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:1] NoOp("SIP/5555551234-00000034", ""INCOMING CALL FROM CALLER ID: 4164148967 (DAVID BRILLERT)"") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:2] GotoIf("SIP/5555551234-00000034", "1?7") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,7) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:7] GotoIf("SIP/5555551234-00000034", "1?10") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,10) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:10] GotoIf("SIP/5555551234-00000034", "1?12") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,12) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:12] GotoIf("SIP/5555551234-00000034", "1?14") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,14) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:14] GotoIf("SIP/5555551234-00000034", "1?16") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,16) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:16] GotoIf("SIP/5555551234-00000034", "0?18") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:17] Set("SIP/5555551234-00000034", "CHANNEL(musicclass)=default") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:18] Set("SIP/5555551234-00000034", "CALLERID(dnid)=5555551234") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:19] Set("SIP/5555551234-00000034", "CDR(userfield)=5555551234") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:20] Set("SIP/5555551234-00000034", "__EXTCONTEXT=default-default") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:21] Set("SIP/5555551234-00000034", "_INCOMINGLINE=1") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:22] Set("SIP/5555551234-00000034", "__ORGCALLID=1347303951.80") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:23] GotoIf("SIP/5555551234-00000034", "1?25") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,25) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:25] GotoIf("SIP/5555551234-00000034", "1?27") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,27) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:27] GotoIf("SIP/5555551234-00000034", "1?29") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,29) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:29] GotoIf("SIP/5555551234-00000034", "1?31") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,31) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:31] AGI("SIP/5555551234-00000034", "agi://127.0.0.1:4573/fixcidname") in new stack [2012-09-10 15:05:51] -- AGI Script agi://127.0.0.1:4573/fixcidname completed, returning 0 [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:32] GotoIf("SIP/5555551234-00000034", "1?34") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,34) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:34] GotoIf("SIP/5555551234-00000034", "0?36") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:35] Set("SIP/5555551234-00000034", "CHANNEL(language)=en") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:36] Set("SIP/5555551234-00000034", "FAXNUMBER=5555551234") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:37] GotoIf("SIP/5555551234-00000034", "1?39") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,39) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:39] GotoIf("SIP/5555551234-00000034", "0?41") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:40] Set("SIP/5555551234-00000034", "CALLERID(pres)=allowed_not_screened") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:41] GotoIf("SIP/5555551234-00000034", "1?44") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,44) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:44] GotoIf("SIP/5555551234-00000034", "0?49") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:45] GotoIf("SIP/5555551234-00000034", "1?47") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,47) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:47] GotoIf("SIP/5555551234-00000034", "1?49") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,49) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:49] GotoIf("SIP/5555551234-00000034", "0?54") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:50] GotoIf("SIP/5555551234-00000034", "1?52") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,52) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:52] GotoIf("SIP/5555551234-00000034", "1?54") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,54) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:54] GotoIf("SIP/5555551234-00000034", "0?56") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:55] Set("SIP/5555551234-00000034", "CALLERID(number)=94164148967") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:56] GotoIf("SIP/5555551234-00000034", "1?58") in new stack [2012-09-10 15:05:51] -- Goto (all-incoming-check,s,58) [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:58] GotoIf("SIP/5555551234-00000034", "0?62") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:59] Answer("SIP/5555551234-00000034", "") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:60] PlayTones("SIP/5555551234-00000034", "ring") in new stack [2012-09-10 15:05:51] -- Executing [s@all-incoming-check:61] Wait("SIP/5555551234-00000034", "4") in new stack [2012-09-10 15:05:55] -- Executing [s@all-incoming-check:62] NoOp("SIP/5555551234-00000034", ""NEW CALLER ID: 94164148967 (DAVID BRILLERT)"") in new stack [2012-09-10 15:05:55] -- Executing [s@all-incoming-check:63] Return("SIP/5555551234-00000034", "") in new stack [2012-09-10 15:05:55] -- Executing [5555551234@commzilla-5555551234-incoming:19] Set("SIP/5555551234-00000034", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack [2012-09-10 15:05:55] -- Executing [5555551234@commzilla-5555551234-incoming:20] Set("SIP/5555551234-00000034", "CALLFILENAME=1347303951.80") in new stack [2012-09-10 15:05:55] -- Executing [5555551234@commzilla-5555551234-incoming:21] MixMonitor("SIP/5555551234-00000034", "1347303951.80.WAV,ab") in new stack [2012-09-10 15:05:55] -- Executing [5555551234@commzilla-5555551234-incoming:22] Answer("SIP/5555551234-00000034", "") in new stack [2012-09-10 15:05:55] -- Executing [5555551234@commzilla-5555551234-incoming:23] Goto("SIP/5555551234-00000034", "50") in new stack [2012-09-10 15:05:55] -- Goto (commzilla-5555551234-incoming,5555551234,50) [2012-09-10 15:05:55] -- Executing [5555551234@commzilla-5555551234-incoming:50] Set("SIP/5555551234-00000034", "__CALLDIVERT=YES") in new stack [2012-09-10 15:05:55] == Begin MixMonitor Recording SIP/5555551234-00000034 [2012-09-10 15:05:55] -- Executing [5555551234@commzilla-5555551234-incoming:51] Dial("SIP/5555551234-00000034", "Local/200@commzilla-local/n,") in new stack [2012-09-10 15:05:55] -- Called Local/200@commzilla-local/n [2012-09-10 15:05:55] -- Executing [200@commzilla-local:1] Set("Local/200@commzilla-local-cb1b;2", "CDR(tenant)=commzilla") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:2] Set("Local/200@commzilla-local-cb1b;2", "FORCE_RECORDING=200") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:3] Set("Local/200@commzilla-local-cb1b;2", "LOCAL_EXTEN=200") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:4] Set("Local/200@commzilla-local-cb1b;2", "LOCAL_TENANT=COMMZILLA") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:5] Set("Local/200@commzilla-local-cb1b;2", "LOCAL_TENANT2=commzilla") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:6] Gosub("Local/200@commzilla-local-cb1b;2", "all-local-extension,s,1") in new stack [2012-09-10 15:05:55] -- Executing [s@all-local-extension:1] Set("Local/200@commzilla-local-cb1b;2", "__PICKUPMARK=200") in new stack [2012-09-10 15:05:55] -- Executing [s@all-local-extension:2] GotoIf("Local/200@commzilla-local-cb1b;2", "0?4") in new stack [2012-09-10 15:05:55] -- Executing [s@all-local-extension:3] Set("Local/200@commzilla-local-cb1b;2", "GROUP(OUTGOING_COMMZILLA)=94164148967") in new stack [2012-09-10 15:05:55] -- Executing [s@all-local-extension:4] Set("Local/200@commzilla-local-cb1b;2", "OUTBOUND_GROUP_ONCE=200@INCOMING_COMMZILLA") in new stack [2012-09-10 15:05:55] -- Executing [s@all-local-extension:5] GotoIf("Local/200@commzilla-local-cb1b;2", "1?8") in new stack [2012-09-10 15:05:55] -- Goto (all-local-extension,s,8) [2012-09-10 15:05:55] -- Executing [s@all-local-extension:8] Return("Local/200@commzilla-local-cb1b;2", "") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:7] MixMonitor("Local/200@commzilla-local-cb1b;2", "200_1347303955.82.WAV,ab") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:8] Set("Local/200@commzilla-local-cb1b;2", "CHANNEL(language)=en") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:9] Set("Local/200@commzilla-local-cb1b;2", "SCOPSERV_DBPUT(commzilla/wrapup/94164148967/lastcall)=1347303955.82") in new stack [2012-09-10 15:05:55] WARNING[12129]: res_odbc.c:1406 _ast_odbc_request_obj2: SetConnectAttr (Txn isolation) returned an error: HY000: [MySQL][ODBC 3.51 Driver]MySQL server has gone away [2012-09-10 15:05:55] WARNING[12129]: func_odbc.c:191 generic_execute: SQL Execute returned an error -1: 08S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.95-log]MySQL server has gone away (70) [2012-09-10 15:05:55] WARNING[12129]: func_odbc.c:199 generic_execute: SQL Exec Direct failed (-1)![REPLACE INTO astdb SET dbval='1347303955.82', dbkey='commzilla/wrapup/94164148967/lastcall'] [2012-09-10 15:05:55] WARNING[12129]: res_odbc.c:609 ast_odbc_direct_execute: SQL Execute error! Verifying connection to scopserv [scopserv]... [2012-09-10 15:05:55] WARNING[12129]: res_odbc.c:762 ast_odbc_sanity_check: Connection is down attempting to reconnect... [2012-09-10 15:05:55] NOTICE[12129]: res_odbc.c:1531 odbc_obj_connect: Connecting scopserv [2012-09-10 15:05:55] == Begin MixMonitor Recording Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] NOTICE[12129]: res_odbc.c:1563 odbc_obj_connect: res_odbc: Connected to scopserv [scopserv] [2012-09-10 15:05:55] -- Executing [200@commzilla-local:10] Set("Local/200@commzilla-local-cb1b;2", "SCOPSERV_DBPUT(commzilla/wrapup/200/lastcall)=1347303955.82") in new stack [2012-09-10 15:05:55] -- Executing [200@commzilla-local:11] Macro("Local/200@commzilla-local-cb1b;2", "commzilla-dial,,200,commzilla,20,en,sb200@commzilla,kKg,,default,,Local/s@commzilla-aa-operator-200,vm") in new stack [2012-09-10 15:05:55] -- Executing [s@macro-commzilla-dial:1] NoOp("Local/200@commzilla-local-cb1b;2", ""CALL TO LOCAL EXTENSION FROM 94164148967(DAVID BRILLERT)"") in new stack [2012-09-10 15:05:55] -- Executing [s@macro-commzilla-dial:2] Set("Local/200@commzilla-local-cb1b;2", "__PICKUPMARK=200") in new stack [2012-09-10 15:05:55] -- Executing [s@macro-commzilla-dial:3] AGI("Local/200@commzilla-local-cb1b;2", "agi://127.0.0.1:4573/dial") in new stack [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG1' result is [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG2' result is 200 [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG3' result is commzilla [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG4' result is 20 [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG5' result is en [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG6' result is sb200@commzilla [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG7' result is kKg [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG8' result is [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG9' result is default [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG10' result is [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG11' result is Local/s@commzilla-aa-operator-200 [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'ARG12' result is vm [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'EXT_ACCODE' result is not defined!!! [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'MACRO_PRIORITY' result is 11 [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'CALLBACK_ON_HANGUP' result is not defined!!! [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'CLID_BLOCK' result is not defined!!! [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'BLINDTRANSFER' result is not defined!!! [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'INCOMINGLINE' result is 1 [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'MONITOR_OPTION' result is not defined!!! [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'AUTO_RECORDING' result is not defined!!! [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'FORCE_RECORDING' result is 200 [2012-09-10 15:05:55] -- AGI Script Executing Application: (MixMonitor) Options: (200_1347303955.82.WAV,b) [2012-09-10 15:05:55] == Begin MixMonitor Recording Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] agi://127.0.0.1:4573/dial: Variable 'LIMIT_IN_COMMZILLA_200' result is not defined!!! [2012-09-10 15:05:55] -- AGI Script Executing Application: (NoOp) Options: (IMMEDIATE) [2012-09-10 15:05:55] -- AGI Script agi://127.0.0.1:4573/dial completed, returning 0 [2012-09-10 15:05:55] -- Executing [s@macro-commzilla-dial:4] ExecIf("Local/200@commzilla-local-cb1b;2", "1?Goto(commzilla-aa-followme-200,s,1)") in new stack [2012-09-10 15:05:55] -- Goto (commzilla-aa-followme-200,s,1) [2012-09-10 15:05:55] == Channel 'Local/200@commzilla-local-cb1b;2' jumping out of macro 'commzilla-dial' [2012-09-10 15:05:55] -- Executing [s@commzilla-aa-followme-200:1] Set("Local/200@commzilla-local-cb1b;2", "GLOBAL(__SCREEN_EXTENSION)=COMMZILLA_ONCALL_200") in new stack [2012-09-10 15:05:55] == Setting global variable '__SCREEN_EXTENSION' to 'COMMZILLA_ONCALL_200' [2012-09-10 15:05:55] -- Executing [s@commzilla-aa-followme-200:2] Set("Local/200@commzilla-local-cb1b;2", "__FOLLOWME=YES") in new stack [2012-09-10 15:05:55] -- Executing [s@commzilla-aa-followme-200:3] Dial("Local/200@commzilla-local-cb1b;2", "SIP/201&SIP/220&SIP/221&SIP/222&SIP/223&SIP/224&SIP/225&SIP/226&SIP/229&SIP/231&SIP/232&SIP/233&SIP/234&SIP/250&SIP/300&SIP/301&SIP/401&SIP/402&SIP/500,30,ktM(all-call-followme)") in new stack [2012-09-10 15:05:55] WARNING[12129]: app_dial.c:2341 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent) [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 220[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/220 [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 221[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] == Extension Changed 221[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/221 [2012-09-10 15:05:55] WARNING[12129]: app_dial.c:2341 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent) [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 223[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] == Extension Changed 223[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/223 [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 224[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] == Extension Changed 224[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/224 [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 225[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] == Extension Changed 225[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/225 [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 226[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] == Extension Changed 226[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/226 [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 229[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] == Extension Changed 229[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/229 [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] -- Called SIP/231 [2012-09-10 15:05:55] WARNING[12129]: app_dial.c:2341 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent) [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] -- Called SIP/233 [2012-09-10 15:05:55] WARNING[12129]: app_dial.c:2341 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent) [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 250[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] -- Called SIP/250 [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] == Extension Changed 300[commzilla-local] new state Ringing for Notify User 220 [2012-09-10 15:05:55] == Extension Changed 300[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:05:55] -- Called SIP/300 [2012-09-10 15:05:55] WARNING[12129]: app_dial.c:2341 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent) [2012-09-10 15:05:55] WARNING[12129]: app_dial.c:2341 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent) [2012-09-10 15:05:55] WARNING[12129]: app_dial.c:2341 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent) [2012-09-10 15:05:55] == Using SIP RTP TOS bits 184 [2012-09-10 15:05:55] == Using SIP RTP CoS mark 5 [2012-09-10 15:05:55] -- Called SIP/500 [2012-09-10 15:05:55] -- SIP/220-00000035 connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/221-00000036 connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/223-00000037 connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/224-00000038 connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/225-00000039 connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/226-0000003a connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/229-0000003b connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/231-0000003c connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/233-0000003d connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/250-0000003e connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/300-0000003f connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/500-00000040 connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:55] -- SIP/223-00000037 is ringing [2012-09-10 15:05:55] -- Local/200@commzilla-local-cb1b;1 is ringing [2012-09-10 15:05:55] -- SIP/224-00000038 is ringing [2012-09-10 15:05:55] -- Got SIP response 486 "Busy Here" back from 192.168.192.138:57255 [2012-09-10 15:05:55] -- SIP/233-0000003d is busy [2012-09-10 15:05:55] -- SIP/500-00000040 is ringing [2012-09-10 15:05:55] -- SIP/225-00000039 is ringing [2012-09-10 15:05:55] -- SIP/250-0000003e is ringing [2012-09-10 15:05:56] -- SIP/226-0000003a is ringing [2012-09-10 15:05:56] -- SIP/221-00000036 is ringing [2012-09-10 15:05:56] -- SIP/223-00000037 is ringing [2012-09-10 15:05:56] -- SIP/229-0000003b is ringing [2012-09-10 15:05:56] -- SIP/231-0000003c is ringing [2012-09-10 15:05:56] -- SIP/229-0000003b is ringing [2012-09-10 15:05:56] -- SIP/229-0000003b is ringing [2012-09-10 15:05:56] -- SIP/220-00000035 is ringing [2012-09-10 15:05:56] -- SIP/300-0000003f is ringing [2012-09-10 15:05:56] -- SIP/231-0000003c is ringing [2012-09-10 15:05:56] -- SIP/229-0000003b is ringing [2012-09-10 15:05:57] -- SIP/223-00000037 is ringing [2012-09-10 15:05:58] == Extension Changed 300[commzilla-local] new state InUse for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 300[commzilla-local] new state InUse for Notify User 300 [2012-09-10 15:05:58] -- SIP/300-0000003f connected line has changed. Saving it until answer for Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:58] -- SIP/300-0000003f answered Local/200@commzilla-local-cb1b;2 [2012-09-10 15:05:58] -- Executing [s@macro-all-call-followme:1] Set("SIP/300-0000003f", "GLOBAL(COMMZILLA_ONCALL_200)=SIP/300-0000003f") in new stack [2012-09-10 15:05:58] == Setting global variable 'COMMZILLA_ONCALL_200' to 'SIP/300-0000003f' [2012-09-10 15:05:58] -- Executing [s@macro-all-call-followme:2] Set("SIP/300-0000003f", "MACRO_RESULT=") in new stack [2012-09-10 15:05:58] == Extension Changed 250[commzilla-local] new state Idle for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 229[commzilla-local] new state Idle for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 229[commzilla-local] new state Idle for Notify User 300 [2012-09-10 15:05:58] == Extension Changed 226[commzilla-local] new state Idle for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 226[commzilla-local] new state Idle for Notify User 300 [2012-09-10 15:05:58] == Extension Changed 225[commzilla-local] new state Idle for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 225[commzilla-local] new state Idle for Notify User 300 [2012-09-10 15:05:58] -- Local/200@commzilla-local-cb1b;1 answered SIP/5555551234-00000034 [2012-09-10 15:05:58] == Extension Changed 224[commzilla-local] new state Idle for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 224[commzilla-local] new state Idle for Notify User 300 [2012-09-10 15:05:58] == Extension Changed 223[commzilla-local] new state Idle for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 223[commzilla-local] new state Idle for Notify User 300 [2012-09-10 15:05:58] == Extension Changed 221[commzilla-local] new state Idle for Notify User 220 [2012-09-10 15:05:58] == Extension Changed 221[commzilla-local] new state Idle for Notify User 300 [2012-09-10 15:05:58] == Extension Changed 220[commzilla-local] new state Idle for Notify User 300 [2012-09-10 15:06:00] -- Started music on hold, class 'default', on Local/200@commzilla-local-cb1b;2 [2012-09-10 15:06:00] -- Playing 'pbx-transfer.ulaw' (language 'en') [2012-09-10 15:06:00] WARNING[9692]: mp3/interface.c:216 decodeMP3: Junk at the beginning of frame 49443303 [2012-09-10 15:06:02] -- Executing [220@commzilla-super:1] Set("Local/220@commzilla-super-83dd;2", "CDR(tenant)=commzilla") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:2] Set("Local/220@commzilla-super-83dd;2", "LOCAL_EXTEN=220") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:3] Set("Local/220@commzilla-super-83dd;2", "LOCAL_TENANT=COMMZILLA") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:4] Set("Local/220@commzilla-super-83dd;2", "LOCAL_TENANT2=commzilla") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:5] Gosub("Local/220@commzilla-super-83dd;2", "all-local-extension,s,1") in new stack [2012-09-10 15:06:02] -- Executing [s@all-local-extension:1] Set("Local/220@commzilla-super-83dd;2", "__PICKUPMARK=220") in new stack [2012-09-10 15:06:02] -- Executing [s@all-local-extension:2] GotoIf("Local/220@commzilla-super-83dd;2", "0?4") in new stack [2012-09-10 15:06:02] -- Executing [s@all-local-extension:3] Set("Local/220@commzilla-super-83dd;2", "GROUP(OUTGOING_COMMZILLA)=300") in new stack [2012-09-10 15:06:02] -- Executing [s@all-local-extension:4] Set("Local/220@commzilla-super-83dd;2", "OUTBOUND_GROUP_ONCE=220@INCOMING_COMMZILLA") in new stack [2012-09-10 15:06:02] -- Executing [s@all-local-extension:5] GotoIf("Local/220@commzilla-super-83dd;2", "1?8") in new stack [2012-09-10 15:06:02] -- Goto (all-local-extension,s,8) [2012-09-10 15:06:02] -- Executing [s@all-local-extension:8] Return("Local/220@commzilla-super-83dd;2", "") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:6] Set("Local/220@commzilla-super-83dd;2", "CHANNEL(language)=en") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:7] Set("Local/220@commzilla-super-83dd;2", "SCOPSERV_DBPUT(commzilla/wrapup/300/lastcall)=1347303962.96") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:8] Set("Local/220@commzilla-super-83dd;2", "SCOPSERV_DBPUT(commzilla/wrapup/220/lastcall)=1347303962.96") in new stack [2012-09-10 15:06:02] -- Executing [220@commzilla-super:9] Macro("Local/220@commzilla-super-83dd;2", "commzilla-dial,SIP/220,220,commzilla,20,en,,tkKg,,default,,Local/220@commzilla-local-voicemail,disa") in new stack [2012-09-10 15:06:02] -- Executing [s@macro-commzilla-dial:1] NoOp("Local/220@commzilla-super-83dd;2", ""CALL TO LOCAL EXTENSION FROM 300(David Brillert)"") in new stack [2012-09-10 15:06:02] -- Executing [s@macro-commzilla-dial:2] Set("Local/220@commzilla-super-83dd;2", "__PICKUPMARK=220") in new stack [2012-09-10 15:06:02] -- Executing [s@macro-commzilla-dial:3] AGI("Local/220@commzilla-super-83dd;2", "agi://127.0.0.1:4573/dial") in new stack [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG1' result is SIP/220 [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG2' result is 220 [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG3' result is commzilla [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG4' result is 20 [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG5' result is en [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG6' result is [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG7' result is tkKg [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG8' result is [2012-09-10 15:06:02] agi://127.0.0.1:4573/dial: Variable 'ARG9' result is default [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'ARG10' result is [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'ARG11' result is Local/220@commzilla-local-voicemail [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'ARG12' result is disa [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'EXT_ACCODE' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'MACRO_PRIORITY' result is 9 [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'CALLBACK_ON_HANGUP' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'CLID_BLOCK' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'BLINDTRANSFER' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'INCOMINGLINE' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'MONITOR_OPTION' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'AUTO_RECORDING' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'FORCE_RECORDING' result is not defined!!! [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'LIMIT_IN_COMMZILLA_220' result is not defined!!! [2012-09-10 15:06:03] -- AGI Script Executing Application: (NoOp) Options: (STATUS:) [2012-09-10 15:06:03] agi://127.0.0.1:4573/dial: Variable 'INCOMINGLINE' result is not defined!!! [2012-09-10 15:06:03] -- AGI Script agi://127.0.0.1:4573/dial completed, returning 0 [2012-09-10 15:06:03] -- Executing [s@macro-commzilla-dial:4] ExecIf("Local/220@commzilla-super-83dd;2", "1?Dial(SIP/220,20,tkKgTF(all-hangup,s,1),)") in new stack [2012-09-10 15:06:03] == Using SIP RTP TOS bits 184 [2012-09-10 15:06:03] == Using SIP RTP CoS mark 5 [2012-09-10 15:06:03] -- Called SIP/220 [2012-09-10 15:06:03] == Extension Changed 220[commzilla-local] new state Ringing for Notify User 300 [2012-09-10 15:06:03] -- SIP/220-00000041 is ringing [2012-09-10 15:06:03] -- Local/220@commzilla-super-83dd;1 is ringing [2012-09-10 15:06:11] WARNING[9209]: chan_sip.c:3925 __sip_autodestruct: Autodestruct on dialog '0ade7eff2c5178ff5da5d3075874c2a3@192.168.192.1:5060' with owner in place (Method: BYE). Rescheduling destruction for 10000 ms