-- Executing [8503028993@from-outside:1] Set("SIP/8503028993-00000000", "__NUMBER=8503028993") in new stack
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: pbx_variables.c:381 ast_str_retrieve_variable: Result of 'EXTEN' is '8503028993'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: pbx_variables.c:505 ast_str_substitute_variables_full: Evaluating 'SQL_ESC(${ARG1})' (from 'SQL_ESC(${ARG1})}')' len 16)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: pbx_variables.c:505 ast_str_substitute_variables_full: Evaluating 'ARG1' (from 'ARG1})' len 4)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: pbx_variables.c:381 ast_str_retrieve_variable: Result of 'ARG1' is '8503028993'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: pbx_variables.c:544 ast_str_substitute_variables_full: Function SQL_ESC(8503028993) result is '8503028993'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: res_odbc.c:982 _ast_odbc_request_obj2: Reusing ODBC handle 0x298ad38 from class 'pbxconf1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 2 and maxcol of 10 for column 'xx' (offset 0)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '13626'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 6 and maxcol of 3 for column 'xx' (offset 1)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 6 and maxcol of 64 for column 'xx' (offset 2)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '8503028993'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 8 and maxcol of 10 for column 'xx' (offset 3)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '100'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 10 and maxcol of 10 for column 'xx-in' (offset 4)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '300'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 11 and maxcol of 10 for column 'xx-out' (offset 5)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '50'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 8 and maxcol of 10 for column 'xx' (offset 6)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '55'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 5 and maxcol of 3 for column 'xx' (offset 7)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 9 and maxcol of 3 for column 'xx' (offset 8)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1,1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 8 and maxcol of 10 for column 'xx' (offset 9)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1,1,1'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 10 and maxcol of 10 for column 'xx' (offset 10)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '29'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1,1,1,29'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 9 and maxcol of 3 for column 'xx' (offset 11)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '0'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1,1,1,29,0'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 6 and maxcol of 10 for column 'xx' (offset 12)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '172134'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1,1,1,29,0,172134'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:928 acf_odbc_read: Got collength of 7 and maxcol of 3 for column 'xx' (offset 13)
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:975 acf_odbc_read: Got coldata of '13'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:999 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1,1,1,29,0,172134,13'
[Jun 20 11:34:57] DEBUG[19410][C-00000000]: func_odbc.c:1001 acf_odbc_read: buf is now set to '13626,1,8503028993,100,300,50,55,1,1,1,29,0,172134,13'
[Jun 20 11:34:59] DEBUG[19392]: chan_sip.c:9432 __find_call: = Looking for  Call ID: 1e18d5533e8be93c3b2aed9f00ab499d@185.88.7.189 (Checking From) --From tag as7a76a33a --To-tag
[Jun 20 11:34:59] DEBUG[19392][C-00000000]: chan_sip.c:28855 handle_incoming: **** Received CANCEL (14) - Command in SIP CANCEL
[Jun 20 11:34:59] DEBUG[19392][C-00000000]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '185.88.7.189' into...
[Jun 20 11:34:59] DEBUG[19392][C-00000000]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '185.88.7.189' and port ''.
[Jun 20 11:34:59] DEBUG[19392][C-00000000]: chan_sip.c:3407 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 1e18d5533e8be93c3b2aed9f00ab499d@185.88.7.189