Asterisk Malloc Debugger Started (see /var/log/asterisk/mmlog))
  == Parsing '/etc/asterisk/cli.conf':   == Found
  == Using SIP RTP CoS mark 5
    -- Executing [4805176177@from-pstn:1] Verbose("SIP/grandstream1-04b6f8d8", "1,Inbound call DID 4805176177") in new stack
 Inbound call DID 4805176177
    -- Executing [4805176177@from-pstn:2] Macro("SIP/grandstream1-04b6f8d8", "SET,inbound-number,4805176177") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__inbound-number=4805176177") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=inbound-number,"4805176177"") in new stack
[May 28 07:40:49] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:49] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','inbound-number','4805176177',NOW()) ON DUPLICATE KEY UPDATE value='4805176177']
[May 28 07:40:49] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:49] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:49] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:49] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:49] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:49] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:49] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','inbound-number','4805176177',NOW()) ON DUPLICATE KEY UPDATE value='4805176177']
[May 28 07:40:49] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:49] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:49] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:49] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:49] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:49] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x8104929] asterisk [0x8104929]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x8157683] asterisk [0x8157683]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:49] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:49] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:49] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x4489bd0
[May 28 07:40:49] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:49] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x4489bd0
[May 28 07:40:49] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:49] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x4489bd0
[May 28 07:40:49] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:49] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x4489bd0
[May 28 07:40:49] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
[May 28 07:40:49] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:49] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
    -- Executing [4805176177@from-pstn:3] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-BLACKLIST,1") in new stack
    -- Executing [4805176177@from-pstn:4] NoOp("SIP/grandstream1-04b6f8d8", "Queue Entries for DID(4805176177): 0") in new stack
    -- Executing [4805176177@from-pstn:5] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-TO-QUEUE,1") in new stack
    -- Executing [4805176177@from-pstn:6] GotoIf("SIP/grandstream1-04b6f8d8", "1?s-TO-USER,1") in new stack
    -- Goto (from-pstn,s-TO-USER,1)
    -- Executing [s-TO-USER@from-pstn:1] Macro("SIP/grandstream1-04b6f8d8", "SET,destuser,ajohnson") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__destuser=ajohnson") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=destuser,"ajohnson"") in new stack
[May 28 07:40:50] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:50] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','destuser','ajohnson',NOW()) ON DUPLICATE KEY UPDATE value='ajohnson']
[May 28 07:40:50] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:50] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:50] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','destuser','ajohnson',NOW()) ON DUPLICATE KEY UPDATE value='ajohnson']
[May 28 07:40:50] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:50] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x8104929] asterisk [0x8104929]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x8157683] asterisk [0x8157683]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:50] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:50] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x4e48c68
[May 28 07:40:50] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:50] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x4e48c68
[May 28 07:40:50] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:50] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x4e48c68
[May 28 07:40:50] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:50] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x4e48c68
[May 28 07:40:50] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
    -- Executing [s-TO-USER@from-pstn:2] Macro("SIP/grandstream1-04b6f8d8", "SET,destexten,8448") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__destexten=8448") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=destexten,"8448"") in new stack
[May 28 07:40:50] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:50] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','destexten','8448',NOW()) ON DUPLICATE KEY UPDATE value='8448']
[May 28 07:40:50] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:50] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:50] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','destexten','8448',NOW()) ON DUPLICATE KEY UPDATE value='8448']
[May 28 07:40:50] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:50] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:50] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:51] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x8104929] asterisk [0x8104929]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x8157683] asterisk [0x8157683]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x4ad4f00
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x4ad4f00
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x4ad4f00
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x4ad4f00
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
    -- Executing [s-TO-USER@from-pstn:3] Verbose("SIP/grandstream1-04b6f8d8", "1,4805176177 sent to 8448") in new stack
 4805176177 sent to 8448
    -- Executing [s-TO-USER@from-pstn:4] Goto("SIP/grandstream1-04b6f8d8", "to-exten,8448,checkdnd") in new stack
    -- Goto (to-exten,8448,113)
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
    -- Executing [8448@to-exten:113] Macro("SIP/grandstream1-04b6f8d8", "SET,dndstatus,0") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__dndstatus=0") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=dndstatus,"0"") in new stack
[May 28 07:40:51] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:51] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','dndstatus','0',NOW()) ON DUPLICATE KEY UPDATE value='0']
[May 28 07:40:51] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:51] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:51] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','dndstatus','0',NOW()) ON DUPLICATE KEY UPDATE value='0']
[May 28 07:40:51] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:51] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x8104929] asterisk [0x8104929]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x8157683] asterisk [0x8157683]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:51] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x49a0df0
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x49a0df0
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x49a0df0
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:51] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x49a0df0
[May 28 07:40:51] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
    -- Executing [8448@to-exten:114] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-DND,1") in new stack
    -- Executing [8448@to-exten:115] Macro("SIP/grandstream1-04b6f8d8", "SET,forwardstatus,DB_CALL_FORWARD(ajohnson)") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__forwardstatus=DB_CALL_FORWARD(ajohnson)") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=forwardstatus,"DB_CALL_FORWARD(ajohnson)"") in new stack
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:51] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:51] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','forwardstatus','DB_CALL_FORWARD(ajohnson)',NOW()) ON DUPLICATE KEY UPDATE value='DB_CALL_FORWARD(ajohnson)']
[May 28 07:40:51] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:51] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:51] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:52] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:52] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','forwardstatus','DB_CALL_FORWARD(ajohnson)',NOW()) ON DUPLICATE KEY UPDATE value='DB_CALL_FORWARD(ajohnson)']
[May 28 07:40:52] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:52] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x8104929] asterisk [0x8104929]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x8157683] asterisk [0x8157683]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x4542968
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x4542968
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x4542968
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x4542968
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
    -- Executing [8448@to-exten:116] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-CALLFORWARD,1") in new stack
    -- Executing [8448@to-exten:117] Macro("SIP/grandstream1-04b6f8d8", "build-dialstring,ajohnson,dialstring") in new stack
    -- Executing [s@macro-build-dialstring:1] Macro("SIP/grandstream1-04b6f8d8", "SET,i,0") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__i=0") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=i,"0"") in new stack
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:52] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:52] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','i','0',NOW()) ON DUPLICATE KEY UPDATE value='0']
[May 28 07:40:52] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:52] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:52] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','i','0',NOW()) ON DUPLICATE KEY UPDATE value='0']
[May 28 07:40:52] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:52] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #22: [0x8104929] asterisk [0x8104929]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #23: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #24: [0x8157683] asterisk [0x8157683]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #25: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:52] DEBUG[11177]: logger.c:1232 ast_backtrace: #26: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:52] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x4eae890
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x4eae890
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x4eae890
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:52] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x4eae890
[May 28 07:40:52] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
[May 28 07:40:52] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
    -- Executing [s@macro-build-dialstring:2] Macro("SIP/grandstream1-04b6f8d8", "SET,devices,1") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__devices=1") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=devices,"1"") in new stack
[May 28 07:40:53] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:53] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','devices','1',NOW()) ON DUPLICATE KEY UPDATE value='1']
[May 28 07:40:53] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:53] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:53] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','devices','1',NOW()) ON DUPLICATE KEY UPDATE value='1']
[May 28 07:40:53] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:53] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #22: [0x8104929] asterisk [0x8104929]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #23: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #24: [0x8157683] asterisk [0x8157683]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #25: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:53] DEBUG[11177]: logger.c:1232 ast_backtrace: #26: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:53] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x4ec5350
[May 28 07:40:53] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:53] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x4ec5350
[May 28 07:40:53] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:53] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x4ec5350
[May 28 07:40:53] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:53] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x4ec5350
[May 28 07:40:53] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
    -- Executing [s@macro-build-dialstring:3] Macro("SIP/grandstream1-04b6f8d8", "SET,tmpstring,dialstring") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__tmpstring=dialstring") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=tmpstring,"dialstring"") in new stack
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:53] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:53] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','tmpstring','dialstring',NOW()) ON DUPLICATE KEY UPDATE value='dialstring']
[May 28 07:40:53] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:53] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:53] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','tmpstring','dialstring',NOW()) ON DUPLICATE KEY UPDATE value='dialstring']
[May 28 07:40:53] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:53] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:53] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:54] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #22: [0x8104929] asterisk [0x8104929]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #23: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #24: [0x8157683] asterisk [0x8157683]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #25: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #26: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x5100e70
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x5100e70
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x5100e70
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x5100e70
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
    -- Executing [s@macro-build-dialstring:4] Macro("SIP/grandstream1-04b6f8d8", "SET,string,") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__string=") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=string,""") in new stack
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:54] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:54] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','string','',NOW()) ON DUPLICATE KEY UPDATE value='']
[May 28 07:40:54] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:54] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:54] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','string','',NOW()) ON DUPLICATE KEY UPDATE value='']
[May 28 07:40:54] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:54] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #22: [0x8104929] asterisk [0x8104929]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #23: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #24: [0x8157683] asterisk [0x8157683]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #25: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:54] DEBUG[11177]: logger.c:1232 ast_backtrace: #26: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x450a858
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x450a858
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x450a858
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:54] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x450a858
[May 28 07:40:54] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
    -- Executing [s@macro-build-dialstring:5] While("SIP/grandstream1-04b6f8d8", "1") in new stack
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
    -- Executing [s@macro-build-dialstring:6] Macro("SIP/grandstream1-04b6f8d8", "SET,device,SIP/polycom1") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__device=SIP/polycom1") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=device,"SIP/polycom1"") in new stack
[May 28 07:40:54] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:54] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','device','SIP/polycom1',NOW()) ON DUPLICATE KEY UPDATE value='SIP/polycom1']
[May 28 07:40:54] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:54] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:54] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:55] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:55] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','device','SIP/polycom1',NOW()) ON DUPLICATE KEY UPDATE value='SIP/polycom1']
[May 28 07:40:55] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:55] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:55] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:55] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #22: [0x8104929] asterisk [0x8104929]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #23: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #24: [0x8157683] asterisk [0x8157683]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #25: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:55] DEBUG[11177]: logger.c:1232 ast_backtrace: #26: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:55] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:55] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x43f6320
[May 28 07:40:55] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:55] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x43f6320
[May 28 07:40:55] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:55] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x43f6320
[May 28 07:40:55] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:55] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x43f6320
[May 28 07:40:55] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
    -- Executing [s@macro-SET:4] MacroExit("SIP/grandstream1-04b6f8d8", "") in new stack
    -- Executing [s@macro-build-dialstring:7] Macro("SIP/grandstream1-04b6f8d8", "SET,string,SIP/polycom1&") in new stack
    -- Executing [s@macro-SET:1] GotoIf("SIP/grandstream1-04b6f8d8", "0?s-FUNCTION,1") in new stack
    -- Executing [s@macro-SET:2] Set("SIP/grandstream1-04b6f8d8", "__string=SIP/polycom1&") in new stack
    -- Executing [s@macro-SET:3] Set("SIP/grandstream1-04b6f8d8", "DB_SET(pbx2-1243521648.0)=string,"SIP/polycom1&"") in new stack
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:55] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:55] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','string','SIP/polycom1&',NOW()) ON DUPLICATE KEY UPDATE value='SIP/polycom1&']
[May 28 07:40:55] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:55] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:55] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:55] WARNING[11177]: func_odbc.c:183 generic_execute: SQL Execute returned an error -1: 42S01: [MySQL][ODBC 3.51 Driver][mysqld-5.0.45-log]Table 'asterisk.CallVariables' doesn't exist (88)
[May 28 07:40:55] WARNING[11177]: func_odbc.c:191 generic_execute: SQL Exec Direct failed (-1)![INSERT INTO asterisk.CallVariables (uid,name,value,createdate) VALUES ('pbx2-1243521648.0','string','SIP/polycom1&',NOW()) ON DUPLICATE KEY UPDATE value='SIP/polycom1&']
[May 28 07:40:55] WARNING[11177]: res_odbc.c:585 ast_odbc_direct_execute: SQL Exec Direct failed.  Attempting a reconnect...
[May 28 07:40:55] DEBUG[11177]: res_odbc.c:1405 odbc_obj_disconnect: Disconnected 0 from mysql [mysql]
[May 28 07:40:55] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:55] NOTICE[11177]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:57] NOTICE[11177]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
[May 28 07:40:57] ERROR[11177]: res_odbc.c:1042 odbc_release_obj2: Reference counts incorrect!  Unlinking database object from container as a safeguard.
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #0: [0x80ebc81] asterisk(ast_bt_get_addresses+0x19) [0x80ebc81]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #1: [0x80ebc5d] asterisk(ast_bt_create+0x8e) [0x80ebc5d]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #2: [0x80ebce6] asterisk(ast_backtrace+0x12) [0x80ebce6]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #3: [0x4408610] /usr/lib/asterisk/modules/res_odbc.so [0x4408610]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #4: [0x4408717] /usr/lib/asterisk/modules/res_odbc.so(ast_odbc_release_obj+0x54) [0x4408717]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #5: [0x4e0c7c1] /usr/lib/asterisk/modules/func_odbc.so [0x4e0c7c1]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #6: [0x8102013] asterisk(ast_func_write+0x159) [0x8102013]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #7: [0x8113a0a] asterisk(pbx_builtin_setvar_helper+0xc4) [0x8113a0a]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #8: [0x8113e4e] asterisk(pbx_builtin_setvar+0x194) [0x8113e4e]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #9: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #10: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #11: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #12: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #13: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #14: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #15: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #16: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #17: [0x51982b3] /usr/lib/asterisk/modules/app_macro.so [0x51982b3]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #18: [0x5199443] /usr/lib/asterisk/modules/app_macro.so [0x5199443]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #19: [0x80fb1b2] asterisk(pbx_exec+0x19e) [0x80fb1b2]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #20: [0x8102f08] asterisk [0x8102f08]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #21: [0x8104285] asterisk(ast_spawn_extension+0x53) [0x8104285]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #22: [0x8104929] asterisk [0x8104929]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #23: [0x8105dc9] asterisk [0x8105dc9]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #24: [0x8157683] asterisk [0x8157683]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #25: [0x4f445b] /lib/libpthread.so.0 [0x4f445b]
[May 28 07:40:57] DEBUG[11177]: logger.c:1232 ast_backtrace: #26: [0x702e5e] /lib/libc.so.6(clone+0x5e) [0x702e5e]
[May 28 07:40:57] DEBUG[11177]: res_odbc.c:1413 odbc_obj_disconnect: Database handle deallocated
[May 28 07:40:57] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -1 on object 0x4ad7228
[May 28 07:40:57] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:57] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -2 on object 0x4ad7228
[May 28 07:40:57] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:57] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -3 on object 0x4ad7228
[May 28 07:40:57] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:57] ERROR[11177]: astobj2.c:279 __ao2_ref: refcount -4 on object 0x4ad7228
[May 28 07:40:57] ERROR[11177]: astobj2.c:110 INTERNAL_OBJ: user_data is NULL
[May 28 07:40:58] NOTICE[11143]: res_odbc.c:1440 odbc_obj_connect: Connecting mysql
[May 28 07:40:58] NOTICE[11143]: res_odbc.c:1468 odbc_obj_connect: res_odbc: Connected to mysql [mysql]
core stop now