-- Executing [s@macro-end:12] Set("SIP/westcall-00008160", "ODBC_CDR()=westcall,79991233443,74952223333,wescall-in,"WestCall" <79991233443>,SIP/westcall-00008160,,Queue,medbooking,m,,,300,,user_field,2016-03-31 01:13:31,2016-03-31 01:13:31,2016-03-31 01:13:50,18,18,ANSWERED,NOANSWER,,,DOCUMENTATION,,1459376011.34038,1459376011.34038,office,IN,46175") in new stack
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL1})' (from 'SQL_ESC(${VAL1})}','${SQL_ESC(${VAL2})}','${SQL_ESC(${VAL3})}','${SQL_ESC(${VAL4})}','${SQL_ESC(${VAL5})}','${SQL_ESC(${VAL6})}','${SQL_ESC(${VAL7})}','${SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL1' (from 'VAL1})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL1' is 'westcall'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(westcall) result is 'westcall'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL2})' (from 'SQL_ESC(${VAL2})}','${SQL_ESC(${VAL3})}','${SQL_ESC(${VAL4})}','${SQL_ESC(${VAL5})}','${SQL_ESC(${VAL6})}','${SQL_ESC(${VAL7})}','${SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL2' (from 'VAL2})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL2' is '79991233443'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(79991233443) result is '79991233443'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL3})' (from 'SQL_ESC(${VAL3})}','${SQL_ESC(${VAL4})}','${SQL_ESC(${VAL5})}','${SQL_ESC(${VAL6})}','${SQL_ESC(${VAL7})}','${SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL3' (from 'VAL3})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL3' is '74952223333'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(74952223333) result is '74952223333'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL4})' (from 'SQL_ESC(${VAL4})}','${SQL_ESC(${VAL5})}','${SQL_ESC(${VAL6})}','${SQL_ESC(${VAL7})}','${SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL4' (from 'VAL4})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL4' is 'wescall-in'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(wescall-in) result is 'wescall-in'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL5})' (from 'SQL_ESC(${VAL5})}','${SQL_ESC(${VAL6})}','${SQL_ESC(${VAL7})}','${SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL5' (from 'VAL5})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL5' is 'WestCall <79991233443>'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(WestCall <79991233443>) result is 'WestCall <79991233443>'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL6})' (from 'SQL_ESC(${VAL6})}','${SQL_ESC(${VAL7})}','${SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL6' (from 'VAL6})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL6' is 'SIP/westcall-00008160'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(SIP/westcall-00008160) result is 'SIP/westcall-00008160'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL7})' (from 'SQL_ESC(${VAL7})}','${SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL7' (from 'VAL7})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL7' is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC() result is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL8})' (from 'SQL_ESC(${VAL8})}','${SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL8' (from 'VAL8})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL8' is 'Queue'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(Queue) result is 'Queue'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL9})' (from 'SQL_ESC(${VAL9})}','${SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 16)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL9' (from 'VAL9})' len 4)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL9' is 'medbooking'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(medbooking) result is 'medbooking'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL10})' (from 'SQL_ESC(${VAL10})}','${SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL10' (from 'VAL10})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL10' is 'm'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(m) result is 'm'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL11})' (from 'SQL_ESC(${VAL11})}','${SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL11' (from 'VAL11})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL11' is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC() result is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL12})' (from 'SQL_ESC(${VAL12})}','${SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL12' (from 'VAL12})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL12' is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC() result is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL13})' (from 'SQL_ESC(${VAL13})}','${SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL13' (from 'VAL13})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL13' is '300'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(300) result is '300'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL14})' (from 'SQL_ESC(${VAL14})}','${SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL14' (from 'VAL14})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL14' is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC() result is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL15})' (from 'SQL_ESC(${VAL15})}','${SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL15' (from 'VAL15})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL15' is 'user_field'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(user_field) result is 'user_field'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL16})' (from 'SQL_ESC(${VAL16})}','${SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL16' (from 'VAL16})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL16' is '2016-03-31 01:13:31'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(2016-03-31 01:13:31) result is '2016-03-31 01:13:31'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL17})' (from 'SQL_ESC(${VAL17})}','${SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL17' (from 'VAL17})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL17' is '2016-03-31 01:13:31'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(2016-03-31 01:13:31) result is '2016-03-31 01:13:31'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL18})' (from 'SQL_ESC(${VAL18})}','${SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL18' (from 'VAL18})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL18' is '2016-03-31 01:13:50'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(2016-03-31 01:13:50) result is '2016-03-31 01:13:50'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL19})' (from 'SQL_ESC(${VAL19})}','${SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL19' (from 'VAL19})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL19' is '18'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(18) result is '18'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL20})' (from 'SQL_ESC(${VAL20})}','${SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL20' (from 'VAL20})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL20' is '18'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(18) result is '18'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL21})' (from 'SQL_ESC(${VAL21})}','${SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL21' (from 'VAL21})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL21' is 'ANSWERED'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(ANSWERED) result is 'ANSWERED'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL22})' (from 'SQL_ESC(${VAL22})}','${SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL22' (from 'VAL22})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL22' is 'NOANSWER'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(NOANSWER) result is 'NOANSWER'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL23})' (from 'SQL_ESC(${VAL23})}','${SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL23' (from 'VAL23})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL23' is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC() result is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL24})' (from 'SQL_ESC(${VAL24})}','${SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL24' (from 'VAL24})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL24' is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC() result is ''
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${VAL25})' (from 'SQL_ESC(${VAL25})}')' len 17)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4539 ast_str_substitute_variables_full: Evaluating 'VAL25' (from 'VAL25})' len 5)
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'VAL25' is 'DOCUMENTATION'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4573 ast_str_substitute_variables_full: Function SQL_ESC(DOCUMENTATION) result is 'DOCUMENTATION'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: res_odbc.c:1053 odbc_release_obj2: odbc_release_obj2(0x2056d18) called (obj->txf = (nil))
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: app_macro.c:435 _macro_exec: Executed application: Set
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'DIAL_STAT_QUERY' is 'NOANSWER'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4840 pbx_substitute_variables_helper_full: Expression result is '0'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4772 pbx_substitute_variables_helper_full: Function CALLERID(num) result is '79991233443'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'DEPTH_OF_AUTODIAL' is '3'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4772 pbx_substitute_variables_helper_full: Function CDR(duration) result is '18'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4840 pbx_substitute_variables_helper_full: Expression result is '10818'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4772 pbx_substitute_variables_helper_full: Function CALLERID(num) result is '79991233443'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:3728 ast_str_retrieve_variable: Result of 'DEPTH_OF_AUTODIAL' is '3'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4772 pbx_substitute_variables_helper_full: Function CDR(duration) result is '18'
[Mar 31 01:13:50] DEBUG[24905][C-000054a0]: pbx.c:4840 pbx_substitute_variables_helper_full: Expression result is '10818