Skip to content

Instantly share code, notes, and snippets.

@ben-manes

ben-manes/gist:5746917

Created June 10, 2013 06:37
Show Gist options
  • Save ben-manes/5746917 to your computer and use it in GitHub Desktop.
Save ben-manes/5746917 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
23:36:18.125 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource
23:36:18.129 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'getMetaData'
23:36:18.132 [main] DEBUG c.g.f.c.dbsupport.DbSupportFactory - Database: H2 1.3
23:36:18.134 [main] DEBUG com.googlecode.flyway.core.Flyway - DDL Transactions Supported: false
23:36:18.134 [main] DEBUG com.googlecode.flyway.core.Flyway - Schema: file_vault
23:36:18.139 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.139 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.141 [main] DEBUG c.g.flyway.core.command.DbSchemas - Schema "file_vault" already exists. Skipping schema creation.
23:36:18.141 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.142 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.142 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.145 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.146 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.147 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.148 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.149 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.150 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.151 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.152 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource
23:36:18.152 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'getMetaData'
23:36:18.152 [main] DEBUG c.g.f.c.dbsupport.DbSupportFactory - Database: H2 1.3
23:36:18.153 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.157 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.157 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.158 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.159 [main] INFO c.g.f.c.m.MetaDataTableImpl - Creating Metadata table: "file_vault"."filevault_schema_version"
23:36:18.166 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 17: CREATE TABLE "file_vault"."filevault_schema_version" (
"version_rank" INT NOT NULL,
"installed_rank" INT NOT NULL,
"version" VARCHAR(50) NOT NULL,
"description" VARCHAR(200) NOT NULL,
"type" VARCHAR(20) NOT NULL,
"script" VARCHAR(1000) NOT NULL,
"checksum" INT,
"installed_by" VARCHAR(100) NOT NULL,
"installed_on" TIMESTAMP NOT NULL DEFAULT CURRENT_TIMESTAMP,
"execution_time" INT NOT NULL,
"success" BOOLEAN NOT NULL
)
23:36:18.166 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 30: ALTER TABLE "file_vault"."filevault_schema_version" ADD CONSTRAINT "filevault_schema_version_pk" PRIMARY KEY ("version")
23:36:18.167 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 32: CREATE INDEX "file_vault"."filevault_schema_version_vr_idx" ON "file_vault"."filevault_schema_version" ("version_rank")
23:36:18.167 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 33: CREATE INDEX "file_vault"."filevault_schema_version_ir_idx" ON "file_vault"."filevault_schema_version" ("installed_rank")
23:36:18.167 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 34: CREATE INDEX "file_vault"."filevault_schema_version_s_idx" ON "file_vault"."filevault_schema_version" ("success")
23:36:18.167 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE "file_vault"."filevault_schema_version" (
"version_rank" INT NOT NULL,
"installed_rank" INT NOT NULL,
"version" VARCHAR(50) NOT NULL,
"description" VARCHAR(200) NOT NULL,
"type" VARCHAR(20) NOT NULL,
"script" VARCHAR(1000) NOT NULL,
"checksum" INT,
"installed_by" VARCHAR(100) NOT NULL,
"installed_on" TIMESTAMP NOT NULL DEFAULT CURRENT_TIMESTAMP,
"execution_time" INT NOT NULL,
"success" BOOLEAN NOT NULL
)
23:36:18.168 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.168 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: ALTER TABLE "file_vault"."filevault_schema_version" ADD CONSTRAINT "filevault_schema_version_pk" PRIMARY KEY ("version")
23:36:18.168 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.169 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX "file_vault"."filevault_schema_version_vr_idx" ON "file_vault"."filevault_schema_version" ("version_rank")
23:36:18.169 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.169 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX "file_vault"."filevault_schema_version_ir_idx" ON "file_vault"."filevault_schema_version" ("installed_rank")
23:36:18.169 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.169 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX "file_vault"."filevault_schema_version_s_idx" ON "file_vault"."filevault_schema_version" ("success")
23:36:18.169 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.169 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - Metadata table "file_vault"."filevault_schema_version" created.
23:36:18.169 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "file_vault"."filevault_schema_version"...
23:36:18.170 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.171 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "file_vault"."filevault_schema_version"
23:36:18.177 [main] DEBUG c.g.flyway.core.util.FeatureDetector - Spring Jdbc available: true
23:36:18.181 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classpath resources at 'db/migration/filevault/h2' (Prefix: 'V', Suffix: '.sql')
23:36:18.181 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2
23:36:18.181 [main] DEBUG c.g.flyway.core.util.FeatureDetector - JBoss VFS v2 available: false
23:36:18.183 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/
23:36:18.183 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2 (db/migration/filevault/h2)
23:36:18.184 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/filevault/h2/V1__add_metadata_table.sql
23:36:18.184 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/filevault/h2/V2__add_association_table.sql
23:36:18.184 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/filevault/h2/V3__add_tag_table.sql
23:36:18.187 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/filevault/h2' (Implementing: 'com.googlecode.flyway.core.api.migration.jdbc.JdbcMigration')
23:36:18.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2
23:36:18.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/
23:36:18.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2 (db/migration/filevault/h2)
23:36:18.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V1__add_metadata_table.sql (filename: V1__add_metadata_table.sql)
23:36:18.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V2__add_association_table.sql (filename: V2__add_association_table.sql)
23:36:18.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V3__add_tag_table.sql (filename: V3__add_tag_table.sql)
23:36:18.189 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/filevault/h2' (Implementing: 'com.googlecode.flyway.core.api.migration.spring.SpringJdbcMigration')
23:36:18.189 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2
23:36:18.189 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/
23:36:18.189 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2 (db/migration/filevault/h2)
23:36:18.190 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V1__add_metadata_table.sql (filename: V1__add_metadata_table.sql)
23:36:18.190 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V2__add_association_table.sql (filename: V2__add_association_table.sql)
23:36:18.190 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V3__add_tag_table.sql (filename: V3__add_tag_table.sql)
23:36:18.190 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/filevault/h2' (Implementing: 'com.googlecode.flyway.core.migration.java.JavaMigration')
23:36:18.190 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2
23:36:18.190 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/
23:36:18.190 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: /Users/bmanes/projects/addepar/AMP/domain/service/filevault/bin/db/migration/filevault/h2 (db/migration/filevault/h2)
23:36:18.191 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V1__add_metadata_table.sql (filename: V1__add_metadata_table.sql)
23:36:18.191 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V2__add_association_table.sql (filename: V2__add_association_table.sql)
23:36:18.191 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/filevault/h2/V3__add_tag_table.sql (filename: V3__add_tag_table.sql)
23:36:18.191 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.192 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.193 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.194 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.196 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.197 [main] DEBUG c.g.f.c.m.MetaDataTableTo20FormatUpgrader - No upgrade to the Flyway 2.0 format necessary for metadata table "file_vault"."filevault_schema_version"
23:36:18.198 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.199 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.200 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.206 [main] DEBUG c.g.f.c.m.MetaDataTableTo202FormatUpgrader - No metadata table upgrade to the Flyway 2.0.2 format necessary
23:36:18.207 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.209 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.210 [main] DEBUG c.g.f.c.m.MetaDataTableTo21FormatUpgrader - No metadata table upgrade to the Flyway 2.1 format necessary
23:36:18.212 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.215 [main] INFO c.g.flyway.core.command.DbMigrate - Current version of schema "file_vault": << Empty Schema >>
23:36:18.215 [main] INFO c.g.flyway.core.command.DbMigrate - Migrating schema "file_vault" to version 1
23:36:18.216 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.220 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 5: CREATE TABLE `file_metadata` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`blob_key` CHAR(36) NOT NULL COMMENT 'the unique blob key',
`creator_id` INT NOT NULL COMMENT 'the id of the user who created the file',
`name` VARCHAR(255) NOT NULL COMMENT 'the display name of the file',
`path` VARCHAR(255) NOT NULL COMMENT 'the path which represents a location in a folder tree',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`modified_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.225 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 16: CREATE TABLE `file_metadata_archive` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`blob_key` CHAR(36) NOT NULL COMMENT 'the unique blob key',
`creator_id` INT NOT NULL COMMENT 'the id of the user who created the file',
`name` VARCHAR(255) NOT NULL COMMENT 'the display name of the file',
`path` VARCHAR(255) NOT NULL COMMENT 'the path which represents a location in a folder tree',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`deleted_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.225 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 27: CREATE INDEX firm_id_idx ON file_metadata (firm_id)
23:36:18.226 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 28: CREATE UNIQUE INDEX blob_key_idx ON file_metadata (blob_key)
23:36:18.226 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE `file_metadata` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`blob_key` CHAR(36) NOT NULL COMMENT 'the unique blob key',
`creator_id` INT NOT NULL COMMENT 'the id of the user who created the file',
`name` VARCHAR(255) NOT NULL COMMENT 'the display name of the file',
`path` VARCHAR(255) NOT NULL COMMENT 'the path which represents a location in a folder tree',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`modified_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.226 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.230 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE `file_metadata_archive` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`blob_key` CHAR(36) NOT NULL COMMENT 'the unique blob key',
`creator_id` INT NOT NULL COMMENT 'the id of the user who created the file',
`name` VARCHAR(255) NOT NULL COMMENT 'the display name of the file',
`path` VARCHAR(255) NOT NULL COMMENT 'the path which represents a location in a folder tree',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`deleted_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.230 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.231 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX firm_id_idx ON file_metadata (firm_id)
23:36:18.231 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.231 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE UNIQUE INDEX blob_key_idx ON file_metadata (blob_key)
23:36:18.231 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.232 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.232 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.232 [main] DEBUG c.g.flyway.core.command.DbMigrate - Successfully completed and committed migration of schema "file_vault" to version 1
23:36:18.233 [main] DEBUG c.g.flyway.core.command.DbMigrate - Finished migrating schema "file_vault" to version 1 (execution time 00:00.017s)
23:36:18.233 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.234 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.235 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.235 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.237 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.237 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.240 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - MetaData table "file_vault"."filevault_schema_version" successfully updated to reflect changes
23:36:18.240 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.240 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.240 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.241 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.242 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "file_vault"."filevault_schema_version"...
23:36:18.242 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "file_vault"."filevault_schema_version"
23:36:18.242 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.242 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.242 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.243 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "file_vault"."filevault_schema_version"...
23:36:18.243 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.243 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "file_vault"."filevault_schema_version"
23:36:18.243 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.244 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.245 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.245 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.246 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.248 [main] INFO c.g.flyway.core.command.DbMigrate - Migrating schema "file_vault" to version 2
23:36:18.248 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.250 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 5: CREATE TABLE `file_association_type` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`name` VARCHAR(255) NOT NULL COMMENT 'the name of the association type',
UNIQUE(`name`)
)
23:36:18.251 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 11: INSERT INTO `file_association_type` (`name`) VALUES
('shared_with_node'),
('linked_to_node'),
('transaction')
23:36:18.255 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 16: CREATE TABLE `file_association` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the id of the file entry',
`association_type_id` INT NOT NULL COMMENT 'the id of the association type',
`association_entity_id` INT NOT NULL COMMENT 'the id of the entity association with',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`modified_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified',
FOREIGN KEY (`file_metadata_id`) REFERENCES `file_metadata`(`id`),
FOREIGN KEY (`association_type_id`) REFERENCES `file_association_type`(id)
)
23:36:18.259 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 28: CREATE TABLE `file_association_archive` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the id of the file entry',
`association_type_id` INT NOT NULL COMMENT 'the id of the association type',
`association_entity_id` INT NOT NULL COMMENT 'the id of the entity association with',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`deleted_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.259 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE `file_association_type` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`name` VARCHAR(255) NOT NULL COMMENT 'the name of the association type',
UNIQUE(`name`)
)
23:36:18.259 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.260 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO `file_association_type` (`name`) VALUES
('shared_with_node'),
('linked_to_node'),
('transaction')
23:36:18.260 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.261 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE `file_association` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the id of the file entry',
`association_type_id` INT NOT NULL COMMENT 'the id of the association type',
`association_entity_id` INT NOT NULL COMMENT 'the id of the entity association with',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`modified_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified',
FOREIGN KEY (`file_metadata_id`) REFERENCES `file_metadata`(`id`),
FOREIGN KEY (`association_type_id`) REFERENCES `file_association_type`(id)
)
23:36:18.261 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.267 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE `file_association_archive` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the id of the file entry',
`association_type_id` INT NOT NULL COMMENT 'the id of the association type',
`association_entity_id` INT NOT NULL COMMENT 'the id of the entity association with',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`deleted_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.267 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.267 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.267 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.267 [main] DEBUG c.g.flyway.core.command.DbMigrate - Successfully completed and committed migration of schema "file_vault" to version 2
23:36:18.268 [main] DEBUG c.g.flyway.core.command.DbMigrate - Finished migrating schema "file_vault" to version 2 (execution time 00:00.019s)
23:36:18.268 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.268 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.269 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.269 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.269 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.269 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.270 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - MetaData table "file_vault"."filevault_schema_version" successfully updated to reflect changes
23:36:18.270 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.270 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.270 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.270 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.271 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "file_vault"."filevault_schema_version"...
23:36:18.271 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "file_vault"."filevault_schema_version"
23:36:18.271 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.271 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.272 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.272 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "file_vault"."filevault_schema_version"...
23:36:18.272 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.272 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "file_vault"."filevault_schema_version"
23:36:18.272 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.273 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.274 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.274 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.275 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.277 [main] INFO c.g.flyway.core.command.DbMigrate - Migrating schema "file_vault" to version 3
23:36:18.277 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.280 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 5: CREATE TABLE `file_tag` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`text` VARCHAR(255) NOT NULL COMMENT 'the string representation of the tag',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`modified_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified',
FOREIGN KEY (`file_metadata_id`) REFERENCES `file_metadata`(`id`)
)
23:36:18.282 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 15: CREATE TABLE `file_tag_archive` (
`id` INT PRIMARY KEY,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`text` VARCHAR(255) NOT NULL COMMENT 'the string representation of the tag',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`deleted_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.282 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE `file_tag` (
`id` INT PRIMARY KEY AUTO_INCREMENT,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`text` VARCHAR(255) NOT NULL COMMENT 'the string representation of the tag',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`modified_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified',
FOREIGN KEY (`file_metadata_id`) REFERENCES `file_metadata`(`id`)
)
23:36:18.282 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.284 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE `file_tag_archive` (
`id` INT PRIMARY KEY,
`firm_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`file_metadata_id` INT NOT NULL COMMENT 'the firm that the file belongs to',
`text` VARCHAR(255) NOT NULL COMMENT 'the string representation of the tag',
`created_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was created',
`deleted_at` TIMESTAMP NOT NULL COMMENT 'the instant when the record was last modified'
)
23:36:18.284 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.284 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.284 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.284 [main] DEBUG c.g.flyway.core.command.DbMigrate - Successfully completed and committed migration of schema "file_vault" to version 3
23:36:18.284 [main] DEBUG c.g.flyway.core.command.DbMigrate - Finished migrating schema "file_vault" to version 3 (execution time 00:00.007s)
23:36:18.284 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.285 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.285 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.286 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.286 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.286 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.286 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - MetaData table "file_vault"."filevault_schema_version" successfully updated to reflect changes
23:36:18.286 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.287 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.287 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.287 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.288 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "file_vault"."filevault_schema_version"...
23:36:18.288 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "file_vault"."filevault_schema_version"
23:36:18.288 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.288 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.288 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.289 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "file_vault"."filevault_schema_version"...
23:36:18.289 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.289 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "file_vault"."filevault_schema_version"
23:36:18.289 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.290 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.290 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.291 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.291 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'createStatement'
23:36:18.293 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.293 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.294 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.294 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'getMetaData'
23:36:18.295 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "file_vault"."filevault_schema_version"...
23:36:18.295 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "file_vault"."filevault_schema_version"
23:36:18.295 [main] INFO c.g.flyway.core.command.DbMigrate - Successfully applied 3 migrations to schema "file_vault" (execution time 00:00.141s).
23:36:18.295 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.295 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.295 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.295 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
[TestNG] Running:
/private/var/folders/vp/0ps79b1j7v51m2mtw2jf7xqh0000gn/T/testng-eclipse-117379413/testng-customsuite.xml
23:36:18.590 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.591 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.593 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.599 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : insert into file_metadata (blob_key, creator_id, firm_id, name, path, modified_at, created_at) values (cast(? as char), cast(? as int), cast(? as int), cast(? as varchar), cast(? as varchar), cast(? as timestamp), cast(? as timestamp))
23:36:18.599 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : insert into file_metadata (blob_key, creator_id, firm_id, name, path, modified_at, created_at) values ('113a84b4-93e4-46c9-9456-1dbbf7772ada', 200, 101, 'file', 'a/b/c', timestamp '2013-06-10 06:36:18.425', timestamp '2013-06-10 06:36:18.425')
23:36:18.601 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.603 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 14.466ms
23:36:18.628 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : select file_metadata.id, file_metadata.firm_id, file_metadata.blob_key, file_metadata.creator_id, file_metadata.name, file_metadata.path, file_metadata.created_at, file_metadata.modified_at from file_metadata where file_metadata.id in (cast(? as int))
23:36:18.628 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : select file_metadata.id, file_metadata.firm_id, file_metadata.blob_key, file_metadata.creator_id, file_metadata.name, file_metadata.path, file_metadata.created_at, file_metadata.modified_at from file_metadata where file_metadata.id in (1)
23:36:18.628 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.629 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 2.389ms
23:36:18.642 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.643 [main] DEBUG org.jooq.tools.LoggerListener - Fetched result : +----+-------+------------------------------------+----------+----+-----+------------------------+------------------------+
23:36:18.643 [main] DEBUG org.jooq.tools.LoggerListener - : | ID|FIRM_ID|BLOB_KEY |CREATOR_ID|NAME|PATH |CREATED_AT |MODIFIED_AT |
23:36:18.643 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+------------------------------------+----------+----+-----+------------------------+------------------------+
23:36:18.643 [main] DEBUG org.jooq.tools.LoggerListener - : | 1| 101|113a84b4-93e4-46c9-9456-1dbbf7772ada| 200|file|a/b/c|2013-06-10T06:36:18.425Z|2013-06-10T06:36:18.425Z|
23:36:18.643 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+------------------------------------+----------+----+-----+------------------------+------------------------+
23:36:18.643 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.643 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 16.473ms, +14.084ms
23:36:18.644 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.644 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 55.671ms, +41.205ms
23:36:18.645 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.645 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.645 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn4: url=jdbc:h2:mem:test_1 user=]
23:36:18.645 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.645 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.645 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.645 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.645 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn4: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.645 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.645 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.646 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.646 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.646 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.646 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : insert into file_tag (file_metadata_id, firm_id, text, created_at, modified_at) values (cast(? as int), cast(? as int), cast(? as varchar), cast(? as timestamp), cast(? as timestamp))
23:36:18.646 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : insert into file_tag (file_metadata_id, firm_id, text, created_at, modified_at) values (1, 101, 'tag', timestamp '2013-06-10 06:36:18.425', timestamp '2013-06-10 06:36:18.425')
23:36:18.646 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.647 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.672ms
23:36:18.648 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : select file_tag.id, file_tag.firm_id, file_tag.file_metadata_id, file_tag.text, file_tag.created_at, file_tag.modified_at from file_tag where file_tag.id in (cast(? as int))
23:36:18.648 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : select file_tag.id, file_tag.firm_id, file_tag.file_metadata_id, file_tag.text, file_tag.created_at, file_tag.modified_at from file_tag where file_tag.id in (1)
23:36:18.648 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.648 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 0.9ms
23:36:18.649 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.649 [main] DEBUG org.jooq.tools.LoggerListener - Fetched result : +----+-------+----------------+----+------------------------+------------------------+
23:36:18.649 [main] DEBUG org.jooq.tools.LoggerListener - : | ID|FIRM_ID|FILE_METADATA_ID|TEXT|CREATED_AT |MODIFIED_AT |
23:36:18.649 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+----+------------------------+------------------------+
23:36:18.649 [main] DEBUG org.jooq.tools.LoggerListener - : | 1| 101| 1|tag |2013-06-10T06:36:18.425Z|2013-06-10T06:36:18.425Z|
23:36:18.649 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+----+------------------------+------------------------+
23:36:18.649 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.649 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 1.826ms, +0.926ms
23:36:18.650 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.650 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 4.388ms, +2.716ms
23:36:18.650 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.650 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.650 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn5: url=jdbc:h2:mem:test_1 user=]
23:36:18.650 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.650 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.650 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.650 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.650 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn5: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.650 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.650 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.651 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.651 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.651 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.651 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : insert into file_association (file_metadata_id, firm_id, association_type_id, association_entity_id, created_at, modified_at) values (cast(? as int), cast(? as int), cast(? as int), cast(? as int), cast(? as timestamp), cast(? as timestamp))
23:36:18.652 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : insert into file_association (file_metadata_id, firm_id, association_type_id, association_entity_id, created_at, modified_at) values (1, 101, 1, 300, timestamp '2013-06-10 06:36:18.425', timestamp '2013-06-10 06:36:18.425')
23:36:18.652 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.652 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.614ms
23:36:18.653 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : select file_association.id, file_association.firm_id, file_association.file_metadata_id, file_association.association_type_id, file_association.association_entity_id, file_association.created_at, file_association.modified_at from file_association where file_association.id in (cast(? as int))
23:36:18.654 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : select file_association.id, file_association.firm_id, file_association.file_metadata_id, file_association.association_type_id, file_association.association_entity_id, file_association.created_at, file_association.modified_at from file_association where file_association.id in (1)
23:36:18.654 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.654 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.162ms
23:36:18.654 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.655 [main] DEBUG org.jooq.tools.LoggerListener - Fetched result : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.655 [main] DEBUG org.jooq.tools.LoggerListener - : | ID|FIRM_ID|FILE_METADATA_ID|ASSOCIATION_TYPE_ID|ASSOCIATION_ENTITY_ID|CREATED_AT |MODIFIED_AT |
23:36:18.655 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.655 [main] DEBUG org.jooq.tools.LoggerListener - : | 1| 101| 1| 1| 300|2013-06-10T06:36:18.425Z|2013-06-10T06:36:18.425Z|
23:36:18.655 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.655 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.655 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 2.111ms, +0.949ms
23:36:18.655 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.655 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 4.507ms, +2.893ms
23:36:18.655 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.655 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.655 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn6: url=jdbc:h2:mem:test_1 user=]
23:36:18.655 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.656 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.656 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.656 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.656 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn6: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.656 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.656 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.656 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.656 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.656 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.657 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : insert into file_association (file_metadata_id, firm_id, association_type_id, association_entity_id, created_at, modified_at) values (cast(? as int), cast(? as int), cast(? as int), cast(? as int), cast(? as timestamp), cast(? as timestamp))
23:36:18.657 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : insert into file_association (file_metadata_id, firm_id, association_type_id, association_entity_id, created_at, modified_at) values (1, 101, 2, 400, timestamp '2013-06-10 06:36:18.425', timestamp '2013-06-10 06:36:18.425')
23:36:18.657 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.658 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.563ms
23:36:18.659 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : select file_association.id, file_association.firm_id, file_association.file_metadata_id, file_association.association_type_id, file_association.association_entity_id, file_association.created_at, file_association.modified_at from file_association where file_association.id in (cast(? as int))
23:36:18.659 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : select file_association.id, file_association.firm_id, file_association.file_metadata_id, file_association.association_type_id, file_association.association_entity_id, file_association.created_at, file_association.modified_at from file_association where file_association.id in (2)
23:36:18.659 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.659 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.385ms
23:36:18.660 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.660 [main] DEBUG org.jooq.tools.LoggerListener - Fetched result : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.660 [main] DEBUG org.jooq.tools.LoggerListener - : | ID|FIRM_ID|FILE_METADATA_ID|ASSOCIATION_TYPE_ID|ASSOCIATION_ENTITY_ID|CREATED_AT |MODIFIED_AT |
23:36:18.660 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.660 [main] DEBUG org.jooq.tools.LoggerListener - : | 2| 101| 1| 2| 400|2013-06-10T06:36:18.425Z|2013-06-10T06:36:18.425Z|
23:36:18.660 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.660 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.660 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 2.344ms, +0.959ms
23:36:18.661 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.661 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 4.614ms, +3.051ms
23:36:18.661 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.661 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.661 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn7: url=jdbc:h2:mem:test_1 user=]
23:36:18.661 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.661 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.661 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.661 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.661 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn7: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.661 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.661 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.662 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.662 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.662 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.662 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : insert into file_association (file_metadata_id, firm_id, association_type_id, association_entity_id, created_at, modified_at) values (cast(? as int), cast(? as int), cast(? as int), cast(? as int), cast(? as timestamp), cast(? as timestamp))
23:36:18.662 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : insert into file_association (file_metadata_id, firm_id, association_type_id, association_entity_id, created_at, modified_at) values (1, 101, 3, 500, timestamp '2013-06-10 06:36:18.425', timestamp '2013-06-10 06:36:18.425')
23:36:18.662 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.663 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.534ms
23:36:18.664 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : select file_association.id, file_association.firm_id, file_association.file_metadata_id, file_association.association_type_id, file_association.association_entity_id, file_association.created_at, file_association.modified_at from file_association where file_association.id in (cast(? as int))
23:36:18.664 [main] DEBUG org.jooq.tools.LoggerListener - -> with bind values : select file_association.id, file_association.firm_id, file_association.file_metadata_id, file_association.association_type_id, file_association.association_entity_id, file_association.created_at, file_association.modified_at from file_association where file_association.id in (3)
23:36:18.664 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'prepareStatement'
23:36:18.664 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.168ms
23:36:18.665 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.665 [main] DEBUG org.jooq.tools.LoggerListener - Fetched result : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.665 [main] DEBUG org.jooq.tools.LoggerListener - : | ID|FIRM_ID|FILE_METADATA_ID|ASSOCIATION_TYPE_ID|ASSOCIATION_ENTITY_ID|CREATED_AT |MODIFIED_AT |
23:36:18.665 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.665 [main] DEBUG org.jooq.tools.LoggerListener - : | 3| 101| 1| 3| 500|2013-06-10T06:36:18.425Z|2013-06-10T06:36:18.425Z|
23:36:18.665 [main] DEBUG org.jooq.tools.LoggerListener - : +----+-------+----------------+-------------------+---------------------+------------------------+------------------------+
23:36:18.665 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.665 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 2.126ms, +0.958ms
23:36:18.666 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.666 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 4.348ms, +2.814ms
23:36:18.666 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.666 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.666 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn8: url=jdbc:h2:mem:test_1 user=]
23:36:18.666 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.666 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.666 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.666 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.666 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn8: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.666 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.666 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.677 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.677 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.677 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.677 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : select file_association_type.id, file_association_type.name from file_association_type
23:36:18.677 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.678 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.139ms
23:36:18.678 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.679 [main] DEBUG org.jooq.tools.LoggerListener - Fetched result : +----+----------------+
23:36:18.679 [main] DEBUG org.jooq.tools.LoggerListener - : | ID|NAME |
23:36:18.679 [main] DEBUG org.jooq.tools.LoggerListener - : +----+----------------+
23:36:18.679 [main] DEBUG org.jooq.tools.LoggerListener - : | 1|shared_with_node|
23:36:18.679 [main] DEBUG org.jooq.tools.LoggerListener - : | 2|linked_to_node |
23:36:18.679 [main] DEBUG org.jooq.tools.LoggerListener - : | 3|transaction |
23:36:18.679 [main] DEBUG org.jooq.tools.LoggerListener - : +----+----------------+
23:36:18.679 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.679 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 2.322ms, +1.183ms
23:36:18.679 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.679 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.679 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn9: url=jdbc:h2:mem:test_1 user=]
23:36:18.679 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.679 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.679 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.679 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.679 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn9: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.679 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.679 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.684 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.685 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.685 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.685 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : truncate table file_tag
23:36:18.685 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.686 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 1.153ms
23:36:18.686 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.686 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 1.275ms, +0.122ms
23:36:18.686 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.686 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.686 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn10: url=jdbc:h2:mem:test_1 user=]
23:36:18.686 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.686 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.686 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.686 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.686 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn10: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.686 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.686 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.686 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.686 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.686 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.686 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : truncate table file_association
23:36:18.686 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.687 [main] DEBUG org.jooq.tools.StopWatch - Query executed : Total: 0.527ms
23:36:18.687 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.687 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 0.642ms, +0.115ms
23:36:18.687 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction commit
23:36:18.687 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.687 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Committing JDBC transaction on Connection [conn11: url=jdbc:h2:mem:test_1 user=]
23:36:18.687 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'commit'
23:36:18.687 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.687 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.687 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.687 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn11: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.687 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.687 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
23:36:18.687 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
23:36:18.688 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Acquired Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] for JDBC transaction
23:36:18.688 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Switching JDBC Connection [Lazy Connection proxy for target DataSource [org.springframework.jdbc.datasource.DelegatingDataSource@228917a2]] to manual commit
23:36:18.688 [main] DEBUG org.jooq.tools.LoggerListener - Executing query : truncate table file_metadata
23:36:18.688 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Connecting to database for operation 'prepareStatement'
23:36:18.693 [main] DEBUG org.jooq.tools.StopWatch - Exception : Total: 5.6ms
23:36:18.693 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.693 [main] DEBUG org.jooq.tools.StopWatch - Finishing : Total: 5.834ms, +0.234ms
23:36:18.693 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Initiating transaction rollback
23:36:18.693 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.693 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Rolling back JDBC transaction on Connection [conn12: url=jdbc:h2:mem:test_1 user=]
23:36:18.694 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'rollback'
23:36:18.694 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'setAutoCommit'
23:36:18.694 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'isReadOnly'
23:36:18.694 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'toString'
23:36:18.694 [main] DEBUG o.s.j.d.DataSourceTransactionManager - Releasing JDBC Connection [conn12: url=jdbc:h2:mem:test_1 user=] after transaction
23:36:18.694 [main] DEBUG o.s.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
23:36:18.694 [main] DEBUG o.s.j.d.LazyConnectionDataSourceProxy - Using existing database connection for operation 'close'
FAILED CONFIGURATION: @AfterMethod afterMethod
org.jooq.exception.DataAccessException: SQL [truncate table file_metadata]; Cannot truncate """file_vault"".FILE_METADATA"; SQL statement:
truncate table file_metadata [90106-167]
at org.jooq.impl.Utils.translate(Utils.java:1021)
at org.jooq.impl.DefaultExecuteContext.sqlException(DefaultExecuteContext.java:462)
at org.jooq.impl.AbstractQuery.execute(AbstractQuery.java:287)
at com.addepar.domain.service.filevault.FileVaultServiceTest.afterMethod(FileVaultServiceTest.java:92)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:84)
at org.testng.internal.Invoker.invokeConfigurationMethod(Invoker.java:564)
at org.testng.internal.Invoker.invokeConfigurations(Invoker.java:213)
at org.testng.internal.Invoker.invokeMethod(Invoker.java:786)
at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:901)
at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1231)
at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127)
at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)
at org.testng.TestRunner.privateRun(TestRunner.java:767)
at org.testng.TestRunner.run(TestRunner.java:617)
at org.testng.SuiteRunner.runTest(SuiteRunner.java:334)
at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:329)
at org.testng.SuiteRunner.privateRun(SuiteRunner.java:291)
at org.testng.SuiteRunner.run(SuiteRunner.java:240)
at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
at org.testng.TestNG.runSuitesSequentially(TestNG.java:1224)
at org.testng.TestNG.runSuitesLocally(TestNG.java:1149)
at org.testng.TestNG.run(TestNG.java:1057)
at org.testng.remote.RemoteTestNG.run(RemoteTestNG.java:111)
at org.testng.remote.RemoteTestNG.initAndRun(RemoteTestNG.java:204)
at org.testng.remote.RemoteTestNG.main(RemoteTestNG.java:175)
Caused by: org.h2.jdbc.JdbcSQLException: Cannot truncate """file_vault"".FILE_METADATA"; SQL statement:
truncate table file_metadata [90106-167]
at org.h2.message.DbException.getJdbcSQLException(DbException.java:329)
at org.h2.message.DbException.get(DbException.java:169)
at org.h2.message.DbException.get(DbException.java:146)
at org.h2.command.ddl.TruncateTable.update(TruncateTable.java:35)
at org.h2.command.CommandContainer.update(CommandContainer.java:75)
at org.h2.command.Command.executeUpdate(Command.java:230)
at org.h2.jdbc.JdbcPreparedStatement.execute(JdbcPreparedStatement.java:194)
at org.jooq.tools.jdbc.DefaultPreparedStatement.execute(DefaultPreparedStatement.java:189)
at org.jooq.impl.AbstractQuery.execute(AbstractQuery.java:337)
at org.jooq.impl.AbstractQuery.execute(AbstractQuery.java:283)
... 27 more
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
SKIPPED CONFIGURATION: @BeforeMethod beforeMethod
SKIPPED CONFIGURATION: @AfterMethod afterMethod
PASSED: associationType
SKIPPED: create
SKIPPED: delete_found
SKIPPED: delete_notFound
SKIPPED: getAllForFinancialTransaction_found
SKIPPED: getAllForFinancialTransaction_noFiles
SKIPPED: getAllForFirm_found
SKIPPED: getAllForFirm_noFiles
SKIPPED: getAllForLinkedGraphNode_found
SKIPPED: getAllForLinkedGraphNode_noFiles
SKIPPED: getAllForSharedGraphNode_found
SKIPPED: getAllForSharedGraphNode_noFiles
SKIPPED: getById_found
SKIPPED: getById_notFound
SKIPPED: update_data
SKIPPED: update_data_notFound
SKIPPED: update_entry
SKIPPED: update_entry_notFound
===============================================
Default test
Tests run: 18, Failures: 0, Skips: 17
Configuration Failures: 1, Skips: 34
===============================================
===============================================
Default suite
Total tests run: 18, Failures: 0, Skips: 17
Configuration Failures: 1, Skips: 34
===============================================
[TestNG] Time taken by org.testng.reporters.SuiteHTMLReporter@77f297e7: 13 ms
[TestNG] Time taken by [FailedReporter passed=0 failed=0 skipped=0]: 7 ms
[TestNG] Time taken by org.testng.reporters.XMLReporter@3b6f0be8: 23 ms
[TestNG] Time taken by org.testng.reporters.JUnitReportReporter@56d73c7a: 13 ms
[TestNG] Time taken by org.testng.reporters.EmailableReporter2@16f8f7db: 9 ms
[TestNG] Time taken by org.testng.reporters.jq.Main@3b75078b: 40 ms
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment