See
http://hudson.qa.jboss.com/hudson/job/drools/2646/
------------------------------------------
[...truncated 41136 lines...]
INFO 25-02 23:55:23,283 (ServiceImplementation.java:createNewRule:226)
USER:alan_parsons CREATING new asset name [sc1] in package [testScenarioRun]
DEBUG 25-02 23:55:23,312 (ServiceImplementation.java:loadRuleAsset:484) Load time taken
for asset: 7
INFO 25-02 23:55:23,313 (ServiceImplementation.java:checkinVersion:593)
USER:alan_parsons CHECKING IN asset: [sc1] UUID: [0ab13874-1f01-4d75-a144-f0c8db4f5e7d]
ARCHIVED [false]
DEBUG 25-02 23:55:23,350 (ServiceImplementation.java:loadRuleAsset:484) Load time taken
for asset: 5
DEBUG 25-02 23:55:23,464 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunWithGeneratedBeans/assets[%]/%'
and drools:format='jar' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,480 (PackageItem.java:queryAssets:450) QueryExec time is: 15
DEBUG 25-02 23:55:23,480 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,481 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunWithGeneratedBeans/assets[%]/%'
and drools:format='jar' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,490 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:23,490 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,491 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunWithGeneratedBeans/assets[%]/%'
and ( drools:format='properties' OR drools:format='conf' ) AND
drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:23,500 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:23,501 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,506 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunWithGeneratedBeans/assets[%]/%'
and drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,514 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:23,515 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,527 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunWithGeneratedBeans/assets[%]/%'
and drools:format='dsl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,536 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:23,537 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,537 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunWithGeneratedBeans/assets[%]/%'
and drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,546 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:23,546 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,547 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunWithGeneratedBeans/assets[%]/%'
and drools:format='drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,558 (PackageItem.java:queryAssets:450) QueryExec time is: 10
DEBUG 25-02 23:55:23,559 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,918 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and drools:format='jar' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,953 (PackageItem.java:queryAssets:450) QueryExec time is: 33
DEBUG 25-02 23:55:23,954 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,955 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and drools:format='jar' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,962 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:23,963 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,964 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and ( drools:format='properties' OR drools:format='conf' ) AND
drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:23,971 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:23,972 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,978 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:23,985 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:23,986 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:23,998 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and drools:format='dsl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,006 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:24,006 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,007 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,015 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:24,015 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,016 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and drools:format='drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,025 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:24,025 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,157 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulkWithDeclaredFacts/assets[%]/%'
and drools:format='scenario' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,167 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:24,167 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,337 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,351 (PackageItem.java:queryAssets:450) QueryExec time is: 12
DEBUG 25-02 23:55:24,351 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,353 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,361 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:24,361 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,362 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and (
drools:format='properties' OR drools:format='conf' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,370 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:24,371 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,377 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and
drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,384 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:24,385 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,386 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,394 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:24,395 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,396 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and
drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,403 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:24,404 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,405 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and
drools:format='drl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,472 (PackageItem.java:queryAssets:450) QueryExec time is: 66
DEBUG 25-02 23:55:24,473 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
42
42
DEBUG 25-02 23:55:24,509 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testRunScenarioWithJar/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,520 (PackageItem.java:queryAssets:450) QueryExec time is: 10
DEBUG 25-02 23:55:24,522 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
42
DEBUG 25-02 23:55:24,884 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,906 (PackageItem.java:queryAssets:450) QueryExec time is: 20
DEBUG 25-02 23:55:24,907 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,908 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,919 (PackageItem.java:queryAssets:450) QueryExec time is: 10
DEBUG 25-02 23:55:24,920 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,921 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and (
drools:format='properties' OR drools:format='conf' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,929 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:24,930 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,935 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and
drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,943 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:24,944 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,945 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,953 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:24,954 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,955 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and
drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:24,963 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:24,963 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:24,964 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and
drools:format='drl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:24,974 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:24,975 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:25,103 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testScenarioRunBulk/assets[%]/%' and
drools:format='scenario' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:25,115 (PackageItem.java:queryAssets:450) QueryExec time is: 11
DEBUG 25-02 23:55:25,116 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
Time taken for runScenariosInPackage 259
DEBUG 25-02 23:55:25,219 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testVerifier/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:25,234 (PackageItem.java:queryAssets:450) QueryExec time is: 14
DEBUG 25-02 23:55:25,234 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:25,235 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testVerifier/assets[%]/%' and (
drools:format='properties' OR drools:format='conf' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:25,243 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:25,244 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:25,249 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testVerifier/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:25,257 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:25,258 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:25,259 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testVerifier/assets[%]/%' and (
drools:format='function' OR drools:format='model.drl' ) AND
drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:25,267 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:25,267 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:33,565 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testAvailableTypes/assets[%]/%' and (
drools:format='jar' OR drools:format='model.drl' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:33,579 (PackageItem.java:queryAssets:450) QueryExec time is: 13
DEBUG 25-02 23:55:33,579 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:33,612 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testAvailableTypes/assets[%]/%' and (
drools:format='jar' OR drools:format='model.drl' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:33,623 (PackageItem.java:queryAssets:450) QueryExec time is: 10
DEBUG 25-02 23:55:33,624 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
INFO 25-02 23:55:33,627 (ServiceImplementation.java:createCategory:196)
USER:alan_parsons CREATING cateogory: [decisiontables] in path [/]
INFO 25-02 23:55:33,817 (ServiceImplementation.java:createNewRule:226)
USER:alan_parsons CREATING new asset name [decTable] in package [testGuidedDTCompile]
DEBUG 25-02 23:55:33,841 (ServiceImplementation.java:loadRuleAsset:484) Load time taken
for asset: 1
INFO 25-02 23:55:33,841 (ServiceImplementation.java:checkinVersion:593)
USER:alan_parsons CHECKING IN asset: [decTable] UUID:
[7ffe69b5-82fa-4241-a2d6-7b6296817d0a] ARCHIVED [false]
DEBUG 25-02 23:55:33,871 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testGuidedDTCompile/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:33,885 (PackageItem.java:queryAssets:450) QueryExec time is: 13
DEBUG 25-02 23:55:33,886 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:33,887 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testGuidedDTCompile/assets[%]/%' and (
drools:format='properties' OR drools:format='conf' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:33,895 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:33,896 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:33,902 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testGuidedDTCompile/assets[%]/%' and
drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:33,910 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:33,911 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:33,912 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testGuidedDTCompile/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:33,921 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:33,921 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:33,922 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testGuidedDTCompile/assets[%]/%' and
drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:33,930 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:33,931 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:33,931 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/testGuidedDTCompile/assets[%]/%' and
drools:format='drl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:33,942 (PackageItem.java:queryAssets:450) QueryExec time is: 10
DEBUG 25-02 23:55:33,943 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
{}
INFO 25-02 23:55:34,048 (ServiceImplementation.java:updateUserPermissions:2194)
Updating user permissions for userName [googoo] to [{}]
DEBUG 25-02 23:55:34,056 (RulesRepositoryAdministrator.java:clearRulesRepository:63)
Clearing repository database. UserId=alan_parsons
Clearing rules repository
DEBUG 25-02 23:55:38,261 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/defaultPackage/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,309 (PackageItem.java:queryAssets:450) QueryExec time is: 47
DEBUG 25-02 23:55:38,310 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,311 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/defaultPackage/assets[%]/%' and (
drools:format='properties' OR drools:format='conf' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,319 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:38,320 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,326 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/defaultPackage/assets[%]/%' and
drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:38,335 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:38,336 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,337 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/defaultPackage/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,346 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:38,347 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,347 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/defaultPackage/assets[%]/%' and
drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:38,355 (PackageItem.java:queryAssets:450) QueryExec time is: 6
DEBUG 25-02 23:55:38,356 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,356 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/defaultPackage/assets[%]/%' and
drools:format='drl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,365 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:38,366 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,378 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,389 (PackageItem.java:queryAssets:450) QueryExec time is: 10
DEBUG 25-02 23:55:38,390 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,391 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and (
drools:format='properties' OR drools:format='conf' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,399 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:38,400 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,405 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and
drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:38,415 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:38,415 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,456 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,466 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:38,466 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,472 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and
drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:38,480 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:38,480 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,481 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and
drools:format='drl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,490 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:38,490 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,743 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/TEST/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,755 (PackageItem.java:queryAssets:450) QueryExec time is: 11
DEBUG 25-02 23:55:38,755 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,756 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/TEST/assets[%]/%' and (
drools:format='properties' OR drools:format='conf' ) AND drools:archive =
'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,766 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:38,766 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,772 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/TEST/assets[%]/%' and
drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:38,782 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:38,783 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,817 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/TEST/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,827 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:38,828 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,829 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/TEST/assets[%]/%' and
drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:38,837 (PackageItem.java:queryAssets:450) QueryExec time is: 7
DEBUG 25-02 23:55:38,837 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,838 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/TEST/assets[%]/%' and
drools:format='drl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,848 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:38,849 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,982 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/ANOTHER/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:38,994 (PackageItem.java:queryAssets:450) QueryExec time is: 11
DEBUG 25-02 23:55:38,995 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:38,995 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/ANOTHER/assets[%]/%' and
( drools:format='properties' OR drools:format='conf' ) AND
drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:39,005 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:39,006 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:39,011 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/ANOTHER/assets[%]/%' and
drools:format='model.drl' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:39,021 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:39,022 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:39,056 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/ANOTHER/assets[%]/%' and
drools:format='dsl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:39,066 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:39,067 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:39,067 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/ANOTHER/assets[%]/%' and
drools:format='function' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:39,076 (PackageItem.java:queryAssets:450) QueryExec time is: 8
DEBUG 25-02 23:55:39,076 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:39,077 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:packagesnapshot_area/mortgages/ANOTHER/assets[%]/%' and
drools:format='drl' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:39,087 (PackageItem.java:queryAssets:450) QueryExec time is: 9
DEBUG 25-02 23:55:39,087 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:39,218 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and
drools:format='jar' AND drools:archive = 'false' ORDER BY drools:title
DEBUG 25-02 23:55:39,230 (PackageItem.java:queryAssets:450) QueryExec time is: 11
DEBUG 25-02 23:55:39,231 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
DEBUG 25-02 23:55:39,302 (PackageItem.java:queryAssets:444) SQL is SELECT * FROM
drools:assetNodeType WHERE jcr:path LIKE
'/drools:repository/drools:package_area/mortgages/assets[%]/%' and
drools:format='scenario' AND drools:archive = 'false' ORDER BY
drools:title
DEBUG 25-02 23:55:39,313 (PackageItem.java:queryAssets:450) QueryExec time is: 10
DEBUG 25-02 23:55:39,313 (PackageItem.java:queryAssets:452)
org.apache.jackrabbit.core.query.lucene.QueryResultImpl$LazyScoreNodeIterator
Tests run: 55, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 40.205 sec
Results :
Tests in error:
testSimplePackageWithDeclaredTypes(org.drools.guvnor.server.builder.ContentPackageAssemblerTest)
Tests run: 223, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[ERROR] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] There are test failures.
Please refer to
/qa/services/hudson/hudson_workspace/workspace/drools/trunk/drools-guvnor/target/surefire-reports
for the individual test results.
[INFO] ------------------------------------------------------------------------
[INFO] For more information, run Maven with the -e switch
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 17 minutes 27 seconds
[INFO] Finished at: Wed Feb 25 23:55:39 EST 2009
[INFO] Final Memory: 57M/1003M
[INFO] ------------------------------------------------------------------------
Terminating xvnc
[drools] $ vncserver -kill :13
Killing Xvnc process ID 15291
Recording test results