[ 
https://forge.continuent.org/jira/browse/SEQUOIA-965?page=comments#action_14023 
] 

Rakitha Suranga commented on SEQUOIA-965:
-----------------------------------------

Hi,

I've conduct some further tests and I think that there is a problem in the 
recovery process when I'm saving data through only Hibernate.

I tested same scenario with JDBC and simply using "sql client". 

My setup is summarized as follows:

(As primary, backup and recovery databases)

1. enable both backends (enable *)
2. disable backend2
3. I get application to write some data in to the virtual database using   
   JDBC (recover log id 644) 

Here is part of recovery log showing this:
Please note,  Columns have been separated using |. Below you can see sql_param 
column is blank.
------------------------------------------------------------------------------------------------
|644|user|insert into testschema.testtable (myTypeCode, myTypeDesc, myType, 
status, id) values ('HC008', 'test type 0008 desc', 'test type 0008', 
'Y',nextval('testschema.seq_testtable') )|  |A|0|642|S|4300|1|644|
------------------------------------------------------------------------------------------------

Controller Log (saving data/one backend)
------------------------------------------------------------------------------------------------
20:06:27,546 DEBUG controller.RequestManager.rezbaseDB Scheduling write request 
(642): insert into testschema.testtable (myType...
20:06:27,546 DEBUG sequoia.controller.scheduler Schedule insert into 
testschema.testtable (myTypeCode, myTypeDesc, myType, status, id) values 
('HC008', 'test type 0008 desc', 'test type 0008', 
'Y',nextval('testschema.seq_testtable') )/ - Current pending writes: 1
20:06:27,547 DEBUG controller.RequestManager.rezbaseDB Load balancing write 
request (642,317): insert into testschema.testtable (myType...
20:06:27,547 DEBUG sequoia.controller.recoverylog Logging request 644
20:06:27,547 DEBUG sequoia.controller.recoverylog INSERT INTO logtable 
VALUES(644,postgres,insert into testschema.testtable (myTypeCode, myTypeDesc, 
myType, status, id) values ('HC008', 'test type 0008 desc', 'test type 0008', 
'Y',nextval('testschema.seq_testtable') ),,A,0,642,E,4148,0,-1)
20:06:27,547 DEBUG sequoia.controller.loadbalancer Request 642 execute on 1 
backends
20:06:27,550 DEBUG backend.DatabaseBackend.rezbackend1 Scheduling request 
insert into testschema.testtable (myTypeCode, myTypeDesc, myType, status, id) 
values ('HC008', 'test type 0008 desc', 'test type 0008', 
'Y',nextval('testschema.seq_testtable') )/ in non conflicting queue
20:06:27,550 DEBUG backend.DatabaseBackend.rezbackend1 Adding task Autocommit 
StatementExecuteUpdateTask 317 (insert into testschema.testtable (myTypeCode, 
myTypeDesc, myType, status, id) values ('HC008', 'test type 0008 desc', 'test 
type 0008', 'Y',nextval('testschema.seq_testtable') )/) to pending request queue
20:06:27,550 DEBUG backend.DatabaseBackend.rezbackend1 Executing task 
Autocommit StatementExecuteUpdateTask 317 (insert into testschema.testtable 
(myTypeCode, myTypeDesc, myType, status, id) values ('HC008', 'test type 0008 
desc', 'test type 0008', 'Y',nextval('testschema.seq_testtable') )/)
20:06:27,699 DEBUG backend.DatabaseBackend.rezbackend1 Task Autocommit 
StatementExecuteUpdateTask 317 (insert into testschema.testtable (myTypeCode, 
myTypeDesc, myType, status, id) values ('HC008', 'test type 0008 desc', 'test 
type 0008', 'Y',nextval('testschema.seq_testtable') )/) completed
20:06:27,699 DEBUG sequoia.controller.recoverylog Logging request 644
20:06:27,699 DEBUG sequoia.controller.recoverylog UPDATE logtable SET 
exec_status=S,update_count=1,exec_time=4300,completion_log_id=644 WHERE 
log_id=644
20:06:27,699 DEBUG sequoia.controller.scheduler Write completed, remaining 
pending writes: 0
------------------------------------------------------------------------------------------------

4. enable backend2

Controller Log (enable backend2)
------------------------------------------------------------------------------------------------
20:24:18,589 DEBUG sequoia.controller.recoverylog recovering read request
20:24:18,590 DEBUG backend.DatabaseBackend.rezbackend2 Scheduling request 
select 1 in non conflicting queue
20:24:18,590 DEBUG backend.DatabaseBackend.rezbackend2 Adding task Autocommit 
StatementExecuteQueryTask (select 1) to pending request queue
20:24:18,590 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
Autocommit StatementExecuteQueryTask (select 1)
20:24:18,590 DEBUG sequoia.controller.recoverylog recovering write request
20:24:18,591 DEBUG backend.DatabaseBackend.rezbackend2 Scheduling request 
insert into testschema.testtable (myTypeCode, myTypeDesc, myType, status, id) 
values ('HC008', 'test type 0008 desc', 'test type 0008', 
'Y',nextval('testschema.seq_testtable') )/ in non conflicting queue
20:24:18,591 DEBUG backend.DatabaseBackend.rezbackend2 Adding task Autocommit 
StatementExecuteUpdateTask 358 (insert into testschema.testtable (myTypeCode, 
myTypeDesc, myType, status, id) values ('HC008', 'test type 0008 desc', 'test 
type 0008', 'Y',nextval('testschema.seq_testtable') )/) to pending request queue
20:24:18,591 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
Autocommit StatementExecuteUpdateTask 358 (insert into testschema.testtable 
(myTypeCode, myTypeDesc, myType, status, id) values ('HC008', 'test type 0008 
desc', 'test type 0008', 'Y',nextval('testschema.seq_testtable') )/)
20:24:18,592 DEBUG sequoia.controller.recoverylog recovering read request
20:24:18,592 DEBUG backend.DatabaseBackend.rezbackend2 Task Autocommit 
StatementExecuteQueryTask (select 1) completed
20:24:18,657 DEBUG backend.DatabaseBackend.rezbackend2 Scheduling request 
select 1 in non conflicting queue
20:24:18,657 DEBUG backend.DatabaseBackend.rezbackend2 Adding task Autocommit 
StatementExecuteQueryTask (select 1) to pending request queue
20:24:18,657 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
Autocommit StatementExecuteQueryTask (select 1)
20:24:18,657 DEBUG sequoia.controller.recoverylog recovering read request
20:24:18,658 DEBUG backend.DatabaseBackend.rezbackend2 Task Autocommit 
StatementExecuteQueryTask (select 1) completed
20:24:18,658 DEBUG backend.DatabaseBackend.rezbackend2 Scheduling request 
select 1 in non conflicting queue
20:24:18,658 DEBUG backend.DatabaseBackend.rezbackend2 Adding task Autocommit 
StatementExecuteQueryTask (select 1) to pending request queue
20:24:18,658 DEBUG backend.DatabaseBackend.rezbackend2 Task Autocommit 
StatementExecuteUpdateTask 358 (insert into testschema.testtable (myTypeCode, 
myTypeDesc, myType, status, id) values ('HC008', 'test type 0008 desc', 'test 
type 0008', 'Y',nextval('testschema.seq_testtable') )/) completed
------------------------------------------------------------------------------------------------

* Data is recovered successfully and we can see some execute query tasks about 
my insert statement. Also I checked both databases and new entry was there.  


_____________________________________________________________________________

Again I tested above scenario with Hibernate.

My setup is summarized as follows:

1. enable both backends (enable *)
2. disable backend2
3. I get application to write some data in to the virtual database using   
   Hibernate (recover log ids 808-810).

Here is part of recovery log showing this:
Please note, Columns have been separated using |. Below you can see sql_param 
column is having some values.
------------------------------------------------------------------------------------------------
|808|user|{call nextval('testschema.seq_testtable')}|T|1|123|S|39732|0|808|

|809|user|insert into testschema.testtable (meTypeCode, meTypeDesc, meType, 
status, id) values (?, ?, ?, ?, ?) <!%N|12|!%><!%S|test type 0009 
desc|!%><!%S|test type 0009|!%><!%S|Y|!%><!%I|95|!%>|T|1|124|S|4136|1|809|

|810|user|commit|T|1|0|S|4|0|810|
------------------------------------------------------------------------------------------------

Controller Log (saving data/one backend)
------------------------------------------------------------------------------------------------
20:37:31,051 DEBUG backend.DatabaseBackend.rezbackend1 Procedure least already 
in schema.
20:37:31,052 DEBUG backend.DatabaseBackend.rezbackend1 Task 
CallableStatementExecuteQueryTask for transaction 1 ({call 
nextval('testschema.seq_testtable')}/) completed
20:37:31,052 DEBUG controller.RequestManager.rezbaseDB No semantic found for 
stored procedure {call nextval('testschema.seq_testtable')}/.... Forcing a 
schema refresh.
20:37:31,052 DEBUG sequoia.controller.recoverylog Logging request 808
20:37:31,053 DEBUG sequoia.controller.recoverylog UPDATE logtable SET 
exec_status=S,update_count=0,exec_time=39732,completion_log_id=808 WHERE 
log_id=808
20:37:31,053 DEBUG sequoia.controller.scheduler Stored procedure completed, 
remaining pending writes: 0
20:37:31,093 DEBUG virtualdatabase.VirtualDatabaseWorkerThread.rezbaseDB 
StatementExecuteUpdate command
20:37:31,096 DEBUG controller.RequestManager.rezbaseDB Database schema is 
dirty, refreshing it
20:37:35,225 DEBUG controller.RequestManager.rezbaseDB Scheduling write request 
(124): insert into testschema.testtable (myType...
20:37:35,225 DEBUG sequoia.controller.scheduler Schedule insert into 
testschema.testtable (myTypeCode, myTypeDesc, myType, status, id) values (?, ?, 
?, ?, ?)/<!%N|12|!%><!%S|test type 0009 desc|!%><!%S|test type 
0009|!%><!%S|Y|!%><!%I|95|!%> - Current pending writes: 1
20:37:35,225 DEBUG controller.RequestManager.rezbaseDB Load balancing write 
request (124,1): insert into testschema.testtable (myType...
20:37:35,227 DEBUG sequoia.controller.recoverylog Logging request 809
20:37:35,227 DEBUG sequoia.controller.recoverylog INSERT INTO logtable 
VALUES(809,postgres,insert into testschema.testtable (myTypeCode, myTypeDesc, 
myType, status, id) values (?, ?, ?, ?, ?),<!%N|12|!%><!%S|test type 0009 
desc|!%><!%S|test type 0009|!%><!%S|Y|!%><!%I|95|!%>,T,1,124,E,4131,0,-1)
20:37:35,227 DEBUG sequoia.controller.loadbalancer Request 124 execute on 1 
backends
20:37:35,230 DEBUG backend.DatabaseBackend.rezbackend1 Scheduling request 
insert into testschema.testtable (myTypeCode, myTypeDesc, myType, status, id) 
values (?, ?, ?, ?, ?)/<!%N|12|!%><!%S|test type 0009 desc|!%><!%S|test type 
0009|!%><!%S|Y|!%><!%I|95|!%> in non conflicting queue
20:37:35,230 DEBUG backend.DatabaseBackend.rezbackend1 Adding task 
StatementExecuteUpdateTask from transaction 1 (insert into testschema.testtable 
(myTypeCode, myTypeDesc, myType, status, id) values (?, ?, ?, ?, 
?)/<!%N|12|!%><!%S|test type 0009 desc|!%><!%S|test type 
0009|!%><!%S|Y|!%><!%I|95|!%>) to pending request queue
20:37:35,230 DEBUG backend.DatabaseBackend.rezbackend1 Executing task 
StatementExecuteUpdateTask from transaction 1 (insert into testschema.testtable 
(myTypeCode, myTypeDesc, myType, status, id) values (?, ?, ?, ?, 
?)/<!%N|12|!%><!%S|test type 0009 desc|!%><!%S|test type 
0009|!%><!%S|Y|!%><!%I|95|!%>)
20:37:35,232 DEBUG backend.DatabaseBackend.rezbackend1 Task 
StatementExecuteUpdateTask from transaction 1 (insert into testschema.testtable 
(myTypeCode, myTypeDesc, myType, status, id) values (?, ?, ?, ?, 
?)/<!%N|12|!%><!%S|test type 0009 desc|!%><!%S|test type 
0009|!%><!%S|Y|!%><!%I|95|!%>) completed
20:37:35,232 DEBUG sequoia.controller.recoverylog Logging request 809
20:37:35,232 DEBUG sequoia.controller.recoverylog UPDATE logtable SET 
exec_status=S,update_count=1,exec_time=4136,completion_log_id=809 WHERE 
log_id=809
20:37:35,233 DEBUG sequoia.controller.scheduler Write completed, remaining 
pending writes: 0
20:37:35,233 DEBUG virtualdatabase.VirtualDatabaseWorkerThread.rezbaseDB Commit 
command
20:37:35,234 DEBUG sequoia.controller.scheduler Commit scheduled - current 
pending writes: 1
20:37:35,234 DEBUG controller.RequestManager.rezbaseDB Commit transaction 1
20:37:35,235 DEBUG sequoia.controller.loadbalancer Request commit 1 execute on 
1 backends
20:37:35,235 DEBUG sequoia.controller.recoverylog Logging request 810
20:37:35,235 DEBUG sequoia.controller.recoverylog INSERT INTO logtable 
VALUES(810,postgres,commit,NULL,T,1,0,E,0,-1,-1)
20:37:35,237 DEBUG backend.DatabaseBackend.rezbackend1 Adding task CommitTask 
(1) to pending request queue
20:37:35,237 DEBUG backend.DatabaseBackend.rezbackend1 Executing task 
CommitTask (1)
20:37:35,238 DEBUG backend.DatabaseBackend.rezbackend1 Task CommitTask (1) 
completed
20:37:35,238 DEBUG sequoia.controller.recoverylog Logging request 810
20:37:35,238 DEBUG sequoia.controller.recoverylog UPDATE logtable SET 
exec_status=S,update_count=0,exec_time=4,completion_log_id=810 WHERE log_id=810
20:37:35,239 DEBUG sequoia.controller.scheduler Commit completed, remaining 
pending transactions: 0
20:37:35,239 DEBUG sequoia.controller.scheduler Commit completed, remaining 
pending writes: 0
------------------------------------------------------------------------------------------------

* I checked database1 (backend1). New entry was there. Sequence also adjusted.
 
4. enable backend2

Controller Log (enable backend2)
------------------------------------------------------------------------------------------------

20:48:16,039 DEBUG sequoia.controller.recoverylog recovering read request
20:48:16,040 DEBUG sequoia.controller.recoverylog recovering read request
20:48:16,041 DEBUG sequoia.controller.recoverylog recovering read request
20:48:16,044 DEBUG sequoia.controller.recoverylog recovering stored procedure
20:48:16,047 DEBUG sequoia.controller.recoverylog recovering write request
20:48:16,048 DEBUG sequoia.controller.recoverylog commit transaction: 1
20:48:16,049 DEBUG sequoia.controller.scheduler Suspending new transactions
20:48:16,049 DEBUG sequoia.controller.scheduler Suspending new writes
20:48:16,049 DEBUG sequoia.controller.scheduler All transactions suspended
20:48:16,049 DEBUG sequoia.controller.scheduler All writes suspended
20:48:16,050 INFO  controller.recoverylog.RecoverThread Recovery completed
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 Stopping 5 worker 
threads of backend rezbackend2
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 Adding task 
KillThreadTask to pending request queue
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
KillThreadTask
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 rezbaseDB - 
BackendWorkerThread for backend 'rezbackend2' with RAIDb level:1 is shutting 
down
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 Task KillThreadTask 
completed
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 Adding task 
KillThreadTask to pending request queue
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
KillThreadTask
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 rezbaseDB - 
BackendWorkerThread for backend 'rezbackend2' with RAIDb level:1 is shutting 
down
20:48:16,051 DEBUG backend.DatabaseBackend.rezbackend2 Adding task 
KillThreadTask to pending request queue
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
KillThreadTask
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Adding task 
KillThreadTask to pending request queue
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
KillThreadTask
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 rezbaseDB - 
BackendWorkerThread for backend 'rezbackend2' with RAIDb level:1 is shutting 
down
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Task KillThreadTask 
completed
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Task KillThreadTask 
completed
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 rezbaseDB - 
BackendWorkerThread for backend 'rezbackend2' with RAIDb level:1 is shutting 
down
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Adding task 
KillThreadTask to pending request queue
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Executing task 
KillThreadTask
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 Task KillThreadTask 
completed
20:48:16,052 DEBUG backend.DatabaseBackend.rezbackend2 rezbaseDB - 
BackendWorkerThread for backend 'rezbackend2' with RAIDb level:1 is shutting 
down
20:48:16,053 DEBUG backend.DatabaseBackend.rezbackend2 Task KillThreadTask 
completed
20:48:16,056 DEBUG backend.DatabaseBackend.rezbackend2 Starting 5 worker 
threads for backend rezbackend2
20:48:16,066 DEBUG backend.DatabaseBackend.rezbackend2 Database backend 
rezbackend2 is now in state sequoia.virtualdatabase.backend.enable.write
20:48:16,068 DEBUG backend.DatabaseBackend.rezbackend2 Database backend 
rezbackend2 is now in state sequoia.virtualdatabase.backend.enable.write
20:48:16,070 DEBUG sequoia.controller.scheduler Resuming writes
20:48:16,070 DEBUG sequoia.controller.scheduler Resuming new transactions
20:48:16,071 INFO  controller.recoverylog.RecoverThread Database backend 
rezbackend2 is now enabled
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
allProceduresAreCallable=true
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
allTablesAreSelectable=true
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
dataDefinitionCausesTransactionCommit=false
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
dataDefinitionIgnoredInTransactions=false
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
deletesAreDetected(int=1003)=false
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
deletesAreDetected(int=1004)=false
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
deletesAreDetected(int=1005)=false
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
doesMaxRowSizeIncludeBlobs=false
20:48:16,072 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDefaultTransactionIsolation=2
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDatabaseMajorVersion=8
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDatabaseMinorVersion=0
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDriverMajorVersion=8
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDriverMinorVersion=0
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getJDBCMajorVersion=3
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getJDBCMinorVersion=0
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxBinaryLiteralLength=0
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getResultSetHoldability=1
20:48:16,073 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getSQLStateType=2
20:48:16,074 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxCatalogNameLength=63
20:48:16,074 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxCharLiteralLength=0
20:48:16,074 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxColumnNameLength=63
20:48:16,074 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxColumnsInGroupBy=0
20:48:16,075 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxColumnsInIndex=32
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxColumnsInOrderBy=0
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxColumnsInSelect=0
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxColumnsInTable=1600
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxConnections=8192
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxCursorNameLength=63
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxIndexLength=0
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxProcedureNameLength=63
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxRowSize=1073741824
20:48:16,076 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxSchemaNameLength=63
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxStatementLength=0
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxStatements=1
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxTableNameLength=63
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxTablesInSelect=0
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getMaxUserNameLength=63
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getCatalogSeparator=.
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getCatalogTerm=database
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDatabaseProductName=PostgreSQL
20:48:16,077 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDriverName=PostgreSQL Native Driver
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getDriverVersion=PostgreSQL 8.0 JDBC3 with SSL (build 311)
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getExtraNameCharacters=
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getIdentifierQuoteString="
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getNumericFunctions=abs,acos,asin,atan,atan2,ceiling,cos,cot,degrees,exp,floor,log,log10,mod,pi,power,radians,rand,round,sign,sin,sqrt,tan,truncate
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getProcedureTerm=function
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getSchemaTerm=schema
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getSearchStringEscape=\\
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getSQLKeywords=abort,acl,add,aggregate,append,archive,arch_store,backward,binary,boolean,change,cluster,copy,database,delimiter,delimiters,do,extend,explain,forward,heavy,index,inherits,isnull,light,listen,load,merge,nothing,notify,notnull,oids,purge,rename,replace,retrieve,returns,rule,recipe,setof,stdin,stdout,store,vacuum,verbose,version
20:48:16,078 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getStringFunctions=ascii,char,concat,lcase,left,length,ltrim,repeat,rtrim,space,substring,ucase,replace
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getSystemFunctions=database,ifnull,user
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
getTimeDateFunctions=curdate,curtime,dayname,dayofmonth,dayofweek,dayofyear,hour,minute,month,monthname,now,quarter,second,week,year
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
insertsAreDetected(int=1003)=false
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
insertsAreDetected(int=1004)=false
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
insertsAreDetected(int=1005)=false
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
locatorsUpdateCopy=true
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
nullPlusNonNullIsNull=true
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
nullsAreSortedAtEnd=false
20:48:16,079 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
nullsAreSortedAtStart=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
nullsAreSortedHigh=true
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
nullsAreSortedLow=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersDeletesAreVisible(int=1003)=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersDeletesAreVisible(int=1004)=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersDeletesAreVisible(int=1005)=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersInsertsAreVisible(int=1003)=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersInsertsAreVisible(int=1004)=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersInsertsAreVisible(int=1005)=false
20:48:16,080 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersUpdatesAreVisible(int=1003)=false
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersUpdatesAreVisible(int=1004)=false
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersUpdatesAreVisible(int=1005)=false
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownDeletesAreVisible(int=1003)=true
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownDeletesAreVisible(int=1004)=true
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownDeletesAreVisible(int=1005)=true
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownInsertsAreVisible(int=1003)=true
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownInsertsAreVisible(int=1004)=true
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownInsertsAreVisible(int=1005)=true
20:48:16,081 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownUpdatesAreVisible(int=1003)=true
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownUpdatesAreVisible(int=1004)=true
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownUpdatesAreVisible(int=1005)=true
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
storesLowerCaseIdentifiers=true
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
storesLowerCaseQuotedIdentifiers=false
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
storesMixedCaseIdentifiers=false
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
storesMixedCaseQuotedIdentifiers=false
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
storesUpperCaseIdentifiers=false
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
storesUpperCaseQuotedIdentifiers=false
20:48:16,082 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsAlterTableWithAddColumn=true
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsAlterTableWithDropColumn=true
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsANSI92EntryLevelSQL=true
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsANSI92FullSQL=false
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsANSI92IntermediateSQL=false
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsBatchUpdates=true
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsCatalogsInDataManipulation=false
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsCatalogsInIndexDefinitions=false
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsCatalogsInPrivilegeDefinitions=false
20:48:16,083 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsCatalogsInProcedureCalls=false
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsCatalogsInTableDefinitions=false
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsColumnAliasing=true
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsConvert=false
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsCoreSQLGrammar=false
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsCorrelatedSubqueries=true
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsDataDefinitionAndDataManipulationTransactions=true
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsDataManipulationTransactionsOnly=false
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsDifferentTableCorrelationNames=false
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsExpressionsInOrderBy=true
20:48:16,084 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsExtendedSQLGrammar=false
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsFullOuterJoins=true
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsGetGeneratedKeys=false
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsGroupBy=true
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsGroupByBeyondSelect=true
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsGroupByUnrelated=true
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsIntegrityEnhancementFacility=true
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsLikeEscapeClause=true
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsLimitedOuterJoins=true
20:48:16,085 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMinimumSQLGrammar=true
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMixedCaseIdentifiers=false
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMixedCaseQuotedIdentifiers=true
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMultipleOpenResults=false
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMultipleResultSets=false
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMultipleTransactions=true
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsNamedParameters=false
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsNonNullableColumns=true
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenCursorsAcrossCommit=false
20:48:16,086 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenCursorsAcrossRollback=false
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenStatementsAcrossCommit=true
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenStatementsAcrossRollback=true
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOrderByUnrelated=true
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOuterJoins=true
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsPositionedDelete=false
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsPositionedUpdate=false
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1003,int=1007)=true
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1003,int=1008)=true
20:48:16,087 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1004,int=1007)=true
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1004,int=1008)=true
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1005,int=1007)=false
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1005,int=1008)=false
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetHoldability(int=1)=true
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetHoldability(int=2)=true
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetType(int=1003)=true
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetType(int=1004)=true
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetType(int=1005)=false
20:48:16,088 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSavepoints=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSchemasInDataManipulation=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSchemasInIndexDefinitions=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSchemasInPrivilegeDefinitions=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSchemasInProcedureCalls=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSchemasInTableDefinitions=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSelectForUpdate=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsStatementPooling=false
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsStoredProcedures=false
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSubqueriesInComparisons=true
20:48:16,089 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSubqueriesInExists=true
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSubqueriesInIns=true
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSubqueriesInQuantifieds=true
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsTableCorrelationNames=true
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsTransactionIsolationLevel(int=0)=false
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsTransactionIsolationLevel(int=2)=true
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsTransactionIsolationLevel(int=1)=true
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsTransactionIsolationLevel(int=4)=true
20:48:16,090 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsTransactionIsolationLevel(int=8)=true
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsTransactions=true
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsUnion=true
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsUnionAll=true
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
updatesAreDetected(int=1003)=false
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
updatesAreDetected(int=1004)=false
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
updatesAreDetected(int=1005)=false
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
usesLocalFilePerTable=false
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
usesLocalFiles=false
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
isCatalogAtStart=true
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
deletesAreDetected(int=1004)=false
20:48:16,091 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
deletesAreDetected(int=1005)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
insertsAreDetected(int=1003)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
insertsAreDetected(int=1004)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
insertsAreDetected(int=1005)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
locatorsUpdateCopy=true
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersDeletesAreVisible(int=1003)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersDeletesAreVisible(int=1004)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersDeletesAreVisible(int=1005)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersInsertsAreVisible(int=1003)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersInsertsAreVisible(int=1004)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersInsertsAreVisible(int=1005)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersUpdatesAreVisible(int=1003)=false
20:48:16,092 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersUpdatesAreVisible(int=1004)=false
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
othersUpdatesAreVisible(int=1005)=false
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownDeletesAreVisible(int=1003)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownDeletesAreVisible(int=1004)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownDeletesAreVisible(int=1005)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownInsertsAreVisible(int=1003)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownInsertsAreVisible(int=1004)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownInsertsAreVisible(int=1005)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownUpdatesAreVisible(int=1003)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownUpdatesAreVisible(int=1004)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
ownUpdatesAreVisible(int=1005)=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsBatchUpdates=true
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMultipleOpenResults=false
20:48:16,093 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsMultipleResultSets=false
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenCursorsAcrossCommit=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenCursorsAcrossRollback=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenStatementsAcrossCommit=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsOpenStatementsAcrossRollback=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetHoldability(int=1)=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetHoldability(int=2)=false
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1003,int=1007)=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1003,int=1008)=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1004,int=1007)=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1004,int=1008)=true
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1005,int=1007)=false
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetConcurrency(int=1005,int=1008)=false
20:48:16,094 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetType(int=1003)=true
20:48:16,095 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetType(int=1004)=true
20:48:16,095 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsResultSetType(int=1005)=false
20:48:16,095 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
supportsSavepoints=false
20:48:16,095 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
updatesAreDetected(int=1003)=false
20:48:16,095 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
updatesAreDetected(int=1004)=false
20:48:16,095 DEBUG backend.DatabaseBackend.rezbackend2 Updating metadata 
updatesAreDetected(int=1005)=false
20:48:16,095 DEBUG VirtualDatabaseWorkerThread.rezbaseDB.metadata fetching 
static metadata for backend:rezbackend2
20:48:16,095 DEBUG VirtualDatabaseWorkerThread.rezbaseDB.metadata Backend 
static metadata is compatible with current ones:true
20:48:24,989 DEBUG sequoia.controller.connection Released idle connection (idle 
timeout reached)
20:49:00,770 DEBUG controller.core.Controller Starting new 
ControllerWorkerThread
20:49:00,770 DEBUG controller.core.Controller Connection from /127.0.0.1:37409
20:49:00,771 DEBUG controller.core.Controller Controller pinged
20:49:00,771 DEBUG controller.core.Controller Closing connection with client.
20:49:15,773 DEBUG controller.core.Controller ControllerWorkerThread 
terminating.
------------------------------------------------------------------------------------------------

*The result is: 

1. backend2 is enable position. 
2. Data DO NOT save to the database2 (backend2).
3. I DON'T see any of the execute query tasks from log_id's 808-810 written to 
my
   controller log.
4. I don't see any single WARN or ERROR in my log.

Then I believe that the recovery process is having some sort of problem with 
Hibernate. But different between 1st and 2nd recovery log, is sql_param column.

Please let me know if any one having this kind of problem or Can anyone 
explains this behavior?

Thanks
Rakitha


> Recovery Issue with Sequoia-3.0-beta2
> -------------------------------------
>
>          Key: SEQUOIA-965
>          URL: https://forge.continuent.org/jira/browse/SEQUOIA-965
>      Project: Sequoia
>         Type: Task

>   Components: Recovery Log
>     Versions: Sequoia 3.0 beta3
>  Environment: operating system-linux redhat, databse -PostgreSQL 8.0.3,  
> Jboss4/Hibernate application. 
>     Reporter: Rakitha Suranga
>     Priority: Blocker

>
> Original Estimate: 2 days, 5 hours, 30 minutes
>         Remaining: 2 days, 5 hours, 30 minutes
>
> I'm using one controller with sequoia-3.0-beta2 and two backends(backend1 & 
> backend2) running with PostgreSQL 8.0.3. We are testing following 
> environments with Jboss4/Hibernate application. This environment was 
> configured with RAIDb-1, like Sequoia User's Guide.
>  
>                      My test script makes the following steps:
> 1. initialize backend1
> 2. backup backend1
> 3. enable backend1
> 4. insert one record using application (only backends1 enable)
> 5. restore backup to the backend2
> 6. enable backend2(sequoia recover using last know checkpoint)
>  
> But after recovery, backend2 doesn't have the new record, and looking for 
> controller's log hasn't errors.

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
   https://forge.continuent.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see:
   http://www.atlassian.com/software/jira

_______________________________________________
Sequoia mailing list
[email protected]
https://forge.continuent.org/mailman/listinfo/sequoia

Reply via email to