Skip to content

Instantly share code, notes, and snippets.

@yulgit1
Created June 4, 2014 22:03
Show Gist options
  • Save yulgit1/356c66e7c3dacad4af3a to your computer and use it in GitHub Desktop.
Save yulgit1/356c66e7c3dacad4af3a to your computer and use it in GitHub Desktop.
indexer-pluggable
[INFO] Scanning for projects...
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Build Order:
[INFO]
[INFO] fcrepo-jms-indexer-pluggable
[INFO] fcrepo-jms-indexer-core
[INFO] fcrepo-jms-indexer-webapp
[INFO] fcrepo-jms-indexer-elasticsearch
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building fcrepo-jms-indexer-pluggable 4.0.0-alpha-6-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ fcrepo-jms-indexer-pluggable ---
[INFO] Deleting F:\ermadmix\git\fcrepo-jms-indexer-pluggable\target
[INFO]
[INFO] --- maven-enforcer-plugin:1.3:enforce (enforce-maven) @ fcrepo-jms-indexer-pluggable ---
[INFO]
[INFO] --- maven-enforcer-plugin:1.3:enforce (default) @ fcrepo-jms-indexer-pluggable ---
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:prepare-agent (default-prepare-agent) @ fcrepo-jms-indexer-pluggable ---
[INFO] Skipping JaCoCo for project with packaging type 'pom'
[INFO]
[INFO] >>> maven-source-plugin:2.2.1:jar (attach-sources) @ fcrepo-jms-indexer-pluggable >>>
[INFO]
[INFO] --- maven-enforcer-plugin:1.3:enforce (enforce-maven) @ fcrepo-jms-indexer-pluggable ---
[INFO]
[INFO] --- maven-enforcer-plugin:1.3:enforce (default) @ fcrepo-jms-indexer-pluggable ---
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:prepare-agent (default-prepare-agent) @ fcrepo-jms-indexer-pluggable ---
[INFO] Skipping JaCoCo for project with packaging type 'pom'
[INFO]
[INFO] <<< maven-source-plugin:2.2.1:jar (attach-sources) @ fcrepo-jms-indexer-pluggable <<<
[INFO]
[INFO] --- maven-source-plugin:2.2.1:jar (attach-sources) @ fcrepo-jms-indexer-pluggable ---
[INFO]
[INFO] --- maven-javadoc-plugin:2.9.1:jar (attach-javadocs) @ fcrepo-jms-indexer-pluggable ---
[INFO] Not executing Javadoc as the project is not a Java classpath-capable package
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:prepare-agent-integration (default-prepare-agent-integration) @ fcrepo-jms-indexer-pluggable ---
[INFO] Skipping JaCoCo for project with packaging type 'pom'
[INFO]
[INFO] --- maven-failsafe-plugin:2.16:integration-test (integration-test) @ fcrepo-jms-indexer-pluggable ---
[INFO] No tests to run.
[INFO]
[INFO] --- maven-failsafe-plugin:2.16:integration-test (default-integration-test) @ fcrepo-jms-indexer-pluggable ---
[INFO] No tests to run.
[INFO] Skipping execution of surefire because it has already been run for this configuration
[INFO]
[INFO] --- license-maven-plugin:2.6:check (default) @ fcrepo-jms-indexer-pluggable ---
[INFO] Checking licenses...
[INFO]
[INFO] --- maven-checkstyle-plugin:2.12.1:check (checkstyle) @ fcrepo-jms-indexer-pluggable ---
[INFO] Starting audit...
Audit done.
[INFO]
[INFO] --- maven-failsafe-plugin:2.16:verify (verify) @ fcrepo-jms-indexer-pluggable ---
[INFO] No tests to run.
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:report (default-report) @ fcrepo-jms-indexer-pluggable ---
[INFO] Skipping JaCoCo for project with packaging type 'pom'
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:report-integration (default-report-integration) @ fcrepo-jms-indexer-pluggable ---
[INFO] Skipping JaCoCo for project with packaging type 'pom'
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ fcrepo-jms-indexer-pluggable ---
[INFO] Installing F:\ermadmix\git\fcrepo-jms-indexer-pluggable\pom.xml to F:\ermadmix\.m2\repository\org\fcrepo\fcrepo-jms-indexer-pluggable\4.0.0-alpha-6-SNAPSHOT\fcrepo-jms-indexer-pluggable-4.0.0-alpha-6-SNAPSHOT.pom
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building fcrepo-jms-indexer-core 4.0.0-alpha-6-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ fcrepo-jms-indexer-core ---
[INFO] Deleting F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target
[INFO]
[INFO] --- maven-enforcer-plugin:1.2:enforce (enforce-maven) @ fcrepo-jms-indexer-core ---
[INFO]
[INFO] --- maven-enforcer-plugin:1.2:enforce (default) @ fcrepo-jms-indexer-core ---
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:prepare-agent (default-prepare-agent) @ fcrepo-jms-indexer-core ---
[INFO] jacoco.agent.unit.arg set to -javaagent:F:\\ermadmix\\.m2\\repository\\org\\jacoco\\org.jacoco.agent\\0.6.4.201312101107\\org.jacoco.agent-0.6.4.201312101107-runtime.jar=destfile=F:\\ermadmix\\git\\fcrepo-jms-indexer-pluggable\\fcrepo-jms-indexer-core\\target\\jacoco-ut.exec,append=true
[INFO]
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ fcrepo-jms-indexer-core ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 1 resource
[INFO]
[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ fcrepo-jms-indexer-core ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 13 source files to F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\classes
[WARNING] /F:/ermadmix/git/fcrepo-jms-indexer-pluggable/fcrepo-jms-indexer-core/src/main/java/org/fcrepo/indexer/IndexerGroup.java: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\IndexerGroup.java uses or overrides a deprecated API.
[WARNING] /F:/ermadmix/git/fcrepo-jms-indexer-pluggable/fcrepo-jms-indexer-core/src/main/java/org/fcrepo/indexer/IndexerGroup.java: Recompile with -Xlint:deprecation for details.
[INFO]
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ fcrepo-jms-indexer-core ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 19 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ fcrepo-jms-indexer-core ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 16 source files to F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes
[WARNING] /F:/ermadmix/git/fcrepo-jms-indexer-pluggable/fcrepo-jms-indexer-core/src/test/java/org/fcrepo/indexer/FusekiContainerWrapper.java: Some input files use or override a deprecated API.
[WARNING] /F:/ermadmix/git/fcrepo-jms-indexer-pluggable/fcrepo-jms-indexer-core/src/test/java/org/fcrepo/indexer/FusekiContainerWrapper.java: Recompile with -Xlint:deprecation for details.
[INFO]
[INFO] --- maven-surefire-plugin:2.16:test (default-test) @ fcrepo-jms-indexer-core ---
[INFO] Surefire report directory: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\surefire-reports
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.fcrepo.indexer.FileSerializerTest
DEBUG 17:00:53.173 (AsynchIndexer) Received update for identifier: testBadId/
DEBUG 17:00:53.266 (AsynchIndexer) Received update for identifier: updateTest8cc0ef5c-c06b-4512-8406-48dc85a22d1f
DEBUG 17:00:53.266 (FileSerializer) Updating to file: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\.\target\fileSerializer.170053\updateTest8cc0ef5c-c06b-4512-8406-48dc85a22d1f@20140604170053
DEBUG 17:00:53.274 (AsynchIndexer) Issued task to execution pool for identifier: updateTest8cc0ef5c-c06b-4512-8406-48dc85a22d1f
DEBUG 17:00:53.274 (FileSerializerTest) Got filename: updateTest8cc0ef5c-c06b-4512-8406-48dc85a22d1f@20140604170053
DEBUG 17:00:53.277 (AsynchIndexer) Received remove for identifier: removeTest16ca142d-9d78-437b-9715-c0f4f5ed42be
DEBUG 17:00:53.277 (FileSerializer) Received remove for identifier: removeTest16ca142d-9d78-437b-9715-c0f4f5ed42be
DEBUG 17:00:53.277 (FileSerializer) Updating to file: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\.\target\fileSerializer.170053\removeTest16ca142d-9d78-437b-9715-c0f4f5ed42be@20140604170053
DEBUG 17:00:53.278 (FileSerializerTest) Got filename: removeTest16ca142d-9d78-437b-9715-c0f4f5ed42be@20140604170053
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.613 sec - in org.fcrepo.indexer.FileSerializerTest
Running org.fcrepo.indexer.IndexerGroupTest
DEBUG 17:00:54.629 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@3449440d
DEBUG 17:00:54.724 (IndexerGroup) Received message: mocked-message-id
DEBUG 17:00:54.724 (IndexerGroup) Discovered id: /test1 in message.
DEBUG 17:00:54.724 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED in message.
DEBUG 17:00:54.725 (IndexerGroup) Discovered baseURL: http://example.org:80 in message.
DEBUG 17:00:54.725 (IndexerGroup) Discovered properties: null in message.
DEBUG 17:00:54.725 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:00:54.867 (RdfRetriever) Retrieving RDF content from: http://example.org:80/test1...
WARN 17:00:55.549 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80/test1> Code: 13/DEFAULT_PORT_SHOULD_BE_OMITTED in PORT: If the port is the default one for the scheme it should be omitted.
WARN 17:00:55.549 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80/test1> Code: 14/PORT_SHOULD_NOT_BE_WELL_KNOWN in PORT: Ports under 1024 should be accessed using the appropriate scheme name.
DEBUG 17:00:55.573 (IndexerGroup) Resource: http://example.org:80/test1 retrieved without indexable type.
DEBUG 17:00:55.573 (IndexerGroup) Operating for indexer: indexer
DEBUG 17:00:55.591 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@238b1d64
DEBUG 17:00:55.607 (IndexerGroup) Received message: mocked-message-id
DEBUG 17:00:55.607 (IndexerGroup) Discovered id: /test2 in message.
DEBUG 17:00:55.607 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED in message.
DEBUG 17:00:55.607 (IndexerGroup) Discovered baseURL: http://example.org:80 in message.
DEBUG 17:00:55.607 (IndexerGroup) Discovered properties: null in message.
DEBUG 17:00:55.607 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:00:55.608 (RdfRetriever) Retrieving RDF content from: http://example.org:80/test2...
WARN 17:00:55.609 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80/test2> Code: 13/DEFAULT_PORT_SHOULD_BE_OMITTED in PORT: If the port is the default one for the scheme it should be omitted.
WARN 17:00:55.609 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80/test2> Code: 14/PORT_SHOULD_NOT_BE_WELL_KNOWN in PORT: Ports under 1024 should be accessed using the appropriate scheme name.
DEBUG 17:00:55.611 (IndexerGroup) Resource: http://example.org:80/test2 retrieved with indexable type.
DEBUG 17:00:55.611 (IndexerGroup) Operating for indexer: indexer
DEBUG 17:00:55.612 (IndexerGroup) Retrieving named fields for: http://example.org:80/test2, (may be cached) to index to indexer...
DEBUG 17:00:55.612 (NamedFieldsRetriever) Retrieving RDF representation for: http://example.org:80/test2
DEBUG 17:00:55.626 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:00:55.626 (NamedFieldsRetriever) Retrieving transformed resource from: http://example.org:80/test2/fcr:transform/default
DEBUG 17:00:55.630 (IndexerGroup) Executing update of: http://example.org:80/test2 to indexer: indexer...
DEBUG 17:00:55.638 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@1f7ae17e
DEBUG 17:00:55.649 (IndexerGroup) Received message: mocked-message-id
DEBUG 17:00:55.649 (IndexerGroup) Discovered id: /test3 in message.
DEBUG 17:00:55.649 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#PROPERTY_CHANGED in message.
DEBUG 17:00:55.650 (IndexerGroup) Discovered baseURL: http://example.org:80 in message.
DEBUG 17:00:55.650 (IndexerGroup) Discovered properties: null in message.
DEBUG 17:00:55.650 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:00:55.650 (RdfRetriever) Retrieving RDF content from: http://example.org:80/test3...
WARN 17:00:55.651 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80/test3> Code: 13/DEFAULT_PORT_SHOULD_BE_OMITTED in PORT: If the port is the default one for the scheme it should be omitted.
WARN 17:00:55.651 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80/test3> Code: 14/PORT_SHOULD_NOT_BE_WELL_KNOWN in PORT: Ports under 1024 should be accessed using the appropriate scheme name.
DEBUG 17:00:55.653 (IndexerGroup) Resource: http://example.org:80/test3 retrieved with indexable type.
DEBUG 17:00:55.653 (IndexerGroup) Operating for indexer: indexer
DEBUG 17:00:55.653 (IndexerGroup) Retrieving RDF for: http://example.org:80/test3, (may be cached) to index to indexer...
DEBUG 17:00:55.653 (IndexerGroup) Executing update of: http://example.org:80/test3 to indexer: indexer...
DEBUG 17:00:55.656 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@57b15f3
DEBUG 17:00:55.664 (IndexerGroup) Reindexing http://example.org:80, recursive: true
DEBUG 17:00:55.664 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:00:55.664 (RdfRetriever) Retrieving RDF content from: http://example.org:80...
WARN 17:00:55.665 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80> Code: 13/DEFAULT_PORT_SHOULD_BE_OMITTED in PORT: If the port is the default one for the scheme it should be omitted.
WARN 17:00:55.665 (riot) [line: 2, col: 1 ] Bad IRI: <http://example.org:80> Code: 14/PORT_SHOULD_NOT_BE_WELL_KNOWN in PORT: Ports under 1024 should be accessed using the appropriate scheme name.
DEBUG 17:00:55.666 (IndexerGroup) Resource: http://example.org:80 retrieved with indexable type.
DEBUG 17:00:55.667 (IndexerGroup) Operating for indexer: indexer
DEBUG 17:00:55.667 (IndexerGroup) Retrieving RDF for: http://example.org:80, (may be cached) to index to indexer...
DEBUG 17:00:55.667 (IndexerGroup) Executing update of: http://example.org:80 to indexer: indexer...
DEBUG 17:00:55.667 (RdfRetriever) Retrieving RDF content from: http://example.org:80...
DEBUG 17:00:55.700 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@4e65768a
ERROR 17:00:55.711 (IndexerGroup) Received unintelligible message: {}
$javax.jms.JMSException$$EnhancerByMockitoWithCGLIB$$9cf8bdfe: null
DEBUG 17:00:55.716 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@5473e9b5
DEBUG 17:00:55.845 (IndexerGroup) Adding BASIC credentials to client for repo requests.
DEBUG 17:00:55.854 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@631e1d43
DEBUG 17:00:55.860 (IndexerGroup) Adding BASIC credentials to client for repo requests.
DEBUG 17:00:55.860 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@4e134c8b
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.572 sec - in org.fcrepo.indexer.IndexerGroupTest
Running org.fcrepo.indexer.NamedFieldsDeserializerTest
DEBUG 17:00:55.863 (NamedFieldsDeserializerTest) Using fake JSON: {"id" : "testUri"}
ERROR 17:00:55.885 (NamedFieldsDeserializer) Failed to parse JSON to Map<String, Collection<String>>!
java.lang.IllegalStateException: This is not a JSON Array.
at com.google.gson.JsonElement.getAsJsonArray(JsonElement.java:106) ~[gson-2.2.4.jar:na]
at org.fcrepo.indexer.NamedFieldsDeserializer$2.apply(NamedFieldsDeserializer.java:66) ~[classes/:na]
at org.fcrepo.indexer.NamedFieldsDeserializer$2.apply(NamedFieldsDeserializer.java:59) ~[classes/:na]
at com.google.common.collect.Maps$7.transformEntry(Maps.java:1812) ~[guava-17.0.jar:na]
at com.google.common.collect.Maps$10.getValue(Maps.java:1857) ~[guava-17.0.jar:na]
at java.util.HashMap.putAllForCreate(HashMap.java:534) ~[na:1.7.0_25]
at java.util.HashMap.<init>(HashMap.java:320) ~[na:1.7.0_25]
at org.fcrepo.indexer.NamedFields.<init>(NamedFields.java:37) ~[classes/:na]
at org.fcrepo.indexer.NamedFieldsDeserializer.read(NamedFieldsDeserializer.java:84) ~[classes/:na]
at org.fcrepo.indexer.NamedFieldsDeserializerTest.testReadBadJson(NamedFieldsDeserializerTest.java:65) ~[test-classes/:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.7.0_25]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.7.0_25]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_25]
at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_25]
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) ~[junit-4.11.jar:na]
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) ~[junit-4.11.jar:na]
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) ~[junit-4.11.jar:na]
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) ~[junit-4.11.jar:na]
at org.junit.internal.runners.statements.ExpectException.evaluate(ExpectException.java:19) ~[junit-4.11.jar:na]
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) ~[junit-4.11.jar:na]
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) ~[junit-4.11.jar:na]
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) ~[junit-4.11.jar:na]
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) ~[junit-4.11.jar:na]
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) ~[junit-4.11.jar:na]
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) ~[junit-4.11.jar:na]
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) ~[junit-4.11.jar:na]
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) ~[junit-4.11.jar:na]
at org.junit.runners.ParentRunner.run(ParentRunner.java:309) ~[junit-4.11.jar:na]
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) ~[surefire-junit4-2.16.jar:2.16]
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) ~[surefire-junit4-2.16.jar:2.16]
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) ~[surefire-junit4-2.16.jar:2.16]
at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) ~[surefire-booter-2.16.jar:2.16]
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) ~[surefire-booter-2.16.jar:2.16]
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) ~[surefire-booter-2.16.jar:2.16]
DEBUG 17:00:55.886 (NamedFieldsDeserializerTest) Using fake JSON: {"id" : ["testUri"]}
DEBUG 17:00:55.887 (NamedFieldsDeserializerTest) Received results: {id=[testUri]}
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.023 sec - in org.fcrepo.indexer.NamedFieldsDeserializerTest
Running org.fcrepo.indexer.NamedFieldsRetrieverTest
DEBUG 17:00:55.946 (NamedFieldsRetrieverTest) Using test RDF: @prefix dc:<http://purl.org/dc/elements/1.1/> .
@prefix indexing:<http://fedora.info/definitions/v4/indexing#>.
@prefix rdf:<http://www.w3.org/1999/02/22-rdf-syntax-ns#>.
<indexing:testBadTransform> dc:title "Easy Microwave Meals For Ghouls" ;
dc:creator "Yubulac Xorhorisa" ;
dc:subject <http://id.loc.gov/authorities/subjects/sh2012004374> ;
rdf:type <indexing:indexable> ;
indexing:hasIndexingTransformation "dc".
DEBUG 17:00:56.003 (NamedFieldsRetriever) Retrieving RDF representation for: indexing:testBadTransform
DEBUG 17:00:56.005 (NamedFieldsRetriever) Discovered transform key: dc
DEBUG 17:00:56.006 (NamedFieldsRetriever) Retrieving transformed resource from: indexing:testBadTransform/fcr:transform/dc
DEBUG 17:00:56.010 (NamedFieldsRetriever) Retrieving RDF representation for: http://example.com/testShouldntRetrieve
INFO 17:00:56.010 (NamedFieldsRetriever) Found no property locating LDPath transform for: http://example.com/testShouldntRetrieve, will not retrieve transformed content.
DEBUG 17:00:56.012 (NamedFieldsRetrieverTest) Using test RDF: @prefix dc:<http://purl.org/dc/elements/1.1/> .
@prefix indexing:<http://fedora.info/definitions/v4/indexing#>.
@prefix rdf:<http://www.w3.org/1999/02/22-rdf-syntax-ns#>.
<indexing:testBadTransform> dc:title "Easy Microwave Meals For Ghouls" ;
dc:creator "Yubulac Xorhorisa" ;
dc:subject <http://id.loc.gov/authorities/subjects/sh2012004374> ;
rdf:type <indexing:indexable> ;
indexing:hasIndexingTransformation "dc".
DEBUG 17:00:56.014 (NamedFieldsRetrieverTest) Using fake JSON: {"id" : ["indexing:testBadTransform"]}
DEBUG 17:00:56.014 (NamedFieldsRetriever) Retrieving RDF representation for: indexing:testBadTransform
DEBUG 17:00:56.014 (NamedFieldsRetriever) Discovered transform key: dc
DEBUG 17:00:56.014 (NamedFieldsRetriever) Retrieving transformed resource from: indexing:testBadTransform/fcr:transform/dc
DEBUG 17:00:56.015 (NamedFieldsRetrieverTest) Received results: {id=[indexing:testBadTransform]}
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.123 sec - in org.fcrepo.indexer.NamedFieldsRetrieverTest
Running org.fcrepo.indexer.RdfRetrieverTest
DEBUG 17:00:56.019 (RdfRetriever) Retrieving RDF content from: testAuthForbiddenRetrieval...
DEBUG 17:00:56.020 (RdfRetriever) Retrieving RDF content from: testFailedRetrieval...
DEBUG 17:00:56.022 (RdfRetriever) Retrieving RDF content from: testFailedRetrieval...
DEBUG 17:00:56.046 (RdfRetriever) Retrieving RDF content from: testAuthRetrieval...
DEBUG 17:00:56.048 (RdfRetriever) Retrieving RDF content from: testFailedRetrieval...
DEBUG 17:00:56.050 (RdfRetriever) Retrieving RDF content from: testSimpleRetrieval...
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.032 sec - in org.fcrepo.indexer.RdfRetrieverTest
Running org.fcrepo.indexer.solr.SolrIndexerTest
DEBUG 17:00:57.909 (SolrIndexerTest) Using Solr home: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes\solr
DEBUG 17:00:57.932 (AsynchIndexer) Received update for identifier: testExplodingUpdate2:2eff0905-19de-481d-9e2d-96a9836b97ab
DEBUG 17:00:57.932 (SolrIndexer) Received request for update to: testExplodingUpdate2:2eff0905-19de-481d-9e2d-96a9836b97ab
DEBUG 17:00:57.933 (AsynchIndexer) Issued task to execution pool for identifier: testExplodingUpdate2:2eff0905-19de-481d-9e2d-96a9836b97ab
DEBUG 17:00:57.933 (SolrIndexer) Executing request to Solr index for identifier: testExplodingUpdate2:2eff0905-19de-481d-9e2d-96a9836b97ab with fields: {id=[testExplodingUpdate2:2eff0905-19de-481d-9e2d-96a9836b97ab]}
DEBUG 17:00:57.933 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:00:57.936 (SolrIndexer) Adding value: testExplodingUpdate2:2eff0905-19de-481d-9e2d-96a9836b97ab to field: id
DEBUG 17:00:57.936 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=testExplodingUpdate2:2eff0905-19de-481d-9e2d-96a9836b97ab])
ERROR 17:00:57.937 (SolrIndexer) Update exception: {}!
java.io.IOException: Expected.
at org.fcrepo.indexer.solr.SolrIndexer$1.call(SolrIndexer.java:100) [classes/:na]
at org.fcrepo.indexer.solr.SolrIndexer$1.call(SolrIndexer.java:86) [classes/:na]
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) ~[na:1.7.0_25]
at java.util.concurrent.FutureTask.run(FutureTask.java:166) ~[na:1.7.0_25]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) ~[na:1.7.0_25]
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) ~[na:1.7.0_25]
at java.util.concurrent.FutureTask.run(FutureTask.java:166) ~[na:1.7.0_25]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) ~[na:1.7.0_25]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) ~[na:1.7.0_25]
at java.lang.Thread.run(Thread.java:724) ~[na:1.7.0_25]
DEBUG 17:00:58.734 (SolrIndexerTest) Using Solr home: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes\solr
DEBUG 17:00:58.735 (AsynchIndexer) Received update for identifier: testExplodingUpdate:10d29056-608e-41c0-85f6-b26d985ea5c0
DEBUG 17:00:58.735 (SolrIndexer) Received request for update to: testExplodingUpdate:10d29056-608e-41c0-85f6-b26d985ea5c0
DEBUG 17:00:58.735 (AsynchIndexer) Issued task to execution pool for identifier: testExplodingUpdate:10d29056-608e-41c0-85f6-b26d985ea5c0
DEBUG 17:00:58.735 (SolrIndexer) Executing request to Solr index for identifier: testExplodingUpdate:10d29056-608e-41c0-85f6-b26d985ea5c0 with fields: {id=[testExplodingUpdate:10d29056-608e-41c0-85f6-b26d985ea5c0]}
DEBUG 17:00:58.735 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:00:58.735 (SolrIndexer) Adding value: testExplodingUpdate:10d29056-608e-41c0-85f6-b26d985ea5c0 to field: id
DEBUG 17:00:58.735 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=testExplodingUpdate:10d29056-608e-41c0-85f6-b26d985ea5c0])
ERROR 17:00:58.736 (SolrIndexer) Update exception: {}!
org.apache.solr.client.solrj.SolrServerException: Expected.
at org.fcrepo.indexer.solr.SolrIndexer$1.call(SolrIndexer.java:100) [classes/:na]
at org.fcrepo.indexer.solr.SolrIndexer$1.call(SolrIndexer.java:86) [classes/:na]
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) ~[na:1.7.0_25]
at java.util.concurrent.FutureTask.run(FutureTask.java:166) ~[na:1.7.0_25]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) ~[na:1.7.0_25]
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) ~[na:1.7.0_25]
at java.util.concurrent.FutureTask.run(FutureTask.java:166) ~[na:1.7.0_25]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) ~[na:1.7.0_25]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) ~[na:1.7.0_25]
at java.lang.Thread.run(Thread.java:724) ~[na:1.7.0_25]
DEBUG 17:00:59.442 (SolrIndexerTest) Using Solr home: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes\solr
DEBUG 17:00:59.443 (AsynchIndexer) Received update for identifier: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3
DEBUG 17:00:59.443 (SolrIndexer) Received request for update to: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3
DEBUG 17:00:59.443 (AsynchIndexer) Issued task to execution pool for identifier: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3
DEBUG 17:00:59.444 (SolrIndexer) Executing request to Solr index for identifier: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3 with fields: {id=[testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3]}
DEBUG 17:00:59.444 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:00:59.444 (SolrIndexer) Adding value: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3 to field: id
DEBUG 17:00:59.444 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3])
ERROR 17:00:59.444 (SolrIndexer) Update request returned error code: 1 for identifier: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3
DEBUG 17:00:59.444 (SolrIndexer) Received result from Solr request.
DEBUG 17:00:59.445 (AsynchIndexer) Received remove for identifier: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3
DEBUG 17:00:59.445 (SolrIndexer) Received request for removal of: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3
ERROR 17:00:59.448 (SolrIndexer) Remove request has error, code: 1 for pid: testBadUpdate:84d30ba1-c8a9-4082-9197-6d28b74d49c3
DEBUG 17:01:00.195 (SolrIndexerTest) Using Solr home: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes\solr
DEBUG 17:01:00.863 (SolrIndexerTest) Using Solr home: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes\solr
DEBUG 17:01:00.864 (SolrIndexerTest) Trying update operation with identifier: 123 and content: "{id=[123]}".
DEBUG 17:01:00.864 (AsynchIndexer) Received update for identifier: 123
DEBUG 17:01:00.864 (SolrIndexer) Received request for update to: 123
DEBUG 17:01:00.864 (AsynchIndexer) Issued task to execution pool for identifier: 123
DEBUG 17:01:00.864 (SolrIndexer) Executing request to Solr index for identifier: 123 with fields: {id=[123]}
DEBUG 17:01:00.864 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:01:00.864 (SolrIndexer) Adding value: 123 to field: id
DEBUG 17:01:00.864 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=123])
DEBUG 17:01:00.870 (SolrIndexer) Adding value: 123 to field: id
DEBUG 17:01:00.873 (SolrIndexerTest) Waiting for index record to appear...
DEBUG 17:01:00.972 (SolrIndexer) Update request was successful for: 123
DEBUG 17:01:00.972 (SolrIndexer) Received result from Solr request.
DEBUG 17:01:01.873 (SolrIndexerTest) Checking for presence of appropriate index record...
DEBUG 17:01:01.897 (AsynchIndexer) Received remove for identifier: 123
DEBUG 17:01:01.897 (SolrIndexer) Received request for removal of: 123
DEBUG 17:01:01.898 (SolrIndexerTest) Waiting for index record to appear...
DEBUG 17:01:01.899 (SolrIndexer) Remove request was successful for: 123
DEBUG 17:01:02.898 (SolrIndexerTest) Checking for presence of appropriate index record...
ERROR 17:01:03.569 (UpdateLog) Could not remove tlog file:data\tlog\tlog.0000000000000000000
DEBUG 17:01:03.574 (SolrIndexerTest) Using Solr home: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes\solr
DEBUG 17:01:03.575 (SolrIndexerTest) Trying update operation with identifier: 456 and content: "{id=[456]}".
DEBUG 17:01:03.575 (AsynchIndexer) Received update for identifier: 456
DEBUG 17:01:03.575 (SolrIndexer) Received request for update to: 456
DEBUG 17:01:03.575 (AsynchIndexer) Issued task to execution pool for identifier: 456
DEBUG 17:01:03.575 (SolrIndexer) Executing request to Solr index for identifier: 456 with fields: {id=[456]}
DEBUG 17:01:03.575 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:01:03.575 (SolrIndexer) Adding value: 456 to field: id
DEBUG 17:01:03.576 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=456])
DEBUG 17:01:03.576 (SolrIndexer) Adding value: 456 to field: id
DEBUG 17:01:03.576 (SolrIndexerTest) Waiting for index record to appear...
DEBUG 17:01:03.577 (SolrIndexer) Update request was successful for: 456
DEBUG 17:01:03.577 (SolrIndexer) Received result from Solr request.
DEBUG 17:01:04.576 (SolrIndexerTest) Checking for presence of appropriate index record...
ERROR 17:01:05.247 (UpdateLog) Could not remove tlog file:data\tlog\tlog.0000000000000000000
ERROR 17:01:05.247 (UpdateLog) Could not remove tlog file:data\tlog\tlog.0000000000000000001
DEBUG 17:01:05.253 (SolrIndexerTest) Using Solr home: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\test-classes\solr
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 10.2 sec - in org.fcrepo.indexer.solr.SolrIndexerTest
Running org.fcrepo.indexer.sparql.SparqlIndexerTest
DEBUG 17:01:06.309 (SparqlIndexer) Received update for:
DEBUG 17:01:06.309 (SparqlIndexer) Received remove for:
DEBUG 17:01:06.309 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:01:06.314 (SparqlIndexer) Sending update request for pid:
DEBUG 17:01:06.316 (SparqlIndexer) Received remove for: info://obj-0
DEBUG 17:01:06.317 (SparqlIndexer) Executing: DELETE WHERE { <info://obj-0/child> ?p ?o }
DEBUG 17:01:06.317 (SparqlIndexer) Executing: DELETE WHERE { <info://obj-0> ?p ?o }
DEBUG 17:01:06.317 (SparqlIndexer) Executing: DELETE WHERE { <info://obj-0/fcr:content> ?p ?o }
DEBUG 17:01:06.317 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:01:06.320 (SparqlIndexerTest) Received correct indexer type.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.061 sec - in org.fcrepo.indexer.sparql.SparqlIndexerTest
Results :
Tests run: 35, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ fcrepo-jms-indexer-core ---
[INFO] Building jar: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\fcrepo-jms-indexer-core-4.0.0-alpha-6-SNAPSHOT.jar
[INFO]
[INFO] >>> maven-source-plugin:2.2.1:jar (attach-sources) @ fcrepo-jms-indexer-core >>>
[INFO]
[INFO] --- maven-enforcer-plugin:1.2:enforce (enforce-maven) @ fcrepo-jms-indexer-core ---
[INFO]
[INFO] --- maven-enforcer-plugin:1.2:enforce (default) @ fcrepo-jms-indexer-core ---
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:prepare-agent (default-prepare-agent) @ fcrepo-jms-indexer-core ---
[INFO] jacoco.agent.unit.arg set to -javaagent:F:\\ermadmix\\.m2\\repository\\org\\jacoco\\org.jacoco.agent\\0.6.4.201312101107\\org.jacoco.agent-0.6.4.201312101107-runtime.jar=destfile=F:\\ermadmix\\git\\fcrepo-jms-indexer-pluggable\\fcrepo-jms-indexer-core\\target\\jacoco-ut.exec,append=true
[INFO]
[INFO] <<< maven-source-plugin:2.2.1:jar (attach-sources) @ fcrepo-jms-indexer-core <<<
[INFO]
[INFO] --- maven-source-plugin:2.2.1:jar (attach-sources) @ fcrepo-jms-indexer-core ---
[INFO] Building jar: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\fcrepo-jms-indexer-core-4.0.0-alpha-6-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-javadoc-plugin:2.9.1:jar (attach-javadocs) @ fcrepo-jms-indexer-core ---
[INFO]
Loading source files for package org.fcrepo.indexer...
Loading source files for package org.fcrepo.indexer.solr...
Loading source files for package org.fcrepo.indexer.sparql...
Constructing Javadoc information...
Standard Doclet version 1.7.0_25
Building tree for all the packages and classes...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\AbsentTransformPropertyException.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\AsynchIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\FileSerializer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\IndexableContentRetriever.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\Indexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\Indexer.IndexerType.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\Indexer.NoContent.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\IndexerGroup.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\NamedFields.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\NamedFieldsDeserializer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\NamedFieldsRetriever.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\RdfRetriever.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\SynchIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\solr\SolrIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\sparql\SparqlIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\overview-frame.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\package-frame.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\package-summary.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\package-tree.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\solr\package-frame.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\solr\package-summary.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\solr\package-tree.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\sparql\package-frame.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\sparql\package-summary.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\sparql\package-tree.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\constant-values.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\serialized-form.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\SynchIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\RdfRetriever.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\NamedFieldsRetriever.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\NamedFieldsDeserializer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\NamedFields.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\IndexerGroup.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\Indexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\Indexer.IndexerType.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\Indexer.NoContent.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\IndexableContentRetriever.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\FileSerializer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\AsynchIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\class-use\AbsentTransformPropertyException.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\solr\class-use\SolrIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\sparql\class-use\SparqlIndexer.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\package-use.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\solr\package-use.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\org\fcrepo\indexer\sparql\package-use.html...
Building index for all the packages and classes...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\overview-tree.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\index-all.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\deprecated-list.html...
Building index for all classes...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\allclasses-frame.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\allclasses-noframe.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\index.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\overview-summary.html...
Generating F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\apidocs\help-doc.html...
29 warnings
[WARNING] Javadoc Warnings
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\AbsentTransformPropertyException.java:26: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\AsynchIndexer.java:91: warning - @return tag has no arguments.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\AsynchIndexer.java:98: warning - @return tag has no arguments.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\AsynchIndexer.java:38: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\FileSerializer.java:68: warning - @return tag has no arguments.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\FileSerializer.java:77: warning - @return tag has no arguments.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\FileSerializer.java:43: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\IndexableContentRetriever.java:27: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\Indexer.java:34: warning - Missing closing '}' character for inline tag: "{@link SynchIndexer
[WARNING] are provided for convenience."
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\Indexer.java:34: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\Indexer.java:66: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\Indexer.java:76: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\IndexerGroup.java:64: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\NamedFields.java:29: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\NamedFieldsDeserializer.java:46: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\NamedFieldsRetriever.java:48: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\RdfRetriever.java:46: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @Indexer is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @Indexer is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\solr\SolrIndexer.java:52: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\solr\SolrIndexer.java:78: warning - @Autowired is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\sparql\SparqlIndexer.java:58: warning - @date is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\sparql\SparqlIndexer.java:80: warning - @content is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @Indexer is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @Indexer is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @Indexer is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @Indexer is an unknown tag.
[WARNING] F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\src\main\java\org\fcrepo\indexer\SynchIndexer.java:30: warning - @Indexer is an unknown tag.
[INFO] Building jar: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\fcrepo-jms-indexer-core-4.0.0-alpha-6-SNAPSHOT-javadoc.jar
[INFO]
[INFO] --- build-helper-maven-plugin:1.8:reserve-network-port (reserve-port) @ fcrepo-jms-indexer-core ---
[INFO] Reserved port 55207 for test.port
[INFO] Reserved port 55208 for test.mgt.port
[INFO] Reserved port 55209 for test.fuseki.port
[INFO]
[INFO] --- jacoco-maven-plugin:0.6.4.201312101107:prepare-agent-integration (default-prepare-agent-integration) @ fcrepo-jms-indexer-core ---
[INFO] jacoco.agent.it.arg set to -javaagent:F:\\ermadmix\\.m2\\repository\\org\\jacoco\\org.jacoco.agent\\0.6.4.201312101107\\org.jacoco.agent-0.6.4.201312101107-runtime.jar=destfile=F:\\ermadmix\\git\\fcrepo-jms-indexer-pluggable\\fcrepo-jms-indexer-core\\target\\jacoco-it.exec,append=true
[INFO]
[INFO] --- maven-failsafe-plugin:2.16:integration-test (integration-test) @ fcrepo-jms-indexer-core ---
[INFO] Failsafe report directory: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\failsafe-reports
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.fcrepo.indexer.integration.IndexerGroupIT
DEBUG 17:01:12.984 (FusekiContainerWrapper) starting Fuseki
WARN 17:01:13.568 (Config) No such directory for static content: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\.
WARN 17:01:13.569 (Config) You may need to set the --pages or --home option to configure static content correctly
WARN 17:01:13.690 (Server) No pages directory - ./pages
INFO 17:01:14.122 (DefaultPropertiesLoader) Loading properties
INFO 17:01:14.123 (DefaultPropertiesLoader) com.arjuna.ats.arjuna.common.ObjectStoreEnvironmentBean.default.objectStoreDir = F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target/object-store-default
INFO 17:01:14.123 (DefaultPropertiesLoader) com.arjuna.ats.arjuna.objectstore.objectStoreDir = F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target/object-store
INFO 17:01:14.123 (DefaultPropertiesLoader) fcrepo.ispn.CacheDirPath = null
INFO 17:01:14.123 (DefaultPropertiesLoader) fcrepo.ispn.binary.CacheDirPath = null
INFO 17:01:14.124 (DefaultPropertiesLoader) fcrepo.binary-store-path = null
INFO 17:01:14.124 (DefaultPropertiesLoader) fcrepo.modeshape.index.location = null
INFO 17:01:14.124 (DefaultPropertiesLoader) fcrepo.ispn.alternative.CacheDirPath = null
INFO 17:01:14.124 (DefaultPropertiesLoader) fcrepo.ispn.binary.alternative.CacheDirPath = null
INFO 17:01:14.124 (DefaultPropertiesLoader) fcrepo.ispn.repo.CacheDirPath = null
INFO 17:01:14.124 (DefaultPropertiesLoader) fcrepo.activemq.dir = null
17:01:14 INFO ISPN000128: Infinispan version: Infinispan 'Tactical Nuclear Penguin' 5.3.0.Final
17:01:14 WARN ISPN000104: Falling back to DummyTransactionManager from Infinispan
17:01:14 INFO ISPN000031: MBeans were successfully registered to the platform MBean server.
17:01:15 INFO ISPN000031: MBeans were successfully registered to the platform MBean server.
17:01:15 INFO ISPN000031: MBeans were successfully registered to the platform MBean server.
INFO 17:01:16.107 (SerializerUtil) Registering serializer org.fcrepo.serialization.JcrXmlSerializer@b23d07 for format jcr/xml
DEBUG 17:01:18.905 (IndexerGroup) Creating IndexerGroup: org.fcrepo.indexer.IndexerGroup@2bb5a5a7
DEBUG 17:01:18.980 (IndexingIT) Installing indexing namespace...
DEBUG 17:01:20.228 (IndexingIT) Installing indexing type information...
DEBUG 17:01:20.262 (IndexingIT) Installing indexing test type information...
DEBUG 17:01:20.454 (IndexerGroupIT) Created object at: http://localhost:55207/a1
DEBUG 17:01:20.454 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1, false
DEBUG 17:01:20.454 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:20.456 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:1
DEBUG 17:01:20.471 (IndexerGroup) Discovered id: /a1 in message.
DEBUG 17:01:20.471 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:01:20.471 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:01:20.471 (IndexerGroup) Discovered properties: indexing:hasIndexingTransformation,jcr:created,jcr:lastModified,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType in message.
DEBUG 17:01:20.471 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:01:20.523 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/a1...
DEBUG 17:01:20.580 (IndexerGroup) Resource: http://localhost:55207/a1 retrieved with indexable type.
DEBUG 17:01:20.580 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:01:20.581 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:01:20.581 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:20.581 (TestIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:20.583 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1]
DEBUG 17:01:20.583 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:20.583 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:01:20.583 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:20.583 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/a1
DEBUG 17:01:20.585 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:01:20.585 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/a1/fcr:transform/default
DEBUG 17:01:20.610 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:20.720 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:20.727 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:01:20.993 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:20.993 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:20.993 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\a1@20140604170120
DEBUG 17:01:20.994 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:20.994 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:01:20.994 (IndexerGroup) Retrieving RDF for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:20.995 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:20.995 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:20.995 (SparqlIndexer) Received update for: http://localhost:55207/a1
DEBUG 17:01:20.995 (SparqlIndexer) Received remove for: http://localhost:55207/a1
DEBUG 17:01:21.219 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:01:21.220 (SparqlIndexer) Sending update request for pid: http://localhost:55207/a1
DEBUG 17:01:21.221 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:21.221 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:01:21.221 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:01:21.221 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:01:21.221 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:21.221 (SolrIndexer) Received request for update to: http://localhost:55207/a1
DEBUG 17:01:21.222 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:21.222 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/a1 with fields: {id=[http://localhost:55207/a1], title=[], uuid=[eb4748cc-7a03-49aa-9097-9079d46ac1b7]}
DEBUG 17:01:21.222 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:01:21.224 (SolrIndexer) Adding value: http://localhost:55207/a1 to field: id
DEBUG 17:01:21.225 (SolrIndexer) Adding value: eb4748cc-7a03-49aa-9097-9079d46ac1b7 to field: uuid
DEBUG 17:01:21.225 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/a1, title=null, uuid=eb4748cc-7a03-49aa-9097-9079d46ac1b7])
DEBUG 17:01:21.230 (SolrIndexer) Adding value: http://localhost:55207/a1 to field: id
DEBUG 17:01:21.232 (SolrIndexer) Adding value: eb4748cc-7a03-49aa-9097-9079d46ac1b7 to field: uuid
DEBUG 17:01:21.261 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:01:21.309 (SolrIndexer) Update request was successful for: http://localhost:55207/a1
DEBUG 17:01:21.310 (SolrIndexer) Received result from Solr request.
DEBUG 17:01:21.454 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1, true
DEBUG 17:01:21.454 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1, true
DEBUG 17:01:21.454 (IndexerGroupIT) Received update at test indexer for identifier: http://localhost:55207/a1
DEBUG 17:01:21.500 (IndexerGroupIT) Created object at: http://localhost:55207/a1/b1
DEBUG 17:01:21.500 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b1, false
DEBUG 17:01:21.501 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:21.508 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:2
DEBUG 17:01:21.508 (IndexerGroup) Discovered id: /a1 in message.
DEBUG 17:01:21.508 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#PROPERTY_CHANGED in message.
DEBUG 17:01:21.508 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:01:21.508 (IndexerGroup) Discovered properties: jcr:lastModified,jcr:lastModifiedBy in message.
DEBUG 17:01:21.508 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:01:21.508 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/a1...
DEBUG 17:01:21.543 (IndexerGroup) Resource: http://localhost:55207/a1 retrieved with indexable type.
DEBUG 17:01:21.543 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:01:21.543 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:01:21.543 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:21.543 (TestIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:21.543 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1]
DEBUG 17:01:21.543 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:21.543 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:01:21.543 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:21.543 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/a1
DEBUG 17:01:21.544 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:01:21.544 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/a1/fcr:transform/default
DEBUG 17:01:21.593 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:21.625 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:21.626 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:01:21.631 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:21.632 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:21.632 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\a1@20140604170121
DEBUG 17:01:21.632 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:21.632 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:01:21.632 (IndexerGroup) Retrieving RDF for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:21.632 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:21.632 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:21.632 (SparqlIndexer) Received update for: http://localhost:55207/a1
DEBUG 17:01:21.632 (SparqlIndexer) Received remove for: http://localhost:55207/a1
DEBUG 17:01:21.671 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/a1/fcr:export?format=jcr/xml> ?p ?o }
DEBUG 17:01:21.672 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/a1> ?p ?o }
DEBUG 17:01:21.673 (SparqlIndexer) Sending update request for pid: http://localhost:55207/a1
DEBUG 17:01:21.673 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:21.673 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:01:21.673 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:01:21.673 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:01:21.673 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:21.673 (SolrIndexer) Received request for update to: http://localhost:55207/a1
DEBUG 17:01:21.673 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:21.674 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:3
DEBUG 17:01:21.674 (IndexerGroup) Discovered id: /a1/b1 in message.
DEBUG 17:01:21.674 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:01:21.674 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:01:21.674 (IndexerGroup) Discovered properties: indexing:hasIndexingTransformation,jcr:created,jcr:lastModified,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType in message.
DEBUG 17:01:21.674 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:01:21.674 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/a1/b1...
DEBUG 17:01:21.679 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/a1 with fields: {id=[http://localhost:55207/a1], title=[], uuid=[eb4748cc-7a03-49aa-9097-9079d46ac1b7]}
DEBUG 17:01:21.679 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:01:21.679 (SolrIndexer) Adding value: http://localhost:55207/a1 to field: id
DEBUG 17:01:21.679 (SolrIndexer) Adding value: eb4748cc-7a03-49aa-9097-9079d46ac1b7 to field: uuid
DEBUG 17:01:21.679 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/a1, title=null, uuid=eb4748cc-7a03-49aa-9097-9079d46ac1b7])
DEBUG 17:01:21.679 (SolrIndexer) Adding value: http://localhost:55207/a1 to field: id
DEBUG 17:01:21.679 (SolrIndexer) Adding value: eb4748cc-7a03-49aa-9097-9079d46ac1b7 to field: uuid
DEBUG 17:01:21.691 (SolrIndexer) Update request was successful for: http://localhost:55207/a1
DEBUG 17:01:21.691 (SolrIndexer) Received result from Solr request.
DEBUG 17:01:21.705 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:01:21.712 (IndexerGroup) Resource: http://localhost:55207/a1/b1 retrieved with indexable type.
DEBUG 17:01:21.712 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:01:21.712 (IndexerGroup) Executing update of: http://localhost:55207/a1/b1 to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:01:21.712 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.712 (TestIndexer) Received update for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.712 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1/b1, http://localhost:55207/a1]
DEBUG 17:01:21.712 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.712 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:01:21.712 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1/b1, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:21.712 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/a1/b1
DEBUG 17:01:21.712 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:01:21.713 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/a1/b1/fcr:transform/default
DEBUG 17:01:21.730 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:21.731 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:01:21.757 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:21.758 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:01:21.762 (IndexerGroup) Executing update of: http://localhost:55207/a1/b1 to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:21.762 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.762 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\b1@20140604170121
DEBUG 17:01:21.763 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.763 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:01:21.763 (IndexerGroup) Retrieving RDF for: http://localhost:55207/a1/b1, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:21.763 (IndexerGroup) Executing update of: http://localhost:55207/a1/b1 to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:21.763 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.763 (SparqlIndexer) Received update for: http://localhost:55207/a1/b1
DEBUG 17:01:21.763 (SparqlIndexer) Received remove for: http://localhost:55207/a1/b1
DEBUG 17:01:21.779 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/a1/b1> ?p ?o }
DEBUG 17:01:21.780 (SparqlIndexer) Sending update request for pid: http://localhost:55207/a1/b1
DEBUG 17:01:21.780 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.780 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:01:21.780 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1/b1, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:01:21.780 (IndexerGroup) Executing update of: http://localhost:55207/a1/b1 to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:01:21.780 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.780 (SolrIndexer) Received request for update to: http://localhost:55207/a1/b1
DEBUG 17:01:21.780 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:21.780 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/a1/b1 with fields: {id=[http://localhost:55207/a1/b1], title=[], uuid=[5279fea8-e913-4dfd-9946-1821d323b069]}
DEBUG 17:01:21.780 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:01:21.781 (SolrIndexer) Adding value: http://localhost:55207/a1/b1 to field: id
DEBUG 17:01:21.781 (SolrIndexer) Adding value: 5279fea8-e913-4dfd-9946-1821d323b069 to field: uuid
DEBUG 17:01:21.781 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/a1/b1, title=null, uuid=5279fea8-e913-4dfd-9946-1821d323b069])
DEBUG 17:01:21.781 (SolrIndexer) Adding value: http://localhost:55207/a1/b1 to field: id
DEBUG 17:01:21.781 (SolrIndexer) Adding value: 5279fea8-e913-4dfd-9946-1821d323b069 to field: uuid
DEBUG 17:01:21.783 (SolrIndexer) Update request was successful for: http://localhost:55207/a1/b1
DEBUG 17:01:21.783 (SolrIndexer) Received result from Solr request.
DEBUG 17:01:21.787 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:01:21.794 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:01:22.501 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b1, true
DEBUG 17:01:22.501 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b1, true
DEBUG 17:01:22.501 (IndexerGroupIT) Received update at test indexer for identifier: http://localhost:55207/a1/b1
DEBUG 17:01:22.546 (IndexerGroupIT) Created object at: http://localhost:55207/a1/b2
DEBUG 17:01:22.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:22.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:22.549 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:4
DEBUG 17:01:22.549 (IndexerGroup) Discovered id: /a1 in message.
DEBUG 17:01:22.549 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#PROPERTY_CHANGED in message.
DEBUG 17:01:22.550 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:01:22.550 (IndexerGroup) Discovered properties: jcr:lastModified,jcr:lastModifiedBy in message.
DEBUG 17:01:22.550 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:01:22.550 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/a1...
DEBUG 17:01:22.589 (IndexerGroup) Resource: http://localhost:55207/a1 retrieved with indexable type.
DEBUG 17:01:22.589 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:01:22.589 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:01:22.589 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:22.589 (TestIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:22.589 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1/b1, http://localhost:55207/a1]
DEBUG 17:01:22.589 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:22.589 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:01:22.589 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:22.589 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/a1
DEBUG 17:01:22.589 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:01:22.589 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/a1/fcr:transform/default
DEBUG 17:01:22.601 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:22.640 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:01:22.641 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:01:22.646 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:01:22.646 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:22.646 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\a1@20140604170122
DEBUG 17:01:22.647 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:01:22.647 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:01:22.647 (IndexerGroup) Retrieving RDF for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:22.647 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:01:22.647 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:01:22.647 (SparqlIndexer) Received update for: http://localhost:55207/a1
DEBUG 17:01:22.647 (SparqlIndexer) Received remove for: http://localhost:55207/a1
DEBUG 17:01:23.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:23.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:24.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:24.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:25.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:25.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:26.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:26.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:27.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:27.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:28.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:28.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:29.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:29.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:30.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:30.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:31.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:31.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:32.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:32.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:33.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:33.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:34.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:34.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:35.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:35.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:36.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:36.546 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:37.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:37.546 (TestIndexer) Checked whether we received an update for: http://localhost:55207/a1/b2, false
DEBUG 17:01:37.574 (IndexingIT) Installing indexing namespace...
DEBUG 17:01:37.588 (IndexingIT) Installing indexing type information...
DEBUG 17:01:37.600 (IndexingIT) Installing indexing test type information...
DEBUG 17:01:37.660 (IndexerGroupIT) Created object at: http://localhost:55207/removeTestPid
DEBUG 17:01:37.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:37.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:38.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:38.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:39.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:39.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:40.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:40.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:41.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:41.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:42.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:42.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:43.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:43.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:44.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:44.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:45.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:45.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:46.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:46.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:47.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:47.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:48.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:48.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:49.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:49.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:50.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:50.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:51.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:51.660 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:52.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:52.660 (TestIndexer) Checked whether we received an update for: http://localhost:55207/removeTestPid, false
DEBUG 17:01:52.673 (IndexingIT) Installing indexing namespace...
DEBUG 17:01:52.684 (IndexingIT) Installing indexing type information...
DEBUG 17:01:52.697 (IndexingIT) Installing indexing test type information...
DEBUG 17:01:52.751 (IndexerGroupIT) Created object at: http://localhost:55207/updateTestPid
DEBUG 17:01:52.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:52.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:53.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:53.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:54.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:54.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:55.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:55.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:56.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:56.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:57.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:57.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:58.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:58.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:01:59.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:01:59.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:00.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:00.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:01.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:01.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:02.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:02.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:03.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:03.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:04.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:04.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:05.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:05.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:06.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:06.751 (IndexerGroupIT) Waiting for next notification from TestIndexer...
DEBUG 17:02:07.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
DEBUG 17:02:07.751 (TestIndexer) Checked whether we received an update for: http://localhost:55207/updateTestPid, false
Tests run: 3, Failures: 3, Errors: 0, Skipped: 0, Time elapsed: 58.337 sec <<< FAILURE! - in org.fcrepo.indexer.integration.IndexerGroupIT
testIndexerGroupReindex(org.fcrepo.indexer.integration.IndexerGroupIT) Time elapsed: 27.608 sec <<< FAILURE!
java.lang.AssertionError: Test indexer should have received an update message for http://localhost:55207/a1/b2!
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.fcrepo.indexer.integration.IndexerGroupIT.doIndexerGroupUpdateTest(IndexerGroupIT.java:86)
at org.fcrepo.indexer.integration.IndexerGroupIT.testIndexerGroupReindex(IndexerGroupIT.java:125)
testIndexerGroupDelete(org.fcrepo.indexer.integration.IndexerGroupIT) Time elapsed: 15.098 sec <<< FAILURE!
java.lang.AssertionError: Test indexer should have received an update message for http://localhost:55207/removeTestPid!
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.fcrepo.indexer.integration.IndexerGroupIT.doIndexerGroupUpdateTest(IndexerGroupIT.java:86)
at org.fcrepo.indexer.integration.IndexerGroupIT.testIndexerGroupDelete(IndexerGroupIT.java:97)
testIndexerGroupUpdate(org.fcrepo.indexer.integration.IndexerGroupIT) Time elapsed: 15.091 sec <<< FAILURE!
java.lang.AssertionError: Test indexer should have received an update message for http://localhost:55207/updateTestPid!
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.fcrepo.indexer.integration.IndexerGroupIT.doIndexerGroupUpdateTest(IndexerGroupIT.java:86)
at org.fcrepo.indexer.integration.IndexerGroupIT.testIndexerGroupUpdate(IndexerGroupIT.java:60)
Running org.fcrepo.indexer.integration.solr.SolrIndexerIT
DEBUG 17:02:07.760 (AsynchIndexer) Received update for identifier: 345
DEBUG 17:02:07.760 (SolrIndexer) Received request for update to: 345
DEBUG 17:02:07.760 (AsynchIndexer) Issued task to execution pool for identifier: 345
DEBUG 17:02:07.760 (SolrIndexer) Executing request to Solr index for identifier: 345 with fields: {id=[345]}
DEBUG 17:02:07.760 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:07.760 (SolrIndexer) Adding value: 345 to field: id
DEBUG 17:02:07.760 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=345])
DEBUG 17:02:07.760 (SolrIndexer) Adding value: 345 to field: id
DEBUG 17:02:07.763 (SolrIndexer) Update request was successful for: 345
DEBUG 17:02:07.763 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:07.779 (SolrIndexerIT) Waiting for index record to appear...
DEBUG 17:02:08.779 (SolrIndexerIT) Checking for presence of appropriate index record...
DEBUG 17:02:08.786 (AsynchIndexer) Received remove for identifier: 345
DEBUG 17:02:08.787 (SolrIndexer) Received request for removal of: 345
DEBUG 17:02:08.789 (SolrIndexerIT) Waiting for index record to appear...
DEBUG 17:02:08.790 (SolrIndexer) Remove request was successful for: 345
DEBUG 17:02:09.789 (SolrIndexerIT) Checking for presence of appropriate index record...
DEBUG 17:02:09.791 (AsynchIndexer) Received update for identifier: 123
DEBUG 17:02:09.792 (SolrIndexer) Received request for update to: 123
DEBUG 17:02:09.792 (AsynchIndexer) Issued task to execution pool for identifier: 123
DEBUG 17:02:09.792 (SolrIndexer) Executing request to Solr index for identifier: 123 with fields: {id=[123]}
DEBUG 17:02:09.792 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:09.792 (SolrIndexer) Adding value: 123 to field: id
DEBUG 17:02:09.792 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=123])
DEBUG 17:02:09.792 (SolrIndexer) Adding value: 123 to field: id
DEBUG 17:02:09.793 (SolrIndexerIT) Waiting for index record to appear...
DEBUG 17:02:09.795 (SolrIndexer) Update request was successful for: 123
DEBUG 17:02:09.795 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:10.793 (SolrIndexerIT) Checking for presence of appropriate index record...
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.935 sec - in org.fcrepo.indexer.integration.solr.SolrIndexerIT
Running org.fcrepo.indexer.integration.sparql.SparqlIndexerIT
DEBUG 17:02:10.804 (AsynchIndexer) Received update for identifier: http://example.com/sparqlIndexerTestURI
DEBUG 17:02:10.804 (SparqlIndexer) Received update for: http://example.com/sparqlIndexerTestURI
DEBUG 17:02:10.804 (SparqlIndexer) Received remove for: http://example.com/sparqlIndexerTestURI
DEBUG 17:02:10.834 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/a1/fcr:export?format=jcr/xml> ?p ?o }
DEBUG 17:02:10.834 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/a1/b1> ?p ?o }
DEBUG 17:02:10.835 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/a1> ?p ?o }
DEBUG 17:02:10.836 (SparqlIndexer) Sending update request for pid: http://localhost:55207/a1
DEBUG 17:02:10.836 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:02:10.836 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:10.836 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:10.836 (IndexerGroup) Executing update of: http://localhost:55207/a1 to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:10.836 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1
DEBUG 17:02:10.836 (SolrIndexer) Received request for update to: http://localhost:55207/a1
DEBUG 17:02:10.836 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1
DEBUG 17:02:10.840 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/a1 with fields: {id=[http://localhost:55207/a1], title=[], uuid=[eb4748cc-7a03-49aa-9097-9079d46ac1b7]}
DEBUG 17:02:10.840 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:10.841 (SolrIndexer) Adding value: http://localhost:55207/a1 to field: id
DEBUG 17:02:10.841 (SolrIndexer) Adding value: eb4748cc-7a03-49aa-9097-9079d46ac1b7 to field: uuid
DEBUG 17:02:10.841 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:5
DEBUG 17:02:10.841 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/a1, title=null, uuid=eb4748cc-7a03-49aa-9097-9079d46ac1b7])
DEBUG 17:02:10.841 (SolrIndexer) Adding value: http://localhost:55207/a1 to field: id
DEBUG 17:02:10.841 (SolrIndexer) Adding value: eb4748cc-7a03-49aa-9097-9079d46ac1b7 to field: uuid
DEBUG 17:02:10.841 (IndexerGroup) Discovered id: /a1/b2 in message.
DEBUG 17:02:10.841 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:02:10.841 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:10.841 (IndexerGroup) Discovered properties: indexing:hasIndexingTransformation,jcr:created,jcr:lastModified,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType in message.
DEBUG 17:02:10.841 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:02:10.841 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/a1/b2...
DEBUG 17:02:10.862 (SolrIndexer) Update request was successful for: http://localhost:55207/a1
DEBUG 17:02:10.862 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:10.864 (IndexerGroup) Resource: http://localhost:55207/a1/b2 retrieved with indexable type.
DEBUG 17:02:10.864 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:10.864 (IndexerGroup) Executing update of: http://localhost:55207/a1/b2 to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:02:10.864 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:10.864 (TestIndexer) Received update for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:10.864 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1/b1, http://localhost:55207/a1, http://localhost:55207/a1/b2]
DEBUG 17:02:10.864 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:10.864 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:02:10.864 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:10.864 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1/b2, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:10.865 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/a1/b2
DEBUG 17:02:10.865 (SparqlIndexer) Sending update request for pid: http://example.com/sparqlIndexerTestURI
DEBUG 17:02:10.865 (AsynchIndexer) Issued task to execution pool for identifier: http://example.com/sparqlIndexerTestURI
DEBUG 17:02:10.865 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:02:10.865 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/a1/b2/fcr:transform/default
DEBUG 17:02:10.870 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:10.876 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:10.877 (SparqlIndexerIT) Discovered 0 triples, waiting for 3...
DEBUG 17:02:10.879 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:10.880 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:10.899 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:10.899 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:02:10.903 (IndexerGroup) Executing update of: http://localhost:55207/a1/b2 to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:10.903 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:10.903 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\b2@20140604170210
DEBUG 17:02:10.904 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:10.904 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:10.904 (IndexerGroup) Retrieving RDF for: http://localhost:55207/a1/b2, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:10.904 (IndexerGroup) Executing update of: http://localhost:55207/a1/b2 to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:10.904 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:10.904 (SparqlIndexer) Received update for: http://localhost:55207/a1/b2
DEBUG 17:02:10.904 (SparqlIndexer) Received remove for: http://localhost:55207/a1/b2
DEBUG 17:02:11.406 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/a1/b2> ?p ?o }
DEBUG 17:02:11.408 (SparqlIndexer) Sending update request for pid: http://localhost:55207/a1/b2
DEBUG 17:02:11.408 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:11.408 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:11.408 (IndexerGroup) Retrieving named fields for: http://localhost:55207/a1/b2, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.408 (IndexerGroup) Executing update of: http://localhost:55207/a1/b2 to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.408 (AsynchIndexer) Received update for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:11.408 (SolrIndexer) Received request for update to: http://localhost:55207/a1/b2
DEBUG 17:02:11.409 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/a1/b2
DEBUG 17:02:11.409 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:6
DEBUG 17:02:11.409 (IndexerGroup) Discovered id: /removeTestPid in message.
DEBUG 17:02:11.409 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:02:11.409 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:11.409 (IndexerGroup) Discovered properties: indexing:hasIndexingTransformation,jcr:created,jcr:lastModified,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType in message.
DEBUG 17:02:11.409 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:02:11.409 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/removeTestPid...
DEBUG 17:02:11.410 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/a1/b2 with fields: {id=[http://localhost:55207/a1/b2], title=[], uuid=[df5b53a6-5518-4aad-a739-bcdfbae1e92f]}
DEBUG 17:02:11.410 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:11.410 (SolrIndexer) Adding value: http://localhost:55207/a1/b2 to field: id
DEBUG 17:02:11.410 (SolrIndexer) Adding value: df5b53a6-5518-4aad-a739-bcdfbae1e92f to field: uuid
DEBUG 17:02:11.410 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/a1/b2, title=null, uuid=df5b53a6-5518-4aad-a739-bcdfbae1e92f])
DEBUG 17:02:11.410 (SolrIndexer) Adding value: http://localhost:55207/a1/b2 to field: id
DEBUG 17:02:11.411 (SolrIndexer) Adding value: df5b53a6-5518-4aad-a739-bcdfbae1e92f to field: uuid
DEBUG 17:02:11.413 (SolrIndexer) Update request was successful for: http://localhost:55207/a1/b2
DEBUG 17:02:11.413 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:11.425 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:11.434 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:11.442 (IndexerGroup) Resource: http://localhost:55207/removeTestPid retrieved with indexable type.
DEBUG 17:02:11.442 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:11.442 (IndexerGroup) Executing update of: http://localhost:55207/removeTestPid to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:02:11.442 (AsynchIndexer) Received update for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.442 (TestIndexer) Received update for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.442 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1/b1, http://localhost:55207/a1, http://localhost:55207/removeTestPid, http://localhost:55207/a1/b2]
DEBUG 17:02:11.442 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.442 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:11.442 (IndexerGroup) Retrieving named fields for: http://localhost:55207/removeTestPid, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:11.442 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/removeTestPid
DEBUG 17:02:11.443 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:02:11.443 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/removeTestPid/fcr:transform/default
DEBUG 17:02:11.453 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:11.470 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:11.471 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:02:11.474 (IndexerGroup) Executing update of: http://localhost:55207/removeTestPid to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:11.474 (AsynchIndexer) Received update for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.474 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\removeTestPid@20140604170211
DEBUG 17:02:11.475 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.475 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:11.475 (IndexerGroup) Retrieving RDF for: http://localhost:55207/removeTestPid, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:11.475 (IndexerGroup) Executing update of: http://localhost:55207/removeTestPid to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:11.475 (AsynchIndexer) Received update for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.475 (SparqlIndexer) Received update for: http://localhost:55207/removeTestPid
DEBUG 17:02:11.475 (SparqlIndexer) Received remove for: http://localhost:55207/removeTestPid
DEBUG 17:02:11.488 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:02:11.489 (SparqlIndexer) Sending update request for pid: http://localhost:55207/removeTestPid
DEBUG 17:02:11.489 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.489 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:11.489 (IndexerGroup) Retrieving named fields for: http://localhost:55207/removeTestPid, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.489 (IndexerGroup) Executing update of: http://localhost:55207/removeTestPid to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.489 (AsynchIndexer) Received update for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.489 (SolrIndexer) Received request for update to: http://localhost:55207/removeTestPid
DEBUG 17:02:11.489 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/removeTestPid
DEBUG 17:02:11.489 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:7
DEBUG 17:02:11.489 (IndexerGroup) Discovered id: /updateTestPid in message.
DEBUG 17:02:11.489 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:02:11.489 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:11.489 (IndexerGroup) Discovered properties: indexing:hasIndexingTransformation,jcr:created,jcr:lastModified,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType in message.
DEBUG 17:02:11.489 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:02:11.489 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/updateTestPid...
DEBUG 17:02:11.489 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/removeTestPid with fields: {id=[http://localhost:55207/removeTestPid], title=[], uuid=[a6278aaa-6078-4e5e-bfd8-a84117e6734b]}
DEBUG 17:02:11.489 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:11.489 (SolrIndexer) Adding value: http://localhost:55207/removeTestPid to field: id
DEBUG 17:02:11.490 (SolrIndexer) Adding value: a6278aaa-6078-4e5e-bfd8-a84117e6734b to field: uuid
DEBUG 17:02:11.490 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/removeTestPid, title=null, uuid=a6278aaa-6078-4e5e-bfd8-a84117e6734b])
DEBUG 17:02:11.490 (SolrIndexer) Adding value: http://localhost:55207/removeTestPid to field: id
DEBUG 17:02:11.490 (SolrIndexer) Adding value: a6278aaa-6078-4e5e-bfd8-a84117e6734b to field: uuid
DEBUG 17:02:11.492 (SolrIndexer) Update request was successful for: http://localhost:55207/removeTestPid
DEBUG 17:02:11.492 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:11.505 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:11.510 (IndexerGroup) Resource: http://localhost:55207/updateTestPid retrieved with indexable type.
DEBUG 17:02:11.510 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:11.510 (IndexerGroup) Executing update of: http://localhost:55207/updateTestPid to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:02:11.510 (AsynchIndexer) Received update for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.510 (TestIndexer) Received update for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.510 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1/b1, http://localhost:55207/a1, http://localhost:55207/updateTestPid, http://localhost:55207/removeTestPid, http://localhost:55207/a1/b2]
DEBUG 17:02:11.510 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.510 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:11.510 (IndexerGroup) Retrieving named fields for: http://localhost:55207/updateTestPid, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:11.510 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/updateTestPid
DEBUG 17:02:11.510 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:02:11.510 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/updateTestPid/fcr:transform/default
DEBUG 17:02:11.511 (AsynchIndexer) Received remove for identifier: http://example.com/sparqlIndexerTestURI
DEBUG 17:02:11.511 (SparqlIndexer) Received remove for: http://example.com/sparqlIndexerTestURI
DEBUG 17:02:11.520 (SparqlIndexer) Executing: DELETE WHERE { <http://example.com/sparqlIndexerTestURI/barDS> ?p ?o }
DEBUG 17:02:11.520 (SparqlIndexer) Executing: DELETE WHERE { <http://example.com/sparqlIndexerTestURI> ?p ?o }
DEBUG 17:02:11.521 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:11.529 (SparqlIndexer) Completed Sparql update/removal.
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.732 sec - in org.fcrepo.indexer.integration.sparql.SparqlIndexerIT
Running org.fcrepo.indexer.system.SolrMappingsIT
DEBUG 17:02:11.540 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:11.540 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:02:11.544 (IndexerGroup) Executing update of: http://localhost:55207/updateTestPid to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:11.544 (AsynchIndexer) Received update for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.544 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\updateTestPid@20140604170211
DEBUG 17:02:11.544 (IndexingIT) Installing indexing namespace...
DEBUG 17:02:11.544 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.544 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:11.544 (IndexerGroup) Retrieving RDF for: http://localhost:55207/updateTestPid, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:11.544 (IndexerGroup) Executing update of: http://localhost:55207/updateTestPid to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:11.545 (AsynchIndexer) Received update for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.545 (SparqlIndexer) Received update for: http://localhost:55207/updateTestPid
DEBUG 17:02:11.545 (SparqlIndexer) Received remove for: http://localhost:55207/updateTestPid
DEBUG 17:02:11.552 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:02:11.552 (SparqlIndexer) Sending update request for pid: http://localhost:55207/updateTestPid
DEBUG 17:02:11.552 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.552 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:11.552 (IndexerGroup) Retrieving named fields for: http://localhost:55207/updateTestPid, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.552 (IndexerGroup) Executing update of: http://localhost:55207/updateTestPid to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.552 (AsynchIndexer) Received update for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.553 (SolrIndexer) Received request for update to: http://localhost:55207/updateTestPid
DEBUG 17:02:11.553 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/updateTestPid
DEBUG 17:02:11.553 (IndexingIT) Installing indexing type information...
DEBUG 17:02:11.553 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/updateTestPid with fields: {id=[http://localhost:55207/updateTestPid], title=[], uuid=[608ff9de-e63a-46c0-af2d-25cfcfdc94d7]}
DEBUG 17:02:11.553 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:11.553 (SolrIndexer) Adding value: http://localhost:55207/updateTestPid to field: id
DEBUG 17:02:11.553 (SolrIndexer) Adding value: 608ff9de-e63a-46c0-af2d-25cfcfdc94d7 to field: uuid
DEBUG 17:02:11.553 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/updateTestPid, title=null, uuid=608ff9de-e63a-46c0-af2d-25cfcfdc94d7])
DEBUG 17:02:11.553 (SolrIndexer) Adding value: http://localhost:55207/updateTestPid to field: id
DEBUG 17:02:11.554 (SolrIndexer) Adding value: 608ff9de-e63a-46c0-af2d-25cfcfdc94d7 to field: uuid
DEBUG 17:02:11.555 (SolrIndexer) Update request was successful for: http://localhost:55207/updateTestPid
DEBUG 17:02:11.555 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:11.567 (IndexingIT) Installing indexing test type information...
DEBUG 17:02:11.569 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:11.585 (SolrMappingsIT) Creating indexable resource...
DEBUG 17:02:11.585 (SolrMappingsIT) Creating object with RDF:
@prefix rdf: <http://www.w3.org/1999/02/22-rdf-syntax-ns#> .@prefix dc:<http://purl.org/dc/elements/1.1/> .@prefix indexing:<http://fedora.info/definitions/v4/indexing#>.<http://localhost:55207/testCollection/testOneResourcePid> dc:title "500 Easy Microwave Meals" ; dc:creator "Yubulac Xorhorisa" ; dc:subject <http://id.loc.gov/authorities/subjects/sh2012004374> ;rdf:type <http://fedora.info/definitions/v4/indexing#indexable> ;indexing:hasIndexingTransformation "default".
DEBUG 17:02:11.619 (SolrMappingsIT) Waiting for our resource to be indexed...
DEBUG 17:02:11.619 (SolrMappingsIT) Checking for our record with query: title:"500 Easy Microwave Meals"
DEBUG 17:02:11.623 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:8
DEBUG 17:02:11.623 (IndexerGroup) Discovered id: /testCollection/testOneResourcePid in message.
DEBUG 17:02:11.623 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:02:11.623 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:11.623 (IndexerGroup) Discovered properties: indexing:hasIndexingTransformation,jcr:created,dc:subject,jcr:lastModified,dc:creator,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType,dc:title in message.
DEBUG 17:02:11.623 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:02:11.623 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/testCollection/testOneResourcePid...
DEBUG 17:02:11.626 (SolrMappingsIT) Waiting for index record to appear...
DEBUG 17:02:11.643 (IndexerGroup) Resource: http://localhost:55207/testCollection/testOneResourcePid retrieved with indexable type.
DEBUG 17:02:11.643 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:11.643 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:02:11.643 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.643 (TestIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.643 (TestIndexer) Current recorded updates include: [http://localhost:55207/a1/b1, http://localhost:55207/a1, http://localhost:55207/updateTestPid, http://localhost:55207/removeTestPid, http://localhost:55207/testCollection/testOneResourcePid, http://localhost:55207/a1/b2]
DEBUG 17:02:11.643 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.643 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:11.643 (IndexerGroup) Retrieving named fields for: http://localhost:55207/testCollection/testOneResourcePid, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:11.643 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.644 (NamedFieldsRetriever) Discovered transform key: default
DEBUG 17:02:11.644 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/testCollection/testOneResourcePid/fcr:transform/default
DEBUG 17:02:11.654 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:11.673 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:11.673 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:02:11.677 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:11.677 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.677 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\testOneResourcePid@20140604170211
DEBUG 17:02:11.678 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.678 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:11.678 (IndexerGroup) Retrieving RDF for: http://localhost:55207/testCollection/testOneResourcePid, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:11.678 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:11.678 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.678 (SparqlIndexer) Received update for: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.678 (SparqlIndexer) Received remove for: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.685 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:02:11.685 (SparqlIndexer) Sending update request for pid: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.686 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.686 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:11.686 (IndexerGroup) Retrieving named fields for: http://localhost:55207/testCollection/testOneResourcePid, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.686 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:11.686 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.686 (SolrIndexer) Received request for update to: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.686 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.686 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/testCollection/testOneResourcePid with fields: {id=[http://localhost:55207/testCollection/testOneResourcePid], title=[500 Easy Microwave Meals], uuid=[7eca9a41-90b9-4b13-a39f-63d759f16a04]}
DEBUG 17:02:11.686 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:11.686 (SolrIndexer) Adding value: http://localhost:55207/testCollection/testOneResourcePid to field: id
DEBUG 17:02:11.686 (SolrIndexer) Adding value: 500 Easy Microwave Meals to field: title
DEBUG 17:02:11.686 (SolrIndexer) Adding value: 7eca9a41-90b9-4b13-a39f-63d759f16a04 to field: uuid
DEBUG 17:02:11.686 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/testCollection/testOneResourcePid, title=500 Easy Microwave Meals, uuid=7eca9a41-90b9-4b13-a39f-63d759f16a04])
DEBUG 17:02:11.686 (SolrIndexer) Adding value: http://localhost:55207/testCollection/testOneResourcePid to field: id
DEBUG 17:02:11.686 (SolrIndexer) Adding value: 500 Easy Microwave Meals to field: title
DEBUG 17:02:11.687 (SolrIndexer) Adding value: 7eca9a41-90b9-4b13-a39f-63d759f16a04 to field: uuid
DEBUG 17:02:11.697 (SolrIndexer) Update request was successful for: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:11.697 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:11.703 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:12.626 (SolrMappingsIT) Checking for presence of appropriate index record...
DEBUG 17:02:12.638 (SolrMappingsIT) Discovered one record for http://localhost:55207/testCollection/testOneResourcePid in index.
DEBUG 17:02:12.655 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:9
DEBUG 17:02:12.655 (SolrMappingsIT) Waiting for index record to disappear...
DEBUG 17:02:12.655 (IndexerGroup) Discovered id: /testCollection/testOneResourcePid in message.
DEBUG 17:02:12.655 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_REMOVED in message.
DEBUG 17:02:12.656 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:12.656 (IndexerGroup) Discovered properties: in message.
DEBUG 17:02:12.656 (IndexerGroup) It is true that this is a removal operation.
DEBUG 17:02:12.656 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:12.656 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:02:12.656 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.656 (TestIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.657 (TestIndexer) Current recorded removes include: [http://localhost:55207/testCollection/testOneResourcePid]
DEBUG 17:02:12.657 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:12.658 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:12.658 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.658 (FileSerializer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.658 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\testOneResourcePid@20140604170212
DEBUG 17:02:12.659 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:12.659 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:12.659 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.659 (SparqlIndexer) Received remove for: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.688 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/testCollection/testOneResourcePid/fcr:export?format=jcr/xml> ?p ?o }
DEBUG 17:02:12.689 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/testCollection/testOneResourcePid> ?p ?o }
DEBUG 17:02:12.689 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:12.689 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourcePid to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:12.689 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.689 (SolrIndexer) Received request for removal of: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.690 (SolrIndexer) Remove request was successful for: http://localhost:55207/testCollection/testOneResourcePid
DEBUG 17:02:12.699 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:13.655 (SolrMappingsIT) Checking for absence of appropriate index record...
DEBUG 17:02:13.670 (IndexingIT) Installing indexing namespace...
DEBUG 17:02:13.680 (IndexingIT) Installing indexing type information...
DEBUG 17:02:13.691 (IndexingIT) Installing indexing test type information...
DEBUG 17:02:13.708 (SolrMappingsIT) Creating index mapping at URL: http://localhost:55207/fedora:system/fedora:transform/fedora:ldpath/dc/indexingtest:book/fcr:content...
DEBUG 17:02:13.752 (SolrMappingsIT) Creating indexable resource...
DEBUG 17:02:13.752 (SolrMappingsIT) Creating object with RDF:
@prefix rdf: <http://www.w3.org/1999/02/22-rdf-syntax-ns#> .@prefix dc:<http://purl.org/dc/elements/1.1/> .@prefix indexing:<http://fedora.info/definitions/v4/indexing#>.<http://localhost:55207/testCollection/testOneResourceCustomMappingPid> dc:title "500 Easy Microwave Meals" ; dc:creator "Yubulac Xorhorisa" ; dc:subject <http://id.loc.gov/authorities/subjects/sh2012004374> ;rdf:type <http://fedora.info/definitions/v4/indexing#indexable> ;rdf:type <http://fedora.info/definitions/v4/indexingtest#book> ;indexing:hasIndexingTransformation "dc".
DEBUG 17:02:13.754 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:10
DEBUG 17:02:13.754 (IndexerGroup) Discovered id: /fedora:system/fedora:transform/fedora:ldpath/dc/indexingtest:book in message.
DEBUG 17:02:13.754 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:02:13.754 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:13.754 (IndexerGroup) Discovered properties: jcr:created,jcr:lastModified,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType in message.
DEBUG 17:02:13.754 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:02:13.754 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/fedora:system/fedora:transform/fedora:ldpath/dc/indexingtest:book...
DEBUG 17:02:13.783 (SolrMappingsIT) Waiting for our resource to be indexed...
DEBUG 17:02:13.784 (SolrMappingsIT) Checking for our record with query: subject:http%3A%2F%2Fid.loc.gov%2Fauthorities%2Fsubjects%2Fsh2012004374
DEBUG 17:02:13.786 (SolrMappingsIT) Waiting for index record to appear...
DEBUG 17:02:13.788 (IndexerGroup) Resource: http://localhost:55207/fedora:system/fedora:transform/fedora:ldpath/dc/indexingtest:book retrieved without indexable type.
DEBUG 17:02:13.788 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:13.788 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:13.788 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:13.788 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:13.788 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:11
DEBUG 17:02:13.788 (IndexerGroup) Discovered id: /testCollection/testOneResourceCustomMappingPid in message.
DEBUG 17:02:13.788 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_ADDED,http://fedora.info/definitions/v4/repository#PROPERTY_ADDED in message.
DEBUG 17:02:13.788 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:13.788 (IndexerGroup) Discovered properties: indexing:hasIndexingTransformation,jcr:created,dc:subject,jcr:lastModified,dc:creator,jcr:createdBy,jcr:uuid,jcr:lastModifiedBy,jcr:mixinTypes,jcr:primaryType,dc:title in message.
DEBUG 17:02:13.788 (IndexerGroup) It is false that this is a removal operation.
DEBUG 17:02:13.788 (RdfRetriever) Retrieving RDF content from: http://localhost:55207/testCollection/testOneResourceCustomMappingPid...
DEBUG 17:02:13.808 (IndexerGroup) Resource: http://localhost:55207/testCollection/testOneResourceCustomMappingPid retrieved with indexable type.
DEBUG 17:02:13.808 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:13.808 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:02:13.808 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.808 (TestIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.808 (TestIndexer) Current recorded updates include: [http://localhost:55207/testCollection/testOneResourceCustomMappingPid, http://localhost:55207/a1/b1, http://localhost:55207/a1, http://localhost:55207/updateTestPid, http://localhost:55207/removeTestPid, http://localhost:55207/testCollection/testOneResourcePid, http://localhost:55207/a1/b2]
DEBUG 17:02:13.808 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.808 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:13.808 (IndexerGroup) Retrieving named fields for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid, (may be cached) to index to org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:13.808 (NamedFieldsRetriever) Retrieving RDF representation for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.809 (NamedFieldsRetriever) Discovered transform key: dc
DEBUG 17:02:13.809 (NamedFieldsRetriever) Retrieving transformed resource from: http://localhost:55207/testCollection/testOneResourceCustomMappingPid/fcr:transform/dc
DEBUG 17:02:13.818 (FedoraTransform) Transforming node: /fedora:system/fedora:transform/fedora:ldpath/default
DEBUG 17:02:13.836 (LDPathTransform) Found program node: /fedora:system/fedora:transform/fedora:ldpath/dc
DEBUG 17:02:13.837 (LDPathTransform) Discovered node types: [fedora:object, fedora:resource, indexing:indexable, indexingtest:book, nt:folder, mix:created, nt:base, nt:hierarchyNode, fedora:resource, dc:describable, fedora:relations, mix:created, mix:lastModified, mix:lockable, mix:referenceable]
DEBUG 17:02:13.841 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:13.841 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.841 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\testOneResourceCustomMappingPid@20140604170213
DEBUG 17:02:13.842 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.842 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:13.842 (IndexerGroup) Retrieving RDF for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid, (may be cached) to index to org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:13.842 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:13.842 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.842 (SparqlIndexer) Received update for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.842 (SparqlIndexer) Received remove for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.849 (SparqlIndexer) Received empty update/remove operation.
DEBUG 17:02:13.849 (SparqlIndexer) Sending update request for pid: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.849 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.849 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:13.849 (IndexerGroup) Retrieving named fields for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid, (may be cached) to index to org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:13.849 (IndexerGroup) Executing update of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:13.849 (AsynchIndexer) Received update for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.849 (SolrIndexer) Received request for update to: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.849 (AsynchIndexer) Issued task to execution pool for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.850 (SolrIndexer) Executing request to Solr index for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid with fields: {author=[Yubulac Xorhorisa], title=[500 Easy Microwave Meals], subject=[http://id.loc.gov/authorities/subjects/sh2012004374]}
DEBUG 17:02:13.850 (SolrIndexer) Constructing new SolrInputDocument...
DEBUG 17:02:13.850 (SolrIndexer) Adding value: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to field: id
DEBUG 17:02:13.850 (SolrIndexer) Adding value: Yubulac Xorhorisa to field: author
DEBUG 17:02:13.850 (SolrIndexer) Adding value: 500 Easy Microwave Meals to field: title
DEBUG 17:02:13.850 (SolrIndexer) Adding value: http://id.loc.gov/authorities/subjects/sh2012004374 to field: subject
DEBUG 17:02:13.850 (SolrIndexer) Created SolrInputDocument: SolrInputDocument(fields: [id=http://localhost:55207/testCollection/testOneResourceCustomMappingPid, author=Yubulac Xorhorisa, title=500 Easy Microwave Meals, subject=http://id.loc.gov/authorities/subjects/sh2012004374])
DEBUG 17:02:13.850 (SolrIndexer) Adding value: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to field: id
DEBUG 17:02:13.850 (SolrIndexer) Adding value: Yubulac Xorhorisa to field: author
DEBUG 17:02:13.850 (SolrIndexer) Adding value: 500 Easy Microwave Meals to field: title
DEBUG 17:02:13.850 (SolrIndexer) Adding value: http://id.loc.gov/authorities/subjects/sh2012004374 to field: subject
DEBUG 17:02:13.853 (SolrIndexer) Update request was successful for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:13.854 (SolrIndexer) Received result from Solr request.
DEBUG 17:02:13.866 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:14.786 (SolrMappingsIT) Checking for presence of appropriate index record...
DEBUG 17:02:14.799 (SolrMappingsIT) Found 1 records with appropriate subject,
DEBUG 17:02:14.799 (SolrMappingsIT) Discovered one record for http://localhost:55207/testCollection/testOneResourceCustomMappingPid in index.
DEBUG 17:02:14.814 (IndexerGroup) Received message: ID:systems28a-55237-1401915676608-3:1:1:1:12
DEBUG 17:02:14.814 (IndexerGroup) Discovered id: /testCollection/testOneResourceCustomMappingPid in message.
DEBUG 17:02:14.814 (IndexerGroup) Discovered event type: http://fedora.info/definitions/v4/repository#NODE_REMOVED in message.
DEBUG 17:02:14.815 (IndexerGroup) Discovered baseURL: http://localhost:55207 in message.
DEBUG 17:02:14.815 (IndexerGroup) Discovered properties: in message.
DEBUG 17:02:14.815 (IndexerGroup) It is true that this is a removal operation.
DEBUG 17:02:14.815 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.TestIndexer@3da06102
DEBUG 17:02:14.815 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.TestIndexer@3da06102...
DEBUG 17:02:14.815 (SolrMappingsIT) Waiting for index record to disappear...
DEBUG 17:02:14.815 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.815 (TestIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.815 (TestIndexer) Current recorded removes include: [http://localhost:55207/testCollection/testOneResourceCustomMappingPid, http://localhost:55207/testCollection/testOneResourcePid]
DEBUG 17:02:14.815 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9
DEBUG 17:02:14.815 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.FileSerializer@6c40e9e9...
DEBUG 17:02:14.816 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.816 (FileSerializer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.816 (FileSerializer) Updating to file: .\target\test-classes\fileSerializer\testOneResourceCustomMappingPid@20140604170214
DEBUG 17:02:14.817 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785
DEBUG 17:02:14.817 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.sparql.SparqlIndexer@19bcf785...
DEBUG 17:02:14.817 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.817 (SparqlIndexer) Received remove for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.840 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/testCollection/testOneResourceCustomMappingPid> ?p ?o }
DEBUG 17:02:14.840 (SparqlIndexer) Executing: DELETE WHERE { <http://localhost:55207/testCollection/testOneResourceCustomMappingPid/fcr:export?format=jcr/xml> ?p ?o }
DEBUG 17:02:14.841 (IndexerGroup) Operating for indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6
DEBUG 17:02:14.841 (IndexerGroup) Executing removal of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid to indexer: org.fcrepo.indexer.solr.SolrIndexer@451e49f6...
DEBUG 17:02:14.841 (AsynchIndexer) Received remove for identifier: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.841 (SolrIndexer) Received request for removal of: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.842 (SolrIndexer) Remove request was successful for: http://localhost:55207/testCollection/testOneResourceCustomMappingPid
DEBUG 17:02:14.850 (SparqlIndexer) Completed Sparql update/removal.
DEBUG 17:02:15.815 (SolrMappingsIT) Checking for absence of appropriate index record...
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.281 sec - in org.fcrepo.indexer.system.SolrMappingsIT
WARN 17:02:15.977 (CommonAnnotationBeanPostProcessor) Invocation of destroy method failed on bean with name 'org.fcrepo.jms.observer.JMSTopicPublisher#0': javax.jms.JMSException: Transport disposed.
DEBUG 17:02:15.983 (FusekiContainerWrapper) stopping Fuseki
Results :
Failed tests:
IndexerGroupIT.testIndexerGroupReindex:125->doIndexerGroupUpdateTest:86 Test indexer should have received an update message for http://localhost:55207/a1/b2!
IndexerGroupIT.testIndexerGroupDelete:97->doIndexerGroupUpdateTest:86 Test indexer should have received an update message for http://localhost:55207/removeTestPid!
IndexerGroupIT.testIndexerGroupUpdate:60->doIndexerGroupUpdateTest:86 Test indexer should have received an update message for http://localhost:55207/updateTestPid!
Tests run: 8, Failures: 3, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-failsafe-plugin:2.16:integration-test (default-integration-test) @ fcrepo-jms-indexer-core ---
[INFO] Skipping execution of surefire because it has already been run for this configuration
[INFO]
[INFO] --- license-maven-plugin:2.6:check (default) @ fcrepo-jms-indexer-core ---
[INFO] Checking licenses...
[INFO]
[INFO] --- maven-checkstyle-plugin:2.12.1:check (checkstyle) @ fcrepo-jms-indexer-core ---
[INFO] Starting audit...
Audit done.
[INFO]
[INFO] --- maven-failsafe-plugin:2.16:verify (verify) @ fcrepo-jms-indexer-core ---
[INFO] Failsafe report directory: F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\failsafe-reports
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] fcrepo-jms-indexer-pluggable ...................... SUCCESS [2.253s]
[INFO] fcrepo-jms-indexer-core ........................... FAILURE [1:29.353s]
[INFO] fcrepo-jms-indexer-webapp ......................... SKIPPED
[INFO] fcrepo-jms-indexer-elasticsearch .................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 1:32.252s
[INFO] Finished at: Wed Jun 04 17:02:16 EDT 2014
[INFO] Final Memory: 56M/522M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-failsafe-plugin:2.16:verify (verify) on project fcrepo-jms-indexer-core: There are test failures.
[ERROR]
[ERROR] Please refer to F:\ermadmix\git\fcrepo-jms-indexer-pluggable\fcrepo-jms-indexer-core\target\failsafe-reports for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :fcrepo-jms-indexer-core
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment