UnstableConsole Output

Skipping 11 KB.. Full Log
/jenkins/.m2/repository/javax/servlet/servlet-api/2.5/servlet-api-2.5.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/orbit/javax.activation/1.1.0.v201105071233/javax.activation-1.1.0.v201105071233.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/orbit/javax.annotation/1.1.0.v201108011116/javax.annotation-1.1.0.v201108011116.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/jetty-http/8.1.15.v20140411/jetty-http-8.1.15.v20140411.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/jetty-util/8.1.15.v20140411/jetty-util-8.1.15.v20140411.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/orbit/org.eclipse.jdt.core/3.7.1/org.eclipse.jdt.core-3.7.1.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/orbit/javax.servlet/3.0.0.v201112011016/javax.servlet-3.0.0.v201112011016.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/orbit/javax.el/2.2.0.v201108011116/javax.el-2.2.0.v201108011116.jar to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT with includes "" and excludes ""
[INFO] 
[INFO] --- maven-war-plugin:2.1.1:war (default-war) @ d1_integration ---
[INFO] Packaging webapp
[INFO] Assembling webapp [d1_integration] in [/var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT]
[INFO] Processing war project
[INFO] Copying webapp resources [/var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/src/main/webapp]
[INFO] Processing overlay [ id edu.uiuc.ncsa.myproxy:oa4mp-client-oauth1]
[INFO] Webapp assembled in [616 msecs]
[INFO] Building war: /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/d1_integration-2.3-SNAPSHOT.war
[INFO] WEB-INF/web.xml already added, skipping
[INFO] 
[INFO] --- maven-antrun-plugin:1.3:run (execution2) @ d1_integration ---
[INFO] Executing tasks
     [move] Moving 1 file to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target
[INFO] Executed tasks
[INFO] 
[INFO] --- maven-failsafe-plugin:2.8.1:integration-test (integration-test) @ d1_integration ---
[INFO] Failsafe report directory: /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/failsafe-reports

-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT
20161118-17:26:59: [WARN]: Starting monitor thread [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:26:59: [WARN]: Starting monitoring... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:26:59: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:26:59: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:26:59: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:26:59: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
~~~ Context is from d1client.properties: https://cn-stage.test.dataone.org/cn
20161118-17:27:00: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.client.rest.RestClient]
20161118-17:27:01: [INFO]: isNodeAlive for Node: https://cn-stage.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:01: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:01: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:01: [INFO]: *** Adding CN to list: cn-stage [ https://cn-stage.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:01: [INFO]: isNodeAlive for Node: https://cn-stage-orc-1.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:01: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:02: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:02: [INFO]: *** Adding CN to list: cn-stage-orc-1 [ https://cn-stage-orc-1.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:02: [INFO]: isNodeAlive for Node: https://mn-stage-unm-1.test.dataone.org/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:02: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:11: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:11: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:12: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:12: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:12: [WARN]: *** Failed to add MN to list: mn-stage-unm-1 [ https://mn-stage-unm-1.test.dataone.org/mn ].  Could not reach the node:GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:12: [INFO]: isNodeAlive for Node: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:12: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: *** Adding MN to list: mn-stage-ucsb-2 [ https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: isNodeAlive for Node: https://cn-stage-ucsb-1.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: *** Adding CN to list: cn-stage-ucsb-1 [ https://cn-stage-ucsb-1.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: isNodeAlive for Node: https://cn-stage-unm-1.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: *** Adding CN to list: cn-stage-unm-1 [ https://cn-stage-unm-1.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: isNodeAlive for Node: http://test.piscoweb.org/catalog/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:13: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: *** Adding MN to list: PISCO Test MN [ http://test.piscoweb.org/catalog/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: isNodeAlive for Node: http://seadva-test.d2i.indiana.edu/sead/rest/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: *** Adding MN to list: SEAD Virtual Archive [ http://seadva-test.d2i.indiana.edu/sead/rest/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: isNodeAlive for Node: https://mn-demo-11.test.dataone.org/knb/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:14: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: *** Adding MN to list: mn-demo-11 [ https://mn-demo-11.test.dataone.org/knb/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: isNodeAlive for Node: https://gmn-s.lternet.edu/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: *** Adding MN to list: Stage test for PASTA-GMN [ https://gmn-s.lternet.edu/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: isNodeAlive for Node: https://dataone-test.pop.umn.edu/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:15: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:22: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:23: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:23: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:23: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:24: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:24: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:25: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:25: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:25: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:25: [WARN]: *** Failed to add MN to list: mn Test MPC [ https://dataone-test.pop.umn.edu/mn ].  Could not reach the node:GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:25: [INFO]: isNodeAlive for Node: https://dataone-dev.ecoinformatics.org.au/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:25: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: *** Adding MN to list: TERN Australia DEV [ https://dataone-dev.ecoinformatics.org.au/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: isNodeAlive for Node: https://dataone.tamucc.edu/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: *** Adding MN to list: Gulf of Mexico Research Initiative Information and Data Cooperative (GRIIDC) [ https://dataone.tamucc.edu/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: isNodeAlive for Node: http://dataone.neonscience.org/metacat/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:27: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:28: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:28: [INFO]: *** Adding MN to list: NEON Test Member Node [ http://dataone.neonscience.org/metacat/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:28: [INFO]: isNodeAlive for Node: https://test.arcticdata.io/metacat/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:28: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: *** Adding MN to list: NSF Arctic Data Center Test Repository [ https://test.arcticdata.io/metacat/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: isNodeAlive for Node: http://dataone.ornith.cornell.edu/metacat/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: *** Adding MN to list: Cornell Lab of Ornithology - eBird [ http://dataone.ornith.cornell.edu/metacat/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: isNodeAlive for Node: https://dataone.researchworkspace.com/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:29: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: *** Adding MN to list: Research Workspace [ https://dataone.researchworkspace.com/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: trying to filter CN list for the round robin CN... original size: 4, includes nodes: [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]:     CN: https://cn-stage.test.dataone.org/cn "Round Robin staging router of Coordinating Nodes" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]:     CN: https://cn-stage-orc-1.test.dataone.org/cn "Oak Ridge National Laboratory Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]:     CN: https://cn-stage-ucsb-1.test.dataone.org/cn "UCSB Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]:     CN: https://cn-stage-unm-1.test.dataone.org/cn "UNM Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: excluding round robin CN: https://cn-stage.test.dataone.org/cn "Round Robin staging router of Coordinating Nodes" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: including CN: https://cn-stage-orc-1.test.dataone.org/cn "Oak Ridge National Laboratory Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: including CN: https://cn-stage-ucsb-1.test.dataone.org/cn "UCSB Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: including CN: https://cn-stage-unm-1.test.dataone.org/cn "UNM Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: filtered CN list without round robin CN. size: 3, including nodes: [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]:     CN: https://cn-stage-orc-1.test.dataone.org/cn "Oak Ridge National Laboratory Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]:     CN: https://cn-stage-ucsb-1.test.dataone.org/cn "UCSB Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]:     CN: https://cn-stage-unm-1.test.dataone.org/cn "UNM Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-17:27:30: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:30: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:27:30: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:31: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:27:31: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:35: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:37: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:37: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:38: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:39: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:27:39: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:28:01: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-unm-1.test.dataone.org/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:28:01: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:28:01: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:28:01: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:28:01: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:28:02: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.client.rest.RestClient]
20161118-17:28:02: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:28:03: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:28:03: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnCreate(AuthTokenTestImplementation.java:154)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnCreate(AuthTokenTestImplementation.java:118)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testMnCreate(AuthTokenFunctionalIT.java:31)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:28:03: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTQ4ODIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mjg6MDIuNjk0KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDA4Mn0.obv045NEbbvCPEEztaF0DoFW1lO82BImYRu4jWJbx0W9jbdukSmgxQiD73sdisctHQjWJQF4iQ9pbUL2U3dR8q4SvIfIACiiVbz3QB9wxKhurMbkpYZFzMn3lSLR6CMbzhFNC1xNplvAAbcjUTPRpwsADWTffL8zv5MDk0uvtKwciAV95lfOls1WfuRsdJc0TLUabIgTJFN1teQEVeBULTd3UAiWZccTnMejrpZ8xXf3vCTfzbWMDCMJvvGJ_MnT2wsUn-JnafzeiEchuZKmnOUpZOfG1Qig5yGg5JsEKCvXM_yGy9ggzg0hGLqbMnnc687jtK7U9PgiKEPJg3cdT2t8IXss-j_KyFZTmpXevCsGgPAW3YXeQojr7b_pKg0RlH0kiP1yhWfN6-9hCVmqAbk_Rt-CCZRWkELK4GFJFZkl4yUJxtv1niPR-kHr02GByKD0maE6bNWCl8B3o2VrEwJjXw-ykxouDnO_e6105EJQ17K2H3b-0I5po9EMJyCY7bXjWUYAC7eyvKrXUbTAxYMEsjZdLVbCTmzR_I9SsifoKEXl_Lo5X1QNroKE-E1IL3Kpuz9TJYhMsTwO_MBQgKe51yO9_TGa6xcipIXvVP2vbc0k57HwSrO2IiAMJ2ISMxBt-NkNwAW5tfqX6SjR_pZ3wDDAmnN4aMGT-C83nzI [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:28:03: [INFO]: 
***************** running test for testMnCreate(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:28:03: [INFO]: entering refreshCache()... [org.dataone.client.v2.formats.ObjectFormatCache]
20161118-17:28:03: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:28:03: [INFO]: refreshing objectFormatCache from cn: null [org.dataone.client.v2.formats.ObjectFormatCache]
20161118-17:28:03: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v2/formats [org.dataone.client.rest.RestClient]
20161118-17:28:03: [INFO]: successfully refreshed cache from cn.listFormats() [org.dataone.client.v2.formats.ObjectFormatCache]
20161118-17:28:05: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: POST https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnCreate_2016323928389; FilePart:object = /tmp/mmp.output.4245109140033502545.tmp (684336 bytes); FilePart:sysmeta = /tmp/mmp.output.2885230447069024936.tmp (978 bytes);  [org.dataone.client.rest.RestClient]
20161118-17:28:05: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:28:06: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323928389 [org.dataone.client.rest.RestClient]
20161118-17:28:36: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:28:37: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323928389 [org.dataone.client.rest.RestClient]
20161118-17:29:07: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:29:09: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323928389 [org.dataone.client.rest.RestClient]
20161118-17:29:39: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:29:40: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323928389 [org.dataone.client.rest.RestClient]
20161118-17:30:10: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:30:11: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323928389 [org.dataone.client.rest.RestClient]
20161118-17:30:41: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:30:42: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323928389 [org.dataone.client.rest.RestClient]
20161118-17:30:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/isAuthorized/testMnCreate_2016323928389?action=read [org.dataone.client.rest.RestClient]
20161118-17:30:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:30:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:30:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:31:13: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-unm-1.test.dataone.org/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:31:13: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:31:14: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:31:14: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:31:14: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:31:14: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:31:14: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnCreate(AuthTokenTestImplementation.java:154)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnCreate(AuthTokenTestImplementation.java:118)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testMnCreate(AuthTokenFunctionalIT.java:31)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:31:14: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUwNzQsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzE6MTQuMjA3KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDI3NH0.N7h8JYyyx4rmu3slyL_4ItQi5K6DokfZBEsctHBv9ZDJiFjlSy1F_rM2ok7ATGlApXVIrFDSOVKOm-jInyOQbXvkLZC1P-HJ1r-DU9gGGcVy0nDJVVK-mCGQivAHvQ1wE5xrMQeOB2ZDbrpNFyVuOB-RpP0RMAq3zjNqc6MziD68ntyPTtwk9Zi7Sk4eAmkvqLhqnnlvWjXSlCJQGbreBOT6KDoEJq3-M2FAwpVmyUBCMHuI3uR_73acFZ1iwjjOaoPoePFci2oV1pVgDcYdT2KDMTZ5UQ6xL9sixMXa79IAZl9edvZPNQGDDo7zMUmScinjbM0u77pwbSdEhT9opLMt8C7plHjHO0UwPqUBGra1TDIH-mrR59kGt2kEBM59lm_C2sWW9DgVdKFIFswdCUmcCM6rFKjDr3whwNadLiRzVbb3JEvMzgq9kM12baaK3wRprbNles4HpcVx06ZpuM9sHVizHDjtnxV-u_va6aAmlgOyBiUUG-qk4OaOBzDJqViOQ0PZSs9_sCh76jpQZVZp_64FjHQP7GonIvJQ0-dwsG3cTacnKe0ZOBIDUWhLtfoJrDIMZQKUwdhZ0nwqUpKKUN_ZjhSTs0wggVOfS_1KzIuoUXjzF-OXqKMs86kNYkyhS4HBajrIPi-4Av3JUajWxft5icUd7BvO_thO0RM [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:31:14: [INFO]: 
***************** running test for testMnCreate(...) vs. node: https://cn-stage-ucsb-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:31:15: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: POST https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnCreate_201632393114839; FilePart:object = /tmp/mmp.output.7962800501005524892.tmp (684336 bytes); FilePart:sysmeta = /tmp/mmp.output.8864818525844702471.tmp (980 bytes);  [org.dataone.client.rest.RestClient]
20161118-17:31:15: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:31:16: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393114839 [org.dataone.client.rest.RestClient]
20161118-17:31:46: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:31:46: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393114839 [org.dataone.client.rest.RestClient]
20161118-17:32:16: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:32:17: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393114839 [org.dataone.client.rest.RestClient]
20161118-17:32:47: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:32:48: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393114839 [org.dataone.client.rest.RestClient]
20161118-17:33:18: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:33:18: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393114839 [org.dataone.client.rest.RestClient]
20161118-17:33:19: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/isAuthorized/testMnCreate_201632393114839?action=read [org.dataone.client.rest.RestClient]
20161118-17:33:19: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:33:20: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:33:20: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:33:50: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-unm-1.test.dataone.org/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:33:50: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:33:50: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:33:50: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:33:50: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:33:51: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:33:51: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnCreate(AuthTokenTestImplementation.java:154)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnCreate(AuthTokenTestImplementation.java:118)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testMnCreate(AuthTokenFunctionalIT.java:31)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:33:51: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUyMzAsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzM6NTAuODQ0KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDQzMH0.iSKDTLODhbqPv9uV-5SXepvzoHDEjqIOvK1mH3WXzqPVTxkq8Lu-vpsS5BD_7oITQLAu_kAEw-L9flqGjapYHBWJqKvsdJG4rz2aMpvzZXhkGtVybXvOOPuel2-pIOBGMLQN9P-JppmgVAIGXtYNUWB5lT-LmbAk8c6SmgQBmlwbE69PDYlWy5j3BuNHtecgMqg-bWF9nAeuKCj5yo64vCkO3hgtdxJBxRLuDR6pHrTJC4INKx0kITSdSA8Q4b1jbmfP-nOfvsTEs3XAcIwrOy4-a-U8JvamcJ0WTuN7-wNh0CAj54H7Yse5dqqmWKtzSSlTqN6tyPmEwQTVvOFeGovCqR4ELTZ7xDD77I5_UDBa9hkTCJgSSARLKl1tY_ER5mx0qE1j8BQtiOoo4KWbaLy-mLOvvs61AXHuEsFJfdQwVzHiwd75ZerDxA6NeCZcPVdjkei4fglYQ0n8q9xO1D_2CGEO8JHYzSKOaxBD_msMBUIycwrrd16UAa30NHb7cqUXc45W4W5ciaa3O9Sq5BPlw6FKWAKlW1_szyZBoSwSgJOv9xxMv9IRSefPyELwA21838f_fh7a1LidiFFBvP0rjOJdxtMQE2iIP_vu69WC99b_uup_o_b6AG7NwlXymrtX1MTpwhRhQXgMuV6XDkNyWLPjmorsavgPUERdo4w [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:33:51: [INFO]: 
***************** running test for testMnCreate(...) vs. node: https://cn-stage-unm-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:33:52: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: POST https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnCreate_201632393351572; FilePart:object = /tmp/mmp.output.759828882829713777.tmp (684336 bytes); FilePart:sysmeta = /tmp/mmp.output.6795416838997361111.tmp (980 bytes);  [org.dataone.client.rest.RestClient]
20161118-17:33:52: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:33:53: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393351572 [org.dataone.client.rest.RestClient]
20161118-17:34:23: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:34:24: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393351572 [org.dataone.client.rest.RestClient]
20161118-17:34:54: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:34:54: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393351572 [org.dataone.client.rest.RestClient]
20161118-17:35:24: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:35:25: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393351572 [org.dataone.client.rest.RestClient]
20161118-17:35:55: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:35:56: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393351572 [org.dataone.client.rest.RestClient]
20161118-17:36:26: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:27: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_201632393351572 [org.dataone.client.rest.RestClient]
20161118-17:36:27: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/isAuthorized/testMnCreate_201632393351572?action=read [org.dataone.client.rest.RestClient]
20161118-17:36:27: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:27: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:27: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:27: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-17:36:27: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:36:28: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:36:28: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnIsAuthorized(AuthTokenTestImplementation.java:236)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnIsAuthorized(AuthTokenTestImplementation.java:229)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testCnIsAuthorized(AuthTokenFunctionalIT.java:36)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:36:28: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUzODcsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6MjcuODQ0KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDU4N30.dOw4xXRuTAUZju9ShDv73d04M4ktIirOjYuCqvKzdC8hglcTIGFyTtvUFeaWRRt1FkpyFMHHlESoDBmHLNXYuQRV8W9KlDfwLffGffA3K318k28QR2f39TRNRkUg-qqaNCq3dyxFc-L0_wTgyMIjvHvLS6_9Fu7NG4DLedH846n8_eT8F8h4cwAKrKU3ar0uVa4nSeG_zNQR1w14__UDclWWiDbT1TntGD1B0Sxko438O3FLESPQKoCK8EvcNsQmMOAuVHi7FNbWqHWy_bUnlCLRsm0_ZzD3NOuMwwE2DkBsDyzecgMwq8PaRu0p_NlWpeF_272RY2iqfKjRVQxKvzNG7IADgBoYyQXr8qb5PCzzI5JrsXDxuupV72qzHNJpBJonGyFd7I6qeDYDDMOHlC-i-j8fStDgcQBOG8k75_X_e5MOy3lBFeTvPN77kGTS0gcjycrp0Fs63Frhbq5dYgBbA_lZU-Jc7aPfZ2I3IdUjoumouXbgIcdK08hzZZoWwCdFrMof6jZRIll0VaBybXSZEV6S_P52anGvNWyP9dZ7Ju67J_GbPbVAaQhw4HWy3Oyc-nKQ9vUp1QOh8fkHkVUQg_D90fP-6szRBoY4MESfzzTn3o8TQf1_O4ECBBEp7IWnSfdDI1wDaP_wgJAdIPmxtJRhzUbVwluDSWyT-dM [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:28: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUzODcsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6MjcuODQ0KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDU4N30.dOw4xXRuTAUZju9ShDv73d04M4ktIirOjYuCqvKzdC8hglcTIGFyTtvUFeaWRRt1FkpyFMHHlESoDBmHLNXYuQRV8W9KlDfwLffGffA3K318k28QR2f39TRNRkUg-qqaNCq3dyxFc-L0_wTgyMIjvHvLS6_9Fu7NG4DLedH846n8_eT8F8h4cwAKrKU3ar0uVa4nSeG_zNQR1w14__UDclWWiDbT1TntGD1B0Sxko438O3FLESPQKoCK8EvcNsQmMOAuVHi7FNbWqHWy_bUnlCLRsm0_ZzD3NOuMwwE2DkBsDyzecgMwq8PaRu0p_NlWpeF_272RY2iqfKjRVQxKvzNG7IADgBoYyQXr8qb5PCzzI5JrsXDxuupV72qzHNJpBJonGyFd7I6qeDYDDMOHlC-i-j8fStDgcQBOG8k75_X_e5MOy3lBFeTvPN77kGTS0gcjycrp0Fs63Frhbq5dYgBbA_lZU-Jc7aPfZ2I3IdUjoumouXbgIcdK08hzZZoWwCdFrMof6jZRIll0VaBybXSZEV6S_P52anGvNWyP9dZ7Ju67J_GbPbVAaQhw4HWy3Oyc-nKQ9vUp1QOh8fkHkVUQg_D90fP-6szRBoY4MESfzzTn3o8TQf1_O4ECBBEp7IWnSfdDI1wDaP_wgJAdIPmxtJRhzUbVwluDSWyT-dM [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:28: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:28: [INFO]: 
***************** running test for testCnIsAuthorized(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:29: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testCnIsAuthorized_token_8 [org.dataone.client.rest.RestClient]
20161118-17:36:29: [INFO]:  ====>>>>> pid of procured test Object: testCnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:31: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/isAuthorized/testCnIsAuthorized_token_8?action=read [org.dataone.client.rest.RestClient]
20161118-17:36:31: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:36:32: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:36:32: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnIsAuthorized(AuthTokenTestImplementation.java:236)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnIsAuthorized(AuthTokenTestImplementation.java:229)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testCnIsAuthorized(AuthTokenFunctionalIT.java:36)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:36:32: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUzOTEsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6MzEuODgxKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDU5MX0.Wcd5LjjLho_wXcX5kNmq3x-JzCx5pQNJzxUrurAcqbJmnfpIgLhF1VX3s8TnXdSk1PPrF3st0zOvuDuccwK0P8SgNOq__u3HqUR7j0KgIYMTOCKPkS2VhggH1a97POuGx-HZfVQLGXf7A8wXT8Mf2RGJyHOFX1pstl_BOnI-Vju0PyMUjmgiGtnc8pEnEACmBLtpU4Scf1Q9BmSM1iFGhq0OkAYCpWlhkOEaw_N4PQMJqO4WH2zalYy0CNm4cPXFckO5cFAaw42tfQf1N0o6MMlwwijcB50ruB23yLvDp3FXHIMT6Ypu-CN0bjsOJ8DVnQVWof9CNfNHl_hAt6iA9Epm8yd-3yFKTRDbbqNuYmRFipNLbdMmQK7d7OtKRFJ7n5XeaFQqMrto-U6eVex1-0197b83Sf6pPuPZ7jPq6D3kTwGy6D_62G3gF7umjY1STWtq_3n1L7JCKHD6121ujO9qKnURDineuFp1HBo1egkFFqtkCMIrdeX_RaCqpHt7JHkZtWdokPERM1wfE-v_wE4xdsgooEWYpFuOCtg_ljSn6mr5CqeY1lpsi0nvuZuWAq7lhqu-QrXj94rXAdMNHg7KhYVdHOSktVOC9g8stQSmXBAfI-MqAClahoBMfg24wTJpfeUA84gv-U4s-KCAL_xGNwtJXbq38d2W9pfsytI [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:32: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUzOTEsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6MzEuODgxKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDU5MX0.Wcd5LjjLho_wXcX5kNmq3x-JzCx5pQNJzxUrurAcqbJmnfpIgLhF1VX3s8TnXdSk1PPrF3st0zOvuDuccwK0P8SgNOq__u3HqUR7j0KgIYMTOCKPkS2VhggH1a97POuGx-HZfVQLGXf7A8wXT8Mf2RGJyHOFX1pstl_BOnI-Vju0PyMUjmgiGtnc8pEnEACmBLtpU4Scf1Q9BmSM1iFGhq0OkAYCpWlhkOEaw_N4PQMJqO4WH2zalYy0CNm4cPXFckO5cFAaw42tfQf1N0o6MMlwwijcB50ruB23yLvDp3FXHIMT6Ypu-CN0bjsOJ8DVnQVWof9CNfNHl_hAt6iA9Epm8yd-3yFKTRDbbqNuYmRFipNLbdMmQK7d7OtKRFJ7n5XeaFQqMrto-U6eVex1-0197b83Sf6pPuPZ7jPq6D3kTwGy6D_62G3gF7umjY1STWtq_3n1L7JCKHD6121ujO9qKnURDineuFp1HBo1egkFFqtkCMIrdeX_RaCqpHt7JHkZtWdokPERM1wfE-v_wE4xdsgooEWYpFuOCtg_ljSn6mr5CqeY1lpsi0nvuZuWAq7lhqu-QrXj94rXAdMNHg7KhYVdHOSktVOC9g8stQSmXBAfI-MqAClahoBMfg24wTJpfeUA84gv-U4s-KCAL_xGNwtJXbq38d2W9pfsytI [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:32: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:32: [INFO]: 
***************** running test for testCnIsAuthorized(...) vs. node: https://cn-stage-ucsb-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:32: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testCnIsAuthorized_token_8 [org.dataone.client.rest.RestClient]
20161118-17:36:32: [INFO]:  ====>>>>> pid of procured test Object: testCnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:33: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/isAuthorized/testCnIsAuthorized_token_8?action=read [org.dataone.client.rest.RestClient]
20161118-17:36:33: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:36:33: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:36:33: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnIsAuthorized(AuthTokenTestImplementation.java:236)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnIsAuthorized(AuthTokenTestImplementation.java:229)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testCnIsAuthorized(AuthTokenFunctionalIT.java:36)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:36:33: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUzOTMsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6MzMuMTMxKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDU5M30.ZPms9A7DxE5qi-xs6Z2UGIWxmTtqdKBySLZIF69_GYdFl7HAQKQE2VjK4NWxanMiLPmlNTW3BTKAbnRLekE0Ah3P7ceWGtjEZ1qwJRrWQgD0Aehwvf4_48-K-ITSh4ZZK0dBuhLmY4oGg_0nexpr82DLB-LvWTBS0RaR36WRTKjlvmvb1175ZucmoVGf_QdA87UFjVxk1zYdXvkUpHpsKhP5CYMz7M8s8IWjnVamUwBU9srEckIZ5IDG74fXOptImZ54CrlfeXCQ_hxww7YIQE01g54kmpG9_gyFJy7IJgaEqzlcNo6eH8aEcwaTKajGFiPvQC7MHjQi4kQVn_n3yEJX4UPj-6Foi3IYtUycyRvBHMkgLrG6IFkAdwRL8XMOCHhuXWQg2pKa1gYGxAdrqibuiR0XaSR_8GMbDcY6VPI2km5Yrq3l7AyhakAsCAlVuZ3xbu2GiUjxQVoR_mQPwZ23dNS780_ROzRQVRkB_yqkqTHz_z6oI5yfOzSeOOfRqDyPGo32QcdbQvokcHfCgUzkeuRC2X_6Z2lo2Wp-HW2tyCfFgqya-LaHkkUIfpT8z58Xpk7aKT9FL0mDBAVmc-Od0ljLxL37t5rnfiH49aJhS6rSK2Zjxhch7Q8z3j-TJn9Yx8M_7QMa0QuE2RUOtw-BE360iFOKDD6PL39m-tk [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:33: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTUzOTMsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6MzMuMTMxKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDU5M30.ZPms9A7DxE5qi-xs6Z2UGIWxmTtqdKBySLZIF69_GYdFl7HAQKQE2VjK4NWxanMiLPmlNTW3BTKAbnRLekE0Ah3P7ceWGtjEZ1qwJRrWQgD0Aehwvf4_48-K-ITSh4ZZK0dBuhLmY4oGg_0nexpr82DLB-LvWTBS0RaR36WRTKjlvmvb1175ZucmoVGf_QdA87UFjVxk1zYdXvkUpHpsKhP5CYMz7M8s8IWjnVamUwBU9srEckIZ5IDG74fXOptImZ54CrlfeXCQ_hxww7YIQE01g54kmpG9_gyFJy7IJgaEqzlcNo6eH8aEcwaTKajGFiPvQC7MHjQi4kQVn_n3yEJX4UPj-6Foi3IYtUycyRvBHMkgLrG6IFkAdwRL8XMOCHhuXWQg2pKa1gYGxAdrqibuiR0XaSR_8GMbDcY6VPI2km5Yrq3l7AyhakAsCAlVuZ3xbu2GiUjxQVoR_mQPwZ23dNS780_ROzRQVRkB_yqkqTHz_z6oI5yfOzSeOOfRqDyPGo32QcdbQvokcHfCgUzkeuRC2X_6Z2lo2Wp-HW2tyCfFgqya-LaHkkUIfpT8z58Xpk7aKT9FL0mDBAVmc-Od0ljLxL37t5rnfiH49aJhS6rSK2Zjxhch7Q8z3j-TJn9Yx8M_7QMa0QuE2RUOtw-BE360iFOKDD6PL39m-tk [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:33: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:33: [INFO]: 
***************** running test for testCnIsAuthorized(...) vs. node: https://cn-stage-unm-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:33: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testCnIsAuthorized_token_8 [org.dataone.client.rest.RestClient]
20161118-17:36:33: [INFO]:  ====>>>>> pid of procured test Object: testCnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:34: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/isAuthorized/testCnIsAuthorized_token_8?action=read [org.dataone.client.rest.RestClient]
20161118-17:36:34: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:34: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:34: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:34: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-17:36:34: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:35: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:35: [INFO]: MN included in test: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:35: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:35: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://test.piscoweb.org/catalog/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:35: [INFO]: MN included in test: http://test.piscoweb.org/catalog/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:35: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:35: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://seadva-test.d2i.indiana.edu/sead/rest/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:35: [INFO]: MN excluded from test: http://seadva-test.d2i.indiana.edu/sead/rest/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:35: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:36: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-demo-11.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:36: [INFO]: MN included in test: https://mn-demo-11.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:36: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:36: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://gmn-s.lternet.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:36: [INFO]: MN included in test: https://gmn-s.lternet.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:36: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:37: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone-dev.ecoinformatics.org.au/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:37: [INFO]: MN excluded from test: https://dataone-dev.ecoinformatics.org.au/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:37: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:39: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.tamucc.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:39: [INFO]: MN excluded from test: https://dataone.tamucc.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:39: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:39: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://dataone.neonscience.org/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:39: [INFO]: MN included in test: http://dataone.neonscience.org/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:39: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:40: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://test.arcticdata.io/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:40: [INFO]: MN included in test: https://test.arcticdata.io/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:40: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:40: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://dataone.ornith.cornell.edu/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:40: [INFO]: MN included in test: http://dataone.ornith.cornell.edu/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:40: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:41: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.researchworkspace.com/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:41: [INFO]: MN excluded from test: https://dataone.researchworkspace.com/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:41: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:36:41: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:36:41: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnIsAuthorized(AuthTokenTestImplementation.java:291)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnIsAuthorized(AuthTokenTestImplementation.java:284)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testMnIsAuthorized(AuthTokenFunctionalIT.java:41)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:36:41: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0MDEsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6NDEuMzc3KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDYwMX0.JgYVOAlHzGlj2BYiOOtGeOWm1-daSJREP935Mq8sAWNRW9AadsZurOZoljfoM_2LhUVDazXKvyYgmTQS1GLW2_WDc0KBvYFl-AToa25QsSPndOQniUwUfKztt7dvnJKEFw6RMIn6zMr835xn1jI7Yelzp2SWYnM69fJMA0XMjY8L8Rk2HBam7W2skLjz7_qSr83svhmWcZMGX-Vy25-XZqu-2lrwJSdzI_DuqmELHwb75805-6MULNORKRln8bJBjs5GIk0FvNbTd3BUzGEs7ZuoltEBLGs0o-cJa_-dEuD6DZdW3RXJmhbHTrdtMdW480-QflszgzYalkhs_AleqwqPhHpmfbcTuyFYjmojcXi2V65lU35w0eRFfTzVKxfcSF6tpgfoOEcXujtEX0_w5LW_XL1aZvDeFhaOnT3LS-oSWmzwBL-UEl-V-8PDpBDMMcyc1IpNJCONVJkED_1h4fAlxKHi9Rx8JFWKEc3tlq5VhLkOAnjG-F7jRTqrQIu9wfsAnTIRxP_FnoA52Gt0m9QJqqbkxFqOr756F9Fi02jpiEgLwbQR1lmBQqBx1IAZgTc7r3syf5n56mFuD7ZRCJktCP9lEL_kecKX3jpx9BBmdHNHZxWlYBoqndPqe5VEg98ApeoUcAfc3QijqHwnO52e5fKRZa5sZOXUjxfh2mk [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:41: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:41: [INFO]: 
***************** running test for testMnIsAuthorized(...) vs. node: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/meta/testMnIsAuthorized_token_8 [org.dataone.client.rest.RestClient]
20161118-17:36:42: [INFO]:  ====>>>>> pid of procured test Object: testMnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/isAuthorized/testMnIsAuthorized_token_8?action=read [org.dataone.client.rest.RestClient]
20161118-17:36:42: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:36:42: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:36:42: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnIsAuthorized(AuthTokenTestImplementation.java:291)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnIsAuthorized(AuthTokenTestImplementation.java:284)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testMnIsAuthorized(AuthTokenFunctionalIT.java:41)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:36:42: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0MDIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6NDIuNTE4KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDYwMn0.h68dl5riKNIDLDLdoj1AgquMTRCQhnyvH5D1Dhmfg26wgnKoG1ieuaDF29PpSe5oIrWk8Ka5VkyrYgCdQ4Pmg9fD5lEbvH8AnGSrO6ZjTtl09McqRYLIA3y3HtTaDI7tSJVZwY8sQ0nKdIK84XNJWf9iMr_rqAUGjntI_HdqKH9x49vrjPFfVbJgSRFSIVb6KFZTRftm8SYcySBNpb36ZwtxoaUus1i1DqHZ9RPDyPOxfOrc2jr-hCwikia9S4Mhl_1hNLM2-qOUBu3eRmIfULtDA3Duz2hkKMdR4VjwMCLTFf-Z26Qr8N0SZiWzHJDUnAY45le4h8nHRo1K2hv4yIwVcNG0UlYT4qHKXBxB21dHz1Sst9MEyDxMwYX-3wl8mICceGaNPXuaVaxzGDszjBbhOHg6m4k1BZtFrDmUXXtlyOBKO6CPiIujbZeLvJB00vmXu-6PEv8LoTVqM-6XCboPSyuQqLevuy5OnTi9MI50hnn1jitdOV8KgPExXMqNv1lrRIkkAC8tCTrc1ayp1EC1silfPuAuPx6GO_nxGZAReHX1b_ppmrqaqDbYkBsV7YdiJl7ZJ7qtyh-ray_v4jPmpywNY8aEX_8IVIcypnCeGWZrtnXxx4jbr0wKmaKYm-wrLH8SWtp1hj_mfINZ24bi1Qz7DqoynPOVvauJXkA [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:42: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: 
***************** running test for testMnIsAuthorized(...) vs. node: http://test.piscoweb.org/catalog/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://test.piscoweb.org/catalog/d1/mn/v2/meta/testMnIsAuthorized_token_8 [org.dataone.client.rest.RestClient]
20161118-17:36:42: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: creating a test object.  pid = testMnIsAuthorized_token_8 on http://test.piscoweb.org/catalog/d1/mn/v2 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:42: [INFO]: SystemMetadata for pid: testMnIsAuthorized_token_8
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<ns3:systemMetadata xmlns:ns2="http://ns.dataone.org/service/types/v1" xmlns:ns3="http://ns.dataone.org/service/types/v2.0">
    <serialVersion>1</serialVersion>
    <identifier>testMnIsAuthorized_token_8</identifier>
    <formatId>eml://ecoinformatics.org/eml-2.0.1</formatId>
    <size>17181</size>
    <checksum algorithm="MD5">03ce09c6b4c3efc1681f3023402998a2</checksum>
    <submitter>CN=urn:node:cnStageUNM1,DC=dataone,DC=org</submitter>
    <rightsHolder>http://orcid.org/0000-0002-1825-0097</rightsHolder>
    <accessPolicy>
        <allow>
            <subject>http://orcid.org/0000-0002-1825-0097</subject>
            <permission>read</permission>
        </allow>
    </accessPolicy>
    <replicationPolicy replicationAllowed="false" numberReplicas="0"/>
    <dateUploaded>2016-11-18T17:36:42.992+00:00</dateUploaded>
    <dateSysMetadataModified>2016-11-18T17:36:42.992+00:00</dateSysMetadataModified>
    <originMemberNode>urn:node:mnStagePISCO</originMemberNode>
    <authoritativeMemberNode>urn:node:mnStagePISCO</authoritativeMemberNode>
</ns3:systemMetadata>
 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: POST http://test.piscoweb.org/catalog/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnIsAuthorized_token_8; FilePart:object = /tmp/mmp.output.5492639009567729098.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.1833849408273677705.tmp (1133 bytes);  [org.dataone.client.rest.RestClient]
20161118-17:36:43: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-17:36:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:43: [INFO]: MN included in test: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://test.piscoweb.org/catalog/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:43: [INFO]: MN included in test: http://test.piscoweb.org/catalog/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://seadva-test.d2i.indiana.edu/sead/rest/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:43: [INFO]: MN excluded from test: http://seadva-test.d2i.indiana.edu/sead/rest/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-demo-11.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:43: [INFO]: MN included in test: https://mn-demo-11.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://gmn-s.lternet.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:43: [INFO]: MN included in test: https://gmn-s.lternet.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:43: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:44: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone-dev.ecoinformatics.org.au/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:44: [INFO]: MN excluded from test: https://dataone-dev.ecoinformatics.org.au/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:44: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:44: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.tamucc.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:44: [INFO]: MN excluded from test: https://dataone.tamucc.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:44: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:44: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://dataone.neonscience.org/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:44: [INFO]: MN included in test: http://dataone.neonscience.org/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:44: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:45: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://test.arcticdata.io/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:45: [INFO]: MN included in test: https://test.arcticdata.io/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:45: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:45: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://dataone.ornith.cornell.edu/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:45: [INFO]: MN included in test: http://dataone.ornith.cornell.edu/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:45: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:45: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.researchworkspace.com/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:36:45: [INFO]: MN excluded from test: https://dataone.researchworkspace.com/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:45: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:36:46: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:36:46: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnUpdate(AuthTokenTestImplementation.java:343)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnUpdate(AuthTokenTestImplementation.java:336)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testMnUpdate(AuthTokenFunctionalIT.java:46)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:36:46: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0MDUsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6MzY6NDUuODczKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDYwNX0.VISWSJazLFc3JXYpn9nphlYUsi4M7t9CkdRhJTDmLVnnWcTG-VLbOSJnLjcwdI1534_hGFj5ewSZWPurpHGxeI0s77zd-EGfJ3B51Cs8VkxmzQsCKwVt58iXGaJ66QjYWiXGC5RK497gvxBQ7qviu_1e9gi5utL-yc8x53R3zTPsMzOdGav6SE6SRuKIcAxaMXmJ4onldXSF-lFelnIWT3d_YkDaS7vkrSIjfDS6j2_OCjuan02fenzh_JRdLoEUMi4Y7T1nkOGjgy9cDFd5AKkDrt22nhOCUFRkB-kLH8zkAtW04ohj6p1e_0Sx_ZlO_7eDNIQjg7RsqHpkIMsaZQe3pk8c1JEav9Ev0FqypHGDebaamhdZ-P4vITFLsdGT3_sIOyufw63bg8m_4N5tCAu1AYfsChNaVjKDjClCC0-RTsiTP_LOKUAH7Qlx0fTrHPItwEVC_ciL8iaqtGv6SIT-ncVytFPL4uhpoj5vJxwVihKNWXky6DBZLHb_metK56t-tb64oRQDgEJFyg0nX0tTxxWwkIRQwTSSuFmwPRyM70DM8lNY187guv45iaIlXZ6zy2-o5DeewuAN4QVbgmxJjvs9HnRHpszU11nYxlPOmKeBYNhljShaozEHYQ0j4f9pu4IT9iWJZGIR5TBGNP4bf4TTjKr0_76vCV0Os4I [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:36:46: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:46: [INFO]: 
***************** running test for testMnUpdate(...) vs. node: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:46: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:46: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:46: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:46: [INFO]: creating a test object.  pid = testMnUpdate_token_8_201632393646341 on https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:46: [INFO]: SystemMetadata for pid: testMnUpdate_token_8_201632393646341
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<ns3:systemMetadata xmlns:ns2="http://ns.dataone.org/service/types/v1" xmlns:ns3="http://ns.dataone.org/service/types/v2.0">
    <serialVersion>1</serialVersion>
    <identifier>testMnUpdate_token_8_201632393646341</identifier>
    <formatId>eml://ecoinformatics.org/eml-2.0.1</formatId>
    <size>17181</size>
    <checksum algorithm="MD5">03ce09c6b4c3efc1681f3023402998a2</checksum>
    <submitter>CN=urn:node:cnStageUNM1,DC=dataone,DC=org</submitter>
    <rightsHolder>http://orcid.org/0000-0002-1825-0097</rightsHolder>
    <accessPolicy>
        <allow>
            <subject>http://orcid.org/0000-0002-1825-0097</subject>
            <permission>write</permission>
        </allow>
        <allow>
            <subject>public</subject>
            <permission>read</permission>
        </allow>
    </accessPolicy>
    <replicationPolicy replicationAllowed="false" numberReplicas="0"/>
    <dateUploaded>2016-11-18T17:36:46.349+00:00</dateUploaded>
    <dateSysMetadataModified>2016-11-18T17:36:46.349+00:00</dateSysMetadataModified>
    <originMemberNode>urn:node:mnStageUCSB2</originMemberNode>
    <authoritativeMemberNode>urn:node:mnStageUCSB2</authoritativeMemberNode>
</ns3:systemMetadata>
 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:47: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: POST https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnUpdate_token_8_201632393646341; FilePart:object = /tmp/mmp.output.3207867124370854764.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.7210734571018380172.tmp (1257 bytes);  [org.dataone.client.rest.RestClient]
20161118-17:36:47: [INFO]: object created.  pid = testMnUpdate_token_8_201632393646341 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:36:58: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/meta/testMnUpdate_token_8_201632393646341 [org.dataone.client.rest.RestClient]
20161118-17:37:00: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: PUT https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/object/testMnUpdate_token_8_201632393646341; MMP message has: ParamPart:newPid = testMnUpdate_token_8_20163239365869; FilePart:object = /tmp/mmp.output.2062249136778901821.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.2234926511185501514.tmp (1244 bytes);  [org.dataone.client.rest.RestClient]
20161118-17:37:00: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:37:00: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:37:00: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnUpdate(AuthTokenTestImplementation.java:343)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testMnUpdate(AuthTokenTestImplementation.java:336)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testMnUpdate(AuthTokenFunctionalIT.java:46)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:37:00: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0MjAsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzc6MDAuMDIwKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDYyMH0.FAYNdHeWXN2dgmw3P4PqpUnD8LDTOsUhflWl7aTP9BqfV546RNxtEfZqb27FXuJn7pxbZlbOELSXfALWfFcdfwLDplVmuUDKk6Z1j4YiIP0C9eqdJvAKi5OFeKJwxLDqsqFJ68rbU-qqzHGYEYDLToO_ab16ktgu30521B0OWt-7SCGF4HvF-gtOhBxGL0dNa1B-gBLKwrJTAaMlhrX8p42Zuv02kx6Y9drrnciHbedE6tQoWNWDqBXgaKJ5fvWdejs9KKYDtfsLVj766akZxjiJv5peZIyndcXRSuIHEkzsOPP0EqeG1A6_sbirfiMU0bTIoAICHyT6kCSGrrotE6J8LiIO2Qpe3caq2r29X5B7fwR7HgigEcqaZL0s6qe0DjykFCE_LhbYExpATh_9ikMyOCblRGeiHEFV9DCfQ6BIqO2dfrKPkWmWY13arya1h-_YYsCEfzkzNXeEwc3_qEO-giRLX6nyI0_l8ZJnZIv9gQb58kRXyInCYlYCa_AUeCalwOfERza528nEthYMpFn4fcNy9kogyihy0kX_19wyrYHFEUPXKZdRAD2rQbC62KKlvUrnd04kTwGTU-i2V6yEUbg7yNZbaC-OQ_F1Ej5K2QvevnceYYvNk1BGkmHR6eKTMqPJxLaThGpz5fmzIZGN07AYld0mIiMZAHlGhQI [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:37:00: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: 
***************** running test for testMnUpdate(...) vs. node: http://test.piscoweb.org/catalog/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: creating a test object.  pid = testMnUpdate_token_8_20163239370428 on http://test.piscoweb.org/catalog/d1/mn/v2 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: SystemMetadata for pid: testMnUpdate_token_8_20163239370428
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<ns3:systemMetadata xmlns:ns2="http://ns.dataone.org/service/types/v1" xmlns:ns3="http://ns.dataone.org/service/types/v2.0">
    <serialVersion>1</serialVersion>
    <identifier>testMnUpdate_token_8_20163239370428</identifier>
    <formatId>eml://ecoinformatics.org/eml-2.0.1</formatId>
    <size>17181</size>
    <checksum algorithm="MD5">03ce09c6b4c3efc1681f3023402998a2</checksum>
    <submitter>CN=urn:node:cnStageUNM1,DC=dataone,DC=org</submitter>
    <rightsHolder>http://orcid.org/0000-0002-1825-0097</rightsHolder>
    <accessPolicy>
        <allow>
            <subject>http://orcid.org/0000-0002-1825-0097</subject>
            <permission>write</permission>
        </allow>
        <allow>
            <subject>public</subject>
            <permission>read</permission>
        </allow>
    </accessPolicy>
    <replicationPolicy replicationAllowed="false" numberReplicas="0"/>
    <dateUploaded>2016-11-18T17:37:00.435+00:00</dateUploaded>
    <dateSysMetadataModified>2016-11-18T17:37:00.435+00:00</dateSysMetadataModified>
    <originMemberNode>urn:node:mnStagePISCO</originMemberNode>
    <authoritativeMemberNode>urn:node:mnStagePISCO</authoritativeMemberNode>
</ns3:systemMetadata>
 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: POST http://test.piscoweb.org/catalog/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnUpdate_token_8_20163239370428; FilePart:object = /tmp/mmp.output.9052533469160982670.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.5201819991960547334.tmp (1256 bytes);  [org.dataone.client.rest.RestClient]
20161118-17:37:00: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:00: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-17:37:00: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:01: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:37:01: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:31: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-unm-1.test.dataone.org/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:37:31: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:31: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:37:32: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:37:32: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:37:32: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:37:32: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnQuery(AuthTokenTestImplementation.java:494)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnQuery(AuthTokenTestImplementation.java:455)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testCnQuery(AuthTokenFunctionalIT.java:51)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:37:32: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0NTIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzc6MzIuMTMyKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDY1Mn0.iQPTtocd6VFikp6X680a64Ukh2xE8z9wmTXnTrD_FQpU8U4ElJIegGWZDUvDHN5ZHI15UNFia6JbZWEY-xrUQHKMUR2dRlQfE8eActhQx2IUO7-r3pbt_wJzgtQQFH6W28CYoaInlJbLC04QWFu9_AJn0FL6TOzTW-JUjKXZ6vNM3YL7e6dZhmp1H8IcLSHiSso9-YFssFcAJzuLHPTKPt5VSCqSANHzAYcIrYPfYksQm6y3OkCduC2x2YgAPa-Ynz8ekt3qpyG5MUoBv1o7jJIzU1UskL691JpMdiycU_i5j837n4OzozFDas0Q0COszF6AXBo0jUEiZF7nQgCJNUsWKyO_EEG0l4GRLmcP1susaQMnJtfIOb1FhVImga6jDfgJe4RNSDdsuzK26xTob8-xH-uUtIslE4Oe9eQ1kAjpGvq9ZnoD6_12wFed6F8Y15PfP-kwMEoG5Ob6lQaZxRNQzfjpmu_FPEpGRFKVJdWMdmxLiZ36ebKJxMQyfNFHTbXMFDdwD7QXFQ4LKjQVdvQNSEGaHjJk5nPbmZHSjBG6uOK5-0VMK2KbcZK9xkX-qkseTaBcB3QKUnHWYsG1Hr5LQTsbPJy7Ysbyat44vF9ePwim8OpKyygg_zqSspabiOrWdwnMBj4BKRtz4YCHNncTeckBsFZYopLpF8fE7MA [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:37:32: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0NTIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzc6MzIuMTMyKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDY1Mn0.iQPTtocd6VFikp6X680a64Ukh2xE8z9wmTXnTrD_FQpU8U4ElJIegGWZDUvDHN5ZHI15UNFia6JbZWEY-xrUQHKMUR2dRlQfE8eActhQx2IUO7-r3pbt_wJzgtQQFH6W28CYoaInlJbLC04QWFu9_AJn0FL6TOzTW-JUjKXZ6vNM3YL7e6dZhmp1H8IcLSHiSso9-YFssFcAJzuLHPTKPt5VSCqSANHzAYcIrYPfYksQm6y3OkCduC2x2YgAPa-Ynz8ekt3qpyG5MUoBv1o7jJIzU1UskL691JpMdiycU_i5j837n4OzozFDas0Q0COszF6AXBo0jUEiZF7nQgCJNUsWKyO_EEG0l4GRLmcP1susaQMnJtfIOb1FhVImga6jDfgJe4RNSDdsuzK26xTob8-xH-uUtIslE4Oe9eQ1kAjpGvq9ZnoD6_12wFed6F8Y15PfP-kwMEoG5Ob6lQaZxRNQzfjpmu_FPEpGRFKVJdWMdmxLiZ36ebKJxMQyfNFHTbXMFDdwD7QXFQ4LKjQVdvQNSEGaHjJk5nPbmZHSjBG6uOK5-0VMK2KbcZK9xkX-qkseTaBcB3QKUnHWYsG1Hr5LQTsbPJy7Ysbyat44vF9ePwim8OpKyygg_zqSspabiOrWdwnMBj4BKRtz4YCHNncTeckBsFZYopLpF8fE7MA [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:37:32: [INFO]: 
***************** running test for testCnQuery(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:33: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/meta/testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:37:33: [INFO]:  ====>>>>> pid of procured test Object: testCnQuery_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:33: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:37:33: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:37:35: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/query/solr/?q=identifier:testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:37:35: [INFO]: CN.query ran against GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testCnQuery_token_8 for pid testCnQuery_token_8 [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:37:36: [INFO]: query results: numFound = 1   <doc>s returned = 1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:36: [INFO]: CN.query results have a count of 1 logs and contain 1 returned docs. [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:37:36: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:37:36: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:37:36: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:06: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-unm-1.test.dataone.org/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:38:06: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:07: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:38:07: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:38:07: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:38:08: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:38:08: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnQuery(AuthTokenTestImplementation.java:494)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnQuery(AuthTokenTestImplementation.java:455)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testCnQuery(AuthTokenFunctionalIT.java:51)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:38:08: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0ODcsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzg6MDcuNjMwKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDY4N30.WY_mXpGhvCVK9AosqFk-Al-LSLI0AjWP9P3haUGQTiyOP9C2tr7YkO5qGPvEVrTwx-qostWhGxu-NMR0kOY8bTrZ6cNiG8zBmhGZqnbDX_ipvSzMYJM5m1_YCvxm8XVUz5Zoaxl5qAmGlf_Pp44pSOkmeXBZHdTRBQ-rx5C82nkp-29nUHzZuZp_Wbro7K0iBODArSnZjA2uHCKKGzan7wgfc6Xv8yVr0m-scPnEkCQTiLtwKB-1QIFgz1HiTYERONrqcPEMHKvCnK-a580V0xGpNGb9P5FMTTztvgivQl_cuLTmc9n8jd179D5xUpiROqPoGZHsSp_zE_AQw7rLN-ga_d2evhddhrveoYPK8h1YGVelmxN3K4v2-KS_db2YBLlnXFbXUef3I4_Y_0GwI6HuYP-6ZTGtEQD72stl4fiYTib_eUA46WOzUAdAUyklgyJUjsLaYTvg-P6dDy_xknyjDaSiAzeTfB0PLzMxxuURxTThm9H6Uujr9gd5cPgruN0aibmnDtT3sC8nOaKCnMasHsUShY3JmAMBgFJeF9xMrRmCIRggvqK6IF1KWilBHWdYm9KOhAWSpmfbs-p3jKWY6vvRTTJahx90YChS6zZmuQpjmyoc5OWoNVCaLUdjV08ehDLwwVmbiolxDDjawYoFpRo0d12JGCZacWwOBt8 [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:08: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU0ODcsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzg6MDcuNjMwKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDY4N30.WY_mXpGhvCVK9AosqFk-Al-LSLI0AjWP9P3haUGQTiyOP9C2tr7YkO5qGPvEVrTwx-qostWhGxu-NMR0kOY8bTrZ6cNiG8zBmhGZqnbDX_ipvSzMYJM5m1_YCvxm8XVUz5Zoaxl5qAmGlf_Pp44pSOkmeXBZHdTRBQ-rx5C82nkp-29nUHzZuZp_Wbro7K0iBODArSnZjA2uHCKKGzan7wgfc6Xv8yVr0m-scPnEkCQTiLtwKB-1QIFgz1HiTYERONrqcPEMHKvCnK-a580V0xGpNGb9P5FMTTztvgivQl_cuLTmc9n8jd179D5xUpiROqPoGZHsSp_zE_AQw7rLN-ga_d2evhddhrveoYPK8h1YGVelmxN3K4v2-KS_db2YBLlnXFbXUef3I4_Y_0GwI6HuYP-6ZTGtEQD72stl4fiYTib_eUA46WOzUAdAUyklgyJUjsLaYTvg-P6dDy_xknyjDaSiAzeTfB0PLzMxxuURxTThm9H6Uujr9gd5cPgruN0aibmnDtT3sC8nOaKCnMasHsUShY3JmAMBgFJeF9xMrRmCIRggvqK6IF1KWilBHWdYm9KOhAWSpmfbs-p3jKWY6vvRTTJahx90YChS6zZmuQpjmyoc5OWoNVCaLUdjV08ehDLwwVmbiolxDDjawYoFpRo0d12JGCZacWwOBt8 [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:08: [INFO]: 
***************** running test for testCnQuery(...) vs. node: https://cn-stage-ucsb-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:08: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/meta/testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:38:08: [INFO]:  ====>>>>> pid of procured test Object: testCnQuery_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:08: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:08: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:38:09: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/query/solr/?q=identifier:testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:38:09: [INFO]: CN.query ran against GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testCnQuery_token_8 for pid testCnQuery_token_8 [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:09: [INFO]: query results: numFound = 1   <doc>s returned = 1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:09: [INFO]: CN.query results have a count of 1 logs and contain 1 returned docs. [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:09: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:10: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:38:10: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:40: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-unm-1.test.dataone.org/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:38:40: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:40: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-17:38:40: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/node [org.dataone.client.rest.RestClient]
20161118-17:38:40: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:38:41: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:38:41: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnQuery(AuthTokenTestImplementation.java:494)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testCnQuery(AuthTokenTestImplementation.java:455)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testCnQuery(AuthTokenFunctionalIT.java:51)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:38:41: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU1MjAsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzg6NDAuNzk0KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDcyMH0.eAaIsR8bEgQW0XE1ScDH9I3QaKte2870thGevk-d1X0n1OXad40rk5RV1mvmN4rRrqVjJXkFPfAqIKr9aC2dxN6NbU7XbgfzQUxlQCKyvInFSFUljtf1F9qSszprMbfmM4Yl7bQXWycVSKnX54jAYs58ulu6o8E0Lx94fH5gljqJwBV_l4uEBKt7akYvzZZSMf2rgwBctUmRksz-_fxZKRCTJx5WDwiJBfT7k-cfwnJQEhKGHNcTE7D6ImrPmwf7BpKDv4CZ7lOXx57ZQyGDn9bJrBmxeOVZ1gFRz0nT3FM69jN__0SsLq7ha0yL4BUMlJL1ESVC4PwINf8tOdyPz07Cjo8a1hf6HtoMmdbkf-2-Pn0t31fTNLQH_6Boysm2VV9QKsAHx0mjipGJsJZaUZSqbae4fq0EDXYj6ddaM36Ut8Bh_T97rzR1BZlLku6Etlch0MSqoAQCkw9MF09oziuKexMLsdvOi20Q5icbkt2nlvr2rqAKnu8hvNueEI8w4wB-g1um1Lado79wncoTW031p23EN-Q-K-M5Ime_8MdOxrTs7EVu49S65qi53k0L9u7Zqk1zmZ0RtW786LK7Dh82vV5HaXP7IIIwoa9GtNJ_SoBnsvavE4c5ffS5zdkzlNMbtLN9RDCCI8hg3vr791P_uQdphO95u-RnkmJXO6Q [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:41: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU1MjAsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzg6NDAuNzk0KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDcyMH0.eAaIsR8bEgQW0XE1ScDH9I3QaKte2870thGevk-d1X0n1OXad40rk5RV1mvmN4rRrqVjJXkFPfAqIKr9aC2dxN6NbU7XbgfzQUxlQCKyvInFSFUljtf1F9qSszprMbfmM4Yl7bQXWycVSKnX54jAYs58ulu6o8E0Lx94fH5gljqJwBV_l4uEBKt7akYvzZZSMf2rgwBctUmRksz-_fxZKRCTJx5WDwiJBfT7k-cfwnJQEhKGHNcTE7D6ImrPmwf7BpKDv4CZ7lOXx57ZQyGDn9bJrBmxeOVZ1gFRz0nT3FM69jN__0SsLq7ha0yL4BUMlJL1ESVC4PwINf8tOdyPz07Cjo8a1hf6HtoMmdbkf-2-Pn0t31fTNLQH_6Boysm2VV9QKsAHx0mjipGJsJZaUZSqbae4fq0EDXYj6ddaM36Ut8Bh_T97rzR1BZlLku6Etlch0MSqoAQCkw9MF09oziuKexMLsdvOi20Q5icbkt2nlvr2rqAKnu8hvNueEI8w4wB-g1um1Lado79wncoTW031p23EN-Q-K-M5Ime_8MdOxrTs7EVu49S65qi53k0L9u7Zqk1zmZ0RtW786LK7Dh82vV5HaXP7IIIwoa9GtNJ_SoBnsvavE4c5ffS5zdkzlNMbtLN9RDCCI8hg3vr791P_uQdphO95u-RnkmJXO6Q [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:41: [INFO]: 
***************** running test for testCnQuery(...) vs. node: https://cn-stage-unm-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:41: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/meta/testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:38:41: [INFO]:  ====>>>>> pid of procured test Object: testCnQuery_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:41: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:42: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:38:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/query/solr/?q=identifier:testCnQuery_token_8 [org.dataone.client.rest.RestClient]
20161118-17:38:43: [INFO]: CN.query ran against GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testCnQuery_token_8 for pid testCnQuery_token_8 [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:43: [INFO]: query results: numFound = 1   <doc>s returned = 1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:43: [INFO]: CN.query results have a count of 1 logs and contain 1 returned docs. [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:43: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:43: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:43: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:43: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-17:38:43: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-17:38:44: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/accounts/http:%2F%2Forcid.org%2F0000-0002-1825-0097 [org.dataone.client.rest.RestClient]
20161118-17:38:44: [WARN]: [LDAP: error code 32 - No Such Object] [org.dataone.portal.TokenGenerator]
org.dataone.service.exceptions.NotFound: [LDAP: error code 32 - No Such Object]
	at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
	at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
	at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
	at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
	at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1481)
	at org.dataone.client.v1.impl.MultipartCNode.getSubjectInfo(MultipartCNode.java:1462)
	at org.dataone.portal.TokenGenerator.getSession(TokenGenerator.java:199)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.getTokenSesssion(AuthTokenTestImplementation.java:65)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testEchoCredentials(AuthTokenTestImplementation.java:89)
	at org.dataone.integration.it.testImplementations.AuthTokenTestImplementation.testEchoCredentials(AuthTokenTestImplementation.java:82)
	at org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT.testEchoCredentials(AuthTokenFunctionalIT.java:26)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.rules.Verifier$1.evaluate(Verifier.java:34)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at com.sun.proxy.$Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
20161118-17:38:44: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTU1MjMsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTc6Mzg6NDMuNjQzKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5MDcyM30.FaXh_ZlJ6IPBQb5K_LPGE8g13GovzeQoYKEJ3qSGXosW5r8a0YIzQQJj_pmhwd3-hgw5A5nyh-dMTP5AygtpDQwi1ylW3eLeKmJ_JB3U4RzX5cNzf1ikKV4330BbamIQzIpeAxxpafhE1BITL4XMREk8HEYgnivlECYuJhPQXRFDTlXenTst-ooH8I1lTuzvKbMFHx0tbUeVUA-qtpiTh7nJ5zWvG6TT_pJOBKtYxYYDa_Mi-rOyKzkuzEwcxPUwIlvlpA9LEIldJMvOhnyNEFqPBIntqNtxWtZqdpAYmBwXqB8EaIx6ldCHpIkxc6GMJyp0wqoq6tGvnkc8xVvUe0PM_xBTgKyJBuUHYqbWjaRBpyScBgarA0r4fB2qFk9Ic40hHzn5RHIrVRH8j3qGnSYYdKLzT1RG677wWWtDNbryyYYC1FMu0vkaLc9jnz7WfTtUTeNefg1FYBM4eROgAFuJ0y2A8fX5POCNQe26pToAb997bzxbN-OTB1rgEKT6JEqsrLxO5ja8q2ROUtZZBGgRhdGmgPt9oApeHZqs8LsBxI5hVbjc8r33EE9pVlSpPnVW3xYW1rBwRQAgtlXDoJxXneaRlAVSvkQ6yuW4vk5Hq4upImme__TAJWgpY0z29f6ImCNASwtlNmVWfXoMczsrhkPrIS6lyT87eHkRGz8 [org.dataone.integration.it.ContextAwareAdapter]
20161118-17:38:44: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:44: [INFO]: 
***************** running test for testEchoCredentials(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-17:38:44: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/diag/subject [org.dataone.client.rest.RestClient]
Tests run: 6, Failures: 3, Errors: 0, Skipped: 0, Time elapsed: 706.581 sec <<< FAILURE!

Results :

Failed tests: 
  testMnIsAuthorized(org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT): Unable to create object (testMnIsAuthorized_token_8), got TestIterationEndingException : unexpected client-side exception encountered when trying to create test object : Unable to create test object!/Could not resolve multipart files: Processing of multipart/form-data request failed. /data/metacattest/temporary/upload__11cedfdb_1582c7fe102__8000_00055346.tmp (Permission denied) from POST http://test.piscoweb.org/catalog/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnIsAuthorized_token_8; FilePart:object = /tmp/mmp.output.5492639009567729098.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.1833849408273677705.tmp (1133 bytes); 
  testMnUpdate(org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT): Unable to create object (testMnUpdate_token_8_20163239370428), got ClientSideException : Unable to create test object!/Could not resolve multipart files: Processing of multipart/form-data request failed. /data/metacattest/temporary/upload__11cedfdb_1582c7fe102__8000_00055347.tmp (Permission denied) from POST http://test.piscoweb.org/catalog/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnUpdate_token_8_20163239370428; FilePart:object = /tmp/mmp.output.9052533469160982670.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.5201819991960547334.tmp (1256 bytes); 
  testEchoCredentials(org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT): echoCredentials failed with token (http://orcid.org/0000-0002-1825-0097, Jane Scientist). got InvalidToken [401,4967] : No credentials were received in the request. (Session was null) from GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testCnQuery_token_8

Tests run: 6, Failures: 3, Errors: 0, Skipped: 0

[WARNING] File encoding has not been set, using platform encoding UTF-8, i.e. build is platform dependent!
[JENKINS] Recording test results
[INFO] 
[INFO] --- maven-failsafe-plugin:2.8.1:verify (verify) @ d1_integration ---
[INFO] Failsafe report directory: /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/failsafe-reports
[WARNING] File encoding has not been set, using platform encoding UTF-8, i.e. build is platform dependent!
[ERROR] There are test failures.

Please refer to /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/target/failsafe-reports for the individual test results.
[JENKINS] Recording test results
Notifying upstream projects of job completion
Join notifier requires a CauseAction
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 12:16.117s
[INFO] Finished at: Fri Nov 18 17:38:45 UTC 2016
[INFO] Final Memory: 60M/628M
[INFO] ------------------------------------------------------------------------
Waiting for Jenkins to finish collecting data