UnstableConsole Output

Skipping 39 KB.. Full Log
 and excludes ""
[INFO] Unpacking /var/lib/jenkins/.m2/repository/org/eclipse/jetty/jetty-io/8.1.15.v20140411/jetty-io-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/javax.servlet.jsp/2.2.0.v201112011158/javax.servlet.jsp-2.2.0.v201112011158.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/aggregate/jetty-all-server/8.1.15.v20140411/jetty-all-server-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-jsp/8.1.15.v20140411/jetty-jsp-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.apache.taglibs.standard.glassfish/1.2.0.v201112081803/org.apache.taglibs.standard.glassfish-1.2.0.v201112081803.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/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 [632 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-18:35:42: [WARN]: Starting monitor thread [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:35:42: [WARN]: Starting monitoring... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:35:42: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:35:42: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:42: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:42: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
~~~ Context is from d1client.properties: https://cn-stage.test.dataone.org/cn
20161118-18:35:43: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.client.rest.RestClient]
20161118-18:35:44: [INFO]: isNodeAlive for Node: https://cn-stage.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:44: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:44: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:44: [INFO]: *** Adding CN to list: cn-stage [ https://cn-stage.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:44: [INFO]: isNodeAlive for Node: https://cn-stage-orc-1.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:44: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:45: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:45: [INFO]: *** Adding CN to list: cn-stage-orc-1 [ https://cn-stage-orc-1.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:45: [INFO]: isNodeAlive for Node: https://mn-stage-unm-1.test.dataone.org/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:45: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:54: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:54: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:55: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:55: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:55: [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-18:35:55: [INFO]: isNodeAlive for Node: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:55: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:55: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:55: [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-18:35:55: [INFO]: isNodeAlive for Node: https://cn-stage-ucsb-1.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:55: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: *** Adding CN to list: cn-stage-ucsb-1 [ https://cn-stage-ucsb-1.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: isNodeAlive for Node: https://cn-stage-unm-1.test.dataone.org/cn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: *** Adding CN to list: cn-stage-unm-1 [ https://cn-stage-unm-1.test.dataone.org/cn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: isNodeAlive for Node: http://test.piscoweb.org/catalog/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:56: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: *** Adding MN to list: PISCO Test MN [ http://test.piscoweb.org/catalog/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: isNodeAlive for Node: http://seadva-test.d2i.indiana.edu/sead/rest/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: *** Adding MN to list: SEAD Virtual Archive [ http://seadva-test.d2i.indiana.edu/sead/rest/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: isNodeAlive for Node: https://mn-demo-11.test.dataone.org/knb/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:57: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: *** Adding MN to list: mn-demo-11 [ https://mn-demo-11.test.dataone.org/knb/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: isNodeAlive for Node: https://gmn-s.lternet.edu/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: *** Adding MN to list: Stage test for PASTA-GMN [ https://gmn-s.lternet.edu/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: isNodeAlive for Node: https://dataone-test.pop.umn.edu/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:35:58: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:05: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:05: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:06: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:06: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:07: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:07: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:08: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:08: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:08: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:08: [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-18:36:08: [INFO]: isNodeAlive for Node: https://dataone-dev.ecoinformatics.org.au/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:08: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:09: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:09: [INFO]: *** Adding MN to list: TERN Australia DEV [ https://dataone-dev.ecoinformatics.org.au/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:09: [INFO]: isNodeAlive for Node: https://dataone.tamucc.edu/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:09: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:10: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:10: [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-18:36:10: [INFO]: isNodeAlive for Node: http://dataone.neonscience.org/metacat/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:10: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:18: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:19: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:20: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:20: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:20: [WARN]: *** Failed to add MN to list: NEON Test Member Node [ http://dataone.neonscience.org/metacat/d1/mn ].  Could not reach the node:GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:20: [INFO]: isNodeAlive for Node: https://test.arcticdata.io/metacat/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:20: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:21: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:21: [INFO]: *** Adding MN to list: NSF Arctic Data Center Test Repository [ https://test.arcticdata.io/metacat/d1/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:21: [INFO]: isNodeAlive for Node: http://dataone.ornith.cornell.edu/metacat/d1/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:21: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:30: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [WARN]: *** Failed to add MN to list: Cornell Lab of Ornithology - eBird [ http://dataone.ornith.cornell.edu/metacat/d1/mn ].  Could not reach the node:GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: isNodeAlive for Node: https://dataone.researchworkspace.com/mn ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: ... calling node ... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: ... called node [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: *** Adding MN to list: Research Workspace [ https://dataone.researchworkspace.com/mn ] [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: trying to filter CN list for the round robin CN... original size: 4, includes nodes: [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]:     CN: https://cn-stage.test.dataone.org/cn "Round Robin staging router of Coordinating Nodes" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]:     CN: https://cn-stage-orc-1.test.dataone.org/cn "Oak Ridge National Laboratory Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]:     CN: https://cn-stage-ucsb-1.test.dataone.org/cn "UCSB Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]:     CN: https://cn-stage-unm-1.test.dataone.org/cn "UNM Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: excluding round robin CN: https://cn-stage.test.dataone.org/cn "Round Robin staging router of Coordinating Nodes" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: including CN: https://cn-stage-orc-1.test.dataone.org/cn "Oak Ridge National Laboratory Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: including CN: https://cn-stage-ucsb-1.test.dataone.org/cn "UCSB Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: including CN: https://cn-stage-unm-1.test.dataone.org/cn "UNM Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: filtered CN list without round robin CN. size: 3, including nodes: [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]:     CN: https://cn-stage-orc-1.test.dataone.org/cn "Oak Ridge National Laboratory Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]:     CN: https://cn-stage-ucsb-1.test.dataone.org/cn "UCSB Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]:     CN: https://cn-stage-unm-1.test.dataone.org/cn "UNM Stage Coordinating Node" [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:31: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-18:36:31: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:36:32: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v1/node [org.dataone.client.rest.RestClient]
20161118-18:36:32: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18: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-18: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.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-18:36:32: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTg5OTIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6MzY6MzIuNDgzKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDE5Mn0.EY5iM2pk30E3Nq1XT7FFAZwP5v-RVcWsm_rCgvSjoMWGWqZySBZUgtj0dy81lA3eaAhPQle80KeG_X8ZiNaSByyeeqiaCx2mAknaY60V1Og9jmx2eng8CALNKNYG76ET2seNULvgSzw0PV-zwfZxhQvgRTRCnNeLE9ojbetQsfQ_ROvJYUkaTbW-ye-PVd8KAw1tWUxavRGZRKzEFt9m0iPhsF49IBIsi90hHPcmTXuUlz5vaWhb_UUJ6GyCWI71ihTettNuRo8rcbs4z3sK4-cEB5OIxOdcx94YqlxrzfzKEyZ63UO4v12hv4p-AH9KGIYg-jCqtlByJBou-rq7zIUpQWyzBTosAj09cpdtvsif4pWn9admie4Ux_oYGCUU0kRJiTdb63e6rnDk1JqkDzFTx8vUtSsG74-ma5DNaTSUo77XLgFYGDXPhxBe-H8xv-LBELfhw3F-rpjMn0B1aQx8BSI5c0oUItJKcE9PMyxxEOCmBL-5gTM4vZ3l7u4LmQ9EfpNd66ep8Ed_Cf9RF70vBZk3tEf7lSQhVjhXfoRe7kCoqxKTv1hHfcbZIANZH3AgkF8DI-EuvTV-7mb0HQV9yJ1vgcqLJJ7G5TjTdsSxfqhxQLAefm-ICmg4zJZlIxGZ6MuKMTyr-bmhX0Zr0pMrfhRwnaMDbbMus4yRM7o [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:36:32: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:32: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:32: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:36:32: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:32: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:36:32: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:32: [INFO]: 
***************** running test for testEchoCredentials(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:33: [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]
20161118-18:36:33: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:33: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:33: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:33: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-18:36:33: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:34: [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-18:36:34: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:36:41: [WARN]: ...aborting connection... [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:37:04: [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-18:37:04: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:37:10: [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-18:37:10: [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-18:37:10: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:37:10: [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-18:37:10: [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-18:37:10: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTkwMzAsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6Mzc6MTAuMjQwKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDIzMH0.SkThwSCOhyvgIjuwkE2hhGdBSj8Y16YJSE6B7fTs4Wi7SkXS_6fBxYmNcBqQO8HfNf1s1NTfpDPHj6MkCEjUrIncfA1JFk9ouaKsbn_jO0qBERU3wSFmhQLfTUZLNAQ5p26AH5TJnu-3utckDMJU2BW10Vn3C3JWGQfZI92Ugk-borJ4y9P6Y2HzyFV5rA_60OLF7KbTm_AneLw25p_upNFR_1qcH9Xovdh_18THU7_MMMIAY7LGS8MtHdMHm5NCshp8DKpJ8eLOyUBXZJ_eQbb3L1Mjahbf8DmRg704JsVREfQ1yYOTulKM3Xqv1dsSltSFUuyeSsg1v66QrPjOpHpgWeiDg__9fUloQBvj0TWBpog9FUvFEZdjJ6O2o4QCT29I54Pw5WsV38LKmo7zg5y17E0PIWUA_xjkivVg5Kbi11OEPlX3lIXNWEZw5n8fefklzU6FDnKBO-bBhp6U1yuOsPjpgYZYXhmsvzBIWN-qIOVr4Wb4gcJnm-TgZCopsnE0GlrGIOR8gr9yzcD2mzsuUcst4QMNDrIlMgzfXlFSKJY6WFKpIqEoa3Hdd7IZhgSvA5SKsXAgog1hTA1NccnmLeoRAzrx3nKW8KZdMhFfPbW0CrYSVPj1rH45o901ndB9KfKbnJJItVYi8QnmVt9EcgBApSfDPHsYrTmJXUk [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:37:10: [INFO]: 
***************** running test for testMnCreate(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:37:10: [INFO]: entering refreshCache()... [org.dataone.client.v2.formats.ObjectFormatCache]
20161118-18:37:10: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:37:10: [INFO]: refreshing objectFormatCache from cn: null [org.dataone.client.v2.formats.ObjectFormatCache]
20161118-18:37:11: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage.test.dataone.org/cn/v2/formats [org.dataone.client.rest.RestClient]
20161118-18:37:11: [INFO]: successfully refreshed cache from cn.listFormats() [org.dataone.client.v2.formats.ObjectFormatCache]
20161118-18:37:12: [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_2016323103710920; FilePart:object = /tmp/mmp.output.1879796340539141945.tmp (684336 bytes); FilePart:sysmeta = /tmp/mmp.output.4067776020009229813.tmp (981 bytes);  [org.dataone.client.rest.RestClient]
20161118-18:37:12: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:37:14: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103710920 [org.dataone.client.rest.RestClient]
20161118-18:37:44: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:37:45: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103710920 [org.dataone.client.rest.RestClient]
20161118-18:38:15: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:38:16: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103710920 [org.dataone.client.rest.RestClient]
20161118-18:38:46: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:38:47: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103710920 [org.dataone.client.rest.RestClient]
20161118-18:39:17: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:39:19: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-orc-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103710920 [org.dataone.client.rest.RestClient]
20161118-18:39:20: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/isAuthorized/testMnCreate_2016323103710920?action=read [org.dataone.client.rest.RestClient]
20161118-18:39:20: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:39:20: [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-18:39:20: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:39: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-18:39:50: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:39:51: [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-18:39:51: [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-18:39:51: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:39: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-18:39: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-18:39:51: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTkxOTEsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6Mzk6NTEuMTkyKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDM5MX0.buvJDjtkcmdodaWD59d81Mm5Or4GAgThlODC71aH5NdGJldFehMjcNp5cXqgE5ad7RWF0OJclH1AobPuTQO5jdRWVv-uYCum5fzWxBPP3lT5AJSSxx7HTHV_GnfDZzyO8K4VeisNKw_G4vLj8PFIOtzC54WLA0WaXlQ0zg99IOo2r4jS9lW-Saq9p46WC4B1OIIyI8ndzV4_lpZxUQp_0KIU2ouH7Qo1_El0yUrp05i6_lF2La0NtXobC7sGU5NdxDAnnWDx1YFKMxsHTVsCq_5gUPoeqtu0s-0pbTGsBeMDzKzwRNH6UnX47sMDs9oYB4dOjJ-Pk3zgqSDy_3dZ9gSefN25_V8s4aHQOhJOH4J78Uen58cUiOcFAAr4MVxmLpvkGfkuRqcquZfPuBpzsg3-Avd-4dvWzM9gazzFO_rzxzl0hA2H_4mk2XCddtSVE_-gZ8UCkv8TdvEaie4XppFP_6ovHNu79ONXC6WD5Mil3qZPkHiMQT4pe-_cbtxmmHGUKoOKii6W_wn8w5nXgzIP-XlTBg_Xsyvd9tiVtGAiwPWptVrPBt6L9SRJhpNpUG7wPbzecOz_YSHtLqKuibrA8qiKDHn-oRtCx5UfH1OQ4kUsLR6J38nBMbUOFuBNyxxr3gSpBLTw_91VNuRHv8prpeQRGUji3p_8WjthFAg [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:39:51: [INFO]: 
***************** running test for testMnCreate(...) vs. node: https://cn-stage-ucsb-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:39: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_2016323103951744; FilePart:object = /tmp/mmp.output.8744472902244164594.tmp (684336 bytes); FilePart:sysmeta = /tmp/mmp.output.5369530055638264859.tmp (981 bytes);  [org.dataone.client.rest.RestClient]
20161118-18:39:52: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:39:53: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103951744 [org.dataone.client.rest.RestClient]
20161118-18:40:23: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:40:23: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103951744 [org.dataone.client.rest.RestClient]
20161118-18:40:53: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:40:54: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103951744 [org.dataone.client.rest.RestClient]
20161118-18:41:24: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:41:25: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103951744 [org.dataone.client.rest.RestClient]
20161118-18:41:55: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:41:55: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103951744 [org.dataone.client.rest.RestClient]
20161118-18:42:25: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:42:26: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-ucsb-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323103951744 [org.dataone.client.rest.RestClient]
20161118-18:42:27: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/isAuthorized/testMnCreate_2016323103951744?action=read [org.dataone.client.rest.RestClient]
20161118-18:42:27: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:42:27: [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-18:42:27: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:42:57: [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-18:42:57: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:42:58: [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-18:42:58: [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-18:42:58: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:42:59: [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-18:42:59: [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-18:42:59: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTkzNzgsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDI6NTguNDEzKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDU3OH0.cIIiM2Okq2e1dPAwUs-DcaOTtpDKnbJFtVvqkekBBVCs2vGrM2Xn_gfqStxnMkvhuSqkmPy33uu2qdnsa13GjcMH0QkvWyO6wuYo-JNTGffqdP6erCt2BZb7WAPoP3oHV0fD72i5KpaAE38voTNJex3BX3m-Zn1HvFLOdm9duy1fiqKOonr_ld-abqrnrUzc_j3_pxUtcpojt-9XCPpwZcMGAm-QNbagw95u58nTy-Qx-h0KBflGfXnemVE6CG8Tn8xDk7i4TOw6N1mKcqorQ7zM-Cq_P5M0ACckADvWeFEICtrRFDp3yWWhj7CTVqnpQIorCvP00eYsdfN0-hNyCx3pfCKA55Ez2Y2qyaxcDWZlCfDBVxLtxMst0s-kl4JECcSnwSAiK-y26rSKenBGzIuVzIglWlQjyTz3-ifk5eRP73-4qUxn4TTH2iDTm8ju7N8fzNimsBLTeJ3spKBfVHKV8sPTuxtDoYQY_C2hDQ1whSSvdxgVfkULyHSlafBu96i1P-XdA99SF82aBbGRJelDyJz277rZJdt5cPu1Ae9rAEJ06V8Tqn6ecF_4RPwdT5svIJIYWNpIePBSElkGzsWXTrnAQ5I8YvHDmT0mT44MW24IIz_aTs0J6J039HMumKZHzb8N8k5UQkdXkejKbvWIOPtW_lTGUwhl3bejnXc [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:42:59: [INFO]: 
***************** running test for testMnCreate(...) vs. node: https://cn-stage-unm-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:42:59: [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_2016323104259131; FilePart:object = /tmp/mmp.output.2488669150428705300.tmp (684336 bytes); FilePart:sysmeta = /tmp/mmp.output.8718157071410174537.tmp (981 bytes);  [org.dataone.client.rest.RestClient]
20161118-18:42:59: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:43:01: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323104259131 [org.dataone.client.rest.RestClient]
20161118-18:43:31: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:43:32: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323104259131 [org.dataone.client.rest.RestClient]
20161118-18:44:02: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:44:02: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323104259131 [org.dataone.client.rest.RestClient]
20161118-18:44:32: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:44:33: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323104259131 [org.dataone.client.rest.RestClient]
20161118-18:45:03: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:04: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323104259131 [org.dataone.client.rest.RestClient]
20161118-18:45:34: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:35: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://cn-stage-unm-1.test.dataone.org/cn/v2/meta/testMnCreate_2016323104259131 [org.dataone.client.rest.RestClient]
20161118-18:45:35: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/isAuthorized/testMnCreate_2016323104259131?action=read [org.dataone.client.rest.RestClient]
20161118-18:45:35: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:35: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:35: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:35: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-18:45:35: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:45:36: [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-18:45:36: [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-18:45:36: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1MzUsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDU6MzUuODEyKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDczNX0.Ptai8rAGlDQysRdtOtgBIAKejD77B6WyVihpUfRO7x5OTCSFojnAzBgzQ1KzCUK45T9uYyEu9tdOC-N9Mx4jLWEFLkPviVDWHZbtwWPdWJjre7b2nXlOaSxnkxN0j0mWf4jmlUXrz-komAcAtY1i_AB1beFOsTQ83qSOSNmaU7IDL9eM4GAJUhs_L12PR7zWRd7xkLKYaO7p-3CG0NwK06O4_bvLaFwXv97Bapps_OvUuQgrJCDSzznogM9gB0W7AX_6brzMIqqitkL-FrvVTTSV9b4IE2A0n8eWlOu1BlibykQ1rsdeAgFrxFs_eCUwmKUsAJnyJw8TKETnPHpC0FwR9-PslZQiqPqM-n79QMa_1YQfv7pM_nN_x4tbzJFIvYDOWmMjkTAHv-qKOE9Mtr7zo4akEZZtgJyudIpnZzUlcKWG12_ZDpKzo0kPknUZWC6IDG8iSxKWt4sBpfisON0W8eJZ4MhQWlzvdwNL2KKfsbKU6Uwy0qAlRx3Ar5hWdBLH1s5pk_vjo9RaPX9FxchsO8pOrhquNv7oqRMBg2XHgWXef9ItntqChWdouDi1dFnghSBRYX0oLYOAiDW90ED7YjWT-s2SSdzxh5_U8OoNbo-Sa80dyrMaS1QyziS2OKgTP3uB8Ve6-soUC0b5ZWuroEwx11NUAhvN_-JGa7o [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:36: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1MzUsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDU6MzUuODEyKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDczNX0.Ptai8rAGlDQysRdtOtgBIAKejD77B6WyVihpUfRO7x5OTCSFojnAzBgzQ1KzCUK45T9uYyEu9tdOC-N9Mx4jLWEFLkPviVDWHZbtwWPdWJjre7b2nXlOaSxnkxN0j0mWf4jmlUXrz-komAcAtY1i_AB1beFOsTQ83qSOSNmaU7IDL9eM4GAJUhs_L12PR7zWRd7xkLKYaO7p-3CG0NwK06O4_bvLaFwXv97Bapps_OvUuQgrJCDSzznogM9gB0W7AX_6brzMIqqitkL-FrvVTTSV9b4IE2A0n8eWlOu1BlibykQ1rsdeAgFrxFs_eCUwmKUsAJnyJw8TKETnPHpC0FwR9-PslZQiqPqM-n79QMa_1YQfv7pM_nN_x4tbzJFIvYDOWmMjkTAHv-qKOE9Mtr7zo4akEZZtgJyudIpnZzUlcKWG12_ZDpKzo0kPknUZWC6IDG8iSxKWt4sBpfisON0W8eJZ4MhQWlzvdwNL2KKfsbKU6Uwy0qAlRx3Ar5hWdBLH1s5pk_vjo9RaPX9FxchsO8pOrhquNv7oqRMBg2XHgWXef9ItntqChWdouDi1dFnghSBRYX0oLYOAiDW90ED7YjWT-s2SSdzxh5_U8OoNbo-Sa80dyrMaS1QyziS2OKgTP3uB8Ve6-soUC0b5ZWuroEwx11NUAhvN_-JGa7o [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:36: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:36: [INFO]: 
***************** running test for testCnIsAuthorized(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:37: [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-18:45:37: [INFO]:  ====>>>>> pid of procured test Object: testCnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:38: [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-18:45:38: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:45:39: [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-18:45:39: [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-18:45:39: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1MzgsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDU6MzguNzE1KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDczOH0.MPPe5BPuRwMbEaiRFMzAEWycWZT3jbCPFQZFNhpeePuz5wSRegMp0NLqhCDc0SFFWZrMhnjsXVT5vEf-7R1u6ZJDZiDM2itEbQsAC1XCGiDnIdwa6QjzTn9KgQi_mNibuYCAwAeQKVJ2-wUDkXLgWI-Gn5APbR-6rBaHKPZlZb_goPMNSNOPb70Vm7y_SP6Uya8FCXKoKQ9i8q4u9b-bXqBAfv_S5mYFhxMH1VfAJ20A4hFNRIzl5JA5gfVVy3DqgH6Eu7zOohAruVdW9rmWOaFazbHdPj7inIm-n0jv7lnq4vyhjn_wfPY6C59gyrCGNonrdQ679bWI88e5133jkeIC23YEq0CySbBXhTOsRjqPkMF-yDEHrZD7_m_34M5F2QaVKmTBweMf_VbEVkFrO9IXSXPmjtt4PnIO1rk43B4AZGPAA43svlSD_xFHV9OH-qEtpZDC-vTMgiZlDKY6urWXxT4kKFK-mplabZm4y6I-3f02tGjT5QS58nXM4AXPmurNnBNXydOPvHZEnJIo9L7UCH8AwMsvEvMjDtywblqxxGDOW-4qKkDJo4b2FiixvmIUa7ly8xCiqXlR7XNDBiyioCmBdWir_mesejCcohJ285WfVBk39fyBQwtW3zS5w0HkpoznPjtDTZJQeYUJfg1uFsvriOa7qEf6VtdwRsw [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:39: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1MzgsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDU6MzguNzE1KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDczOH0.MPPe5BPuRwMbEaiRFMzAEWycWZT3jbCPFQZFNhpeePuz5wSRegMp0NLqhCDc0SFFWZrMhnjsXVT5vEf-7R1u6ZJDZiDM2itEbQsAC1XCGiDnIdwa6QjzTn9KgQi_mNibuYCAwAeQKVJ2-wUDkXLgWI-Gn5APbR-6rBaHKPZlZb_goPMNSNOPb70Vm7y_SP6Uya8FCXKoKQ9i8q4u9b-bXqBAfv_S5mYFhxMH1VfAJ20A4hFNRIzl5JA5gfVVy3DqgH6Eu7zOohAruVdW9rmWOaFazbHdPj7inIm-n0jv7lnq4vyhjn_wfPY6C59gyrCGNonrdQ679bWI88e5133jkeIC23YEq0CySbBXhTOsRjqPkMF-yDEHrZD7_m_34M5F2QaVKmTBweMf_VbEVkFrO9IXSXPmjtt4PnIO1rk43B4AZGPAA43svlSD_xFHV9OH-qEtpZDC-vTMgiZlDKY6urWXxT4kKFK-mplabZm4y6I-3f02tGjT5QS58nXM4AXPmurNnBNXydOPvHZEnJIo9L7UCH8AwMsvEvMjDtywblqxxGDOW-4qKkDJo4b2FiixvmIUa7ly8xCiqXlR7XNDBiyioCmBdWir_mesejCcohJ285WfVBk39fyBQwtW3zS5w0HkpoznPjtDTZJQeYUJfg1uFsvriOa7qEf6VtdwRsw [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:39: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:39: [INFO]: 
***************** running test for testCnIsAuthorized(...) vs. node: https://cn-stage-ucsb-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:39: [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-18:45:39: [INFO]:  ====>>>>> pid of procured test Object: testCnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:40: [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-18:45:40: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:45:40: [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-18:45:40: [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-18:45:40: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1NDAsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDU6NDAuMTIzKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDc0MH0.V4qWiYj3Y6E5ZmYLigfGbsVBT3rzxSwoGH5QRHX90XnujQgz_XCawmKOIJQRdx2bkBNK_fRQK5gcX02Lb8SIHh-kV-EDPab0-slhigykXYDruYaeYEzvD30URUiSn1vWsWUW_NYRW4VrwS6ysvxqSDa6-wHvuNPWUCYDOF03ArZoS_fhk5Qxc4GabjNUkDHG59q4cgbdSIkEE2OML8ATvqWylRBBuMT7Kqw-Xx4Hdn2HN0XGMZ_7Heg7s47PfObgngCWxfzh6jt0PdfXP8g7kQt7EsxLOy2RRNvdSjv9HQLFeOveIFcCanUq9KjiL3y5iQWSsnKX-Jv6hNYk_BY1Ww22Pt-PVBGKpJvMUixjTYrA02rfzJiYS_45QwiCejaTVrGsSFK6GxpJmcmGvYKDoWE49kXH-VEzeFJ16_ZCGjuhiWoZLh-f6kpE-N5AuFXRBeKWsYOvQZzEvRfzRMAJrC_1sIFT07dAJRe46DpeYEOgBRGqT5gtCA7a4TkcF8XBwv3ak_SuKzzbQXY5xVeTio2lXvhLhzW-5n90MO-aqtZ1Axh6Tod9kt-F2hg6km6Q0WSFpMf068xZsrbM5KrMGAENcas438ae6LR3PCxbBRuoy2nya2apz_oR9cxfROmKMcUttq4sYFwLGNq41zZil6rUO-HsSqHLDUtsO0yZDB0 [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:40: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1NDAsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDU6NDAuMTIzKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDc0MH0.V4qWiYj3Y6E5ZmYLigfGbsVBT3rzxSwoGH5QRHX90XnujQgz_XCawmKOIJQRdx2bkBNK_fRQK5gcX02Lb8SIHh-kV-EDPab0-slhigykXYDruYaeYEzvD30URUiSn1vWsWUW_NYRW4VrwS6ysvxqSDa6-wHvuNPWUCYDOF03ArZoS_fhk5Qxc4GabjNUkDHG59q4cgbdSIkEE2OML8ATvqWylRBBuMT7Kqw-Xx4Hdn2HN0XGMZ_7Heg7s47PfObgngCWxfzh6jt0PdfXP8g7kQt7EsxLOy2RRNvdSjv9HQLFeOveIFcCanUq9KjiL3y5iQWSsnKX-Jv6hNYk_BY1Ww22Pt-PVBGKpJvMUixjTYrA02rfzJiYS_45QwiCejaTVrGsSFK6GxpJmcmGvYKDoWE49kXH-VEzeFJ16_ZCGjuhiWoZLh-f6kpE-N5AuFXRBeKWsYOvQZzEvRfzRMAJrC_1sIFT07dAJRe46DpeYEOgBRGqT5gtCA7a4TkcF8XBwv3ak_SuKzzbQXY5xVeTio2lXvhLhzW-5n90MO-aqtZ1Axh6Tod9kt-F2hg6km6Q0WSFpMf068xZsrbM5KrMGAENcas438ae6LR3PCxbBRuoy2nya2apz_oR9cxfROmKMcUttq4sYFwLGNq41zZil6rUO-HsSqHLDUtsO0yZDB0 [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:45:40: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:40: [INFO]: 
***************** running test for testCnIsAuthorized(...) vs. node: https://cn-stage-unm-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:40: [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-18:45:40: [INFO]:  ====>>>>> pid of procured test Object: testCnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [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-18:45:41: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-18:45:41: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [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-18:45:41: [INFO]: MN included in test: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://test.piscoweb.org/catalog/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:45:41: [INFO]: MN included in test: http://test.piscoweb.org/catalog/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:45:41: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:12: [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-18:46:12: [INFO]: MN excluded from test: http://seadva-test.d2i.indiana.edu/sead/rest/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:12: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:12: [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-18:46:12: [INFO]: MN included in test: https://mn-demo-11.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:12: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:13: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://gmn-s.lternet.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:13: [INFO]: MN included in test: https://gmn-s.lternet.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:13: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:14: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone-dev.ecoinformatics.org.au/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:14: [INFO]: MN excluded from test: https://dataone-dev.ecoinformatics.org.au/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:14: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:15: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.tamucc.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:15: [INFO]: MN excluded from test: https://dataone.tamucc.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:15: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:16: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://test.arcticdata.io/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:16: [INFO]: MN included in test: https://test.arcticdata.io/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:16: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:16: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.researchworkspace.com/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:16: [INFO]: MN excluded from test: https://dataone.researchworkspace.com/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:17: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:46:17: [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-18:46:17: [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-18:46:17: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1NzYsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDY6MTYuOTI4KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDc3Nn0.ADeR8vRNdsAie8d92QfIQ48BbcjaYnk2e6dlwpv5pF4wU4d6HpRhNheACxPTeQw4MDsd0n6a8CXgJunNKw6UVbut_YAbdqkztuVz1M6Hstan8Y04t7FjQtGPWUPQwmQj-NFM2SP0rhtSIKaYctYge-Utt5qhjzUIy6O5ipghS0PkTUV68B6dDWqs1Tg8o0vhS11abjyh2ntQKgWQDe85mpCpbNcL3pCSmLQ0CWeNQi2RvAyFHy5w5x-q6s9N37SSFJg2dzBbSSyw-cqI2FVkYqtXImrp7ohuSkzBKHsC_mfiFhQIuKlcwVI8EZCguSoScqHp4EAYExpJtq2oX6Y6MUOXp1aFSgzmMKH5EJ5HUcayMvBnGBKHZ0EUb340-rb9OhS4obLXobv7vMDKt-VfkQhp7EPIzbrVH1EH22949ZOaKmFyjjKBZ5BrOx2LfhyWjb919EZA8DYdzGsxLCHIO-sVd5ALGeVb5D2UMNOCMOuXVHcOOgJxRkIFyEc-0s9QF4SXboLR_8sLMgB5Bk-tRUAo6jaDuiEYyjaoU7eBgXENJieInw3Jg0763APYGOe_Nd4gWuriFseguJFv5Eu2QQkC0yCX3nBHuEVWRj3tTUA4Dx2Ovdo7A1UyqWC1K2DyXafcp0LcCmmahnMcYuyAwAcRIGjioCgTvR1oTMVGtI8 [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:46:17: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:17: [INFO]: 
***************** running test for testMnIsAuthorized(...) vs. node: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:17: [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-18:46:17: [INFO]:  ====>>>>> pid of procured test Object: testMnIsAuthorized_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:18: [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-18:46:18: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:46:18: [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-18:46:18: [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-18:46:18: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk1NzgsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDY6MTguMzAyKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDc3OH0.cM188NdmlgmZnXRKPCXsq5M1oPVtEPqSHXbHAq7T0AFrjSCkA5KIWLfBY0Brsinfr0vs5MXM8ld-xwP32O_xkIbm7gnrxP_ZufHug07DZ8Acs9WMaWHpVW02QBFXa_lIVwMhJHiMGvECxLmmqKCS6xMkPDj6U0gt7mvzGl55GNCuZlcPJRJFgUTVKMpnkksIXwtXBBeVMldRJ5pK4kH9RZzC92UbvNtCwCaMYWHs5eEkc7cJbva2bRj-QM53E4BkuUoXj4xicLzmgpHHJ3NrQrlR3BvieemRSGuEWJeSUGqMOcZkMjObJ0uvBLCaBpkVFftBoiHQZ4SeLnVan0GvJkv4P1DPPXh-NZL5zFQDzceyah0Rp_e9dlnoPp3MTQTV1Tg8PKgrbcc1DV1ERFCFTA6iOPh23irK0txEOnLIwd15qPzfbaKGiglWGiLHTb5kxXgIlrDhbvoiBoHbC4AXk4o2XmPg9KXa-maBcarsExlfu5SgJHbkt1S2luI7X8y0y64ayjKLYyRX49LQeMKmUisiU6jyqyJO-ZoSiiIPLlcJGP83k0jwg27VOWxCCDXblOGR3ll0cDxZ2Z92T1w_gnEPCld6v4XI2uaLCr0qRFsuoiU8U1pqmiTKJ3h3Zc3FftCF2Yxnh4ngVf_wKf5wc3Fq8lu-U4p4lMi4W-CFeC0 [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:46:18: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:18: [INFO]: 
***************** running test for testMnIsAuthorized(...) vs. node: http://test.piscoweb.org/catalog/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [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-18:46:19: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: creating a test object.  pid = testMnIsAuthorized_token_8 on http://test.piscoweb.org/catalog/d1/mn/v2 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [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-18T18:46:19.068+00:00</dateUploaded>
    <dateSysMetadataModified>2016-11-18T18:46:19.068+00:00</dateSysMetadataModified>
    <originMemberNode>urn:node:mnStagePISCO</originMemberNode>
    <authoritativeMemberNode>urn:node:mnStagePISCO</authoritativeMemberNode>
</ns3:systemMetadata>
 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [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.7450783390661944446.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.3032542263425708135.tmp (1133 bytes);  [org.dataone.client.rest.RestClient]
20161118-18:46:19: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-18:46:19: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [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-18:46:19: [INFO]: MN included in test: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET http://test.piscoweb.org/catalog/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:19: [INFO]: MN included in test: http://test.piscoweb.org/catalog/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [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-18:46:19: [INFO]: MN excluded from test: http://seadva-test.d2i.indiana.edu/sead/rest/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [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-18:46:19: [INFO]: MN included in test: https://mn-demo-11.test.dataone.org/knb/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:19: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:50: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://gmn-s.lternet.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:50: [INFO]: MN included in test: https://gmn-s.lternet.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:50: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:51: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone-dev.ecoinformatics.org.au/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:51: [INFO]: MN excluded from test: https://dataone-dev.ecoinformatics.org.au/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:51: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:51: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.tamucc.edu/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:51: [INFO]: MN excluded from test: https://dataone.tamucc.edu/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:51: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:52: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://test.arcticdata.io/metacat/d1/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:52: [INFO]: MN included in test: https://test.arcticdata.io/metacat/d1/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:52: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:52: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://dataone.researchworkspace.com/mn/v2/monitor/ping [org.dataone.client.rest.RestClient]
20161118-18:46:52: [INFO]: MN excluded from test: https://dataone.researchworkspace.com/mn [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:53: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:46:53: [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-18:46:53: [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-18:46:53: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk2MTIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDY6NTIuOTQ5KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDgxMn0.ovd_7pRAK_Wr6Y9hSxoUMvMrztWfJcVk334djive7fVElhK3GG32D9rnjASrqWkMM7ioNH0ub_a9s6ltQlGAUHdQvc18upLn4srACpUeqIQQsxkspHCNs6IaNGvcz-tbADJ5SVbk_9ZVU0Zj1ZEQbtDoA-RS8HjsMkCAuQcAEmMjYgfqSedQBlGBTh_z50r7CjP4E29PZ2iDv5sHItu3yTSUj8mDBAAzDqFjWJJ6NStW2kxD-LwJdBKTUL_cDP2x3_-A970QVyjdc87F2ZXNw3ZHmYy7gY-iLzc1mrnSkipL0o0HcNMD4Zr-I8OYGUu_nWzU9T-tj9yPFoEvsA-qM4M8An1fRe1GMCdcLdB99pvoELMSKqoNNkLXxyLytE-Kz8hHhqqbpW9yqQLcI3Tsze1u4Ho9LsK1GTBJYOVuEZStbUXy0qXGChbKJv7yv3Tnvtwl56uwFhfP5tC-f3Cw77Zhgom3Fvp2wb3W57RrX9Lr1v_cDMUub5oj-Kjd8DwSwR0pketU_sF9ydoZzBa2GIt-vk38LvwLYsk0ZiPBdtv7_tK7W8FguTPerhvQ01nF1g8k_jmX9fpWDPna1lBnmY3QOQ7VZtjEpcP0WKQQgVZVE8wTCn2c-sM6D6EJMl3zmQjJT1Ghr7cCOGnLSUhIfW6rRYs5r1AhuelrjqDzKQk [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:46:53: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:53: [INFO]: 
***************** running test for testMnUpdate(...) vs. node: https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:53: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:53: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:53: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:53: [INFO]: creating a test object.  pid = testMnUpdate_token_8_2016323104653603 on https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:53: [INFO]: SystemMetadata for pid: testMnUpdate_token_8_2016323104653603
<?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_2016323104653603</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-18T18:46:53.611+00:00</dateUploaded>
    <dateSysMetadataModified>2016-11-18T18:46:53.611+00:00</dateSysMetadataModified>
    <originMemberNode>urn:node:mnStageUCSB2</originMemberNode>
    <authoritativeMemberNode>urn:node:mnStageUCSB2</authoritativeMemberNode>
</ns3:systemMetadata>
 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:46:54: [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_2016323104653603; FilePart:object = /tmp/mmp.output.146768248117873634.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.2620724518367235949.tmp (1258 bytes);  [org.dataone.client.rest.RestClient]
20161118-18:46:54: [INFO]: object created.  pid = testMnUpdate_token_8_2016323104653603 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:05: [INFO]: RestClient.doRequestNoBody, thread(1) call Info: GET https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/meta/testMnUpdate_token_8_2016323104653603 [org.dataone.client.rest.RestClient]
20161118-18:47:06: [INFO]: RestClient.doRequestMMPBody, thread(1) call Info: PUT https://mn-stage-ucsb-2.test.dataone.org/knb/d1/mn/v2/object/testMnUpdate_token_8_2016323104653603; MMP message has: ParamPart:newPid = testMnUpdate_token_8_201632310475185; FilePart:object = /tmp/mmp.output.4575304558338756116.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.3146460135955917238.tmp (1246 bytes);  [org.dataone.client.rest.RestClient]
20161118-18:47:06: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:47:06: [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-18:47:06: [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-18:47:06: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk2MjYsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDc6MDYuNzQyKzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDgyNn0.ozno7YZXfMbixWg5EX1caKiDC3_lv5J__DPQoLokatFyEQeXqbcXC2G3YrmYXQlQGA3JC1iiTMwLCuKgus5-vl7wLaP1Ou-sciuuZRetznK3fjoCX0dpoJ9IncNcwKPCTO1BrQi890LI6cT6UFwFFyoQste_Dg088xE5jNH-eE526hy5Tu31ivNjYbDYql7Wcpom-muPQ9JPalfGqMwds4mf4gq8QnZm2KbyGORazYfELoaseivHWptOwgyY1IrjVEhY84CVvVb3q-ZtiOJQJAHaL8-4EJelYtNV_HLNJH63r_WNNdBLA_yT9omrEIvlTS3fa_EQbL8kD-OzxRl5tl2q5e85X_EPcjRt54YyNOtyP8cn_wILsBejRNQjEbfZu775IuCOOpZzRVVH-DA8KNOCxdLqp8lh49VWiPtvw9_3Ur2hfQq0oqb_aGZ9QuBXyfvDhEdj_-fZAX1pfJ281GKziSXrhM2z06rnIzSEXNBL0b77QYga81toOxFQBsachQYJIhSP4C7tthYFfxYPqoF2BRZMBtMhRl6S2onVuWTmc-0oUgIFsAgzqO5KWMvFgnm1t9Wa7lsvcRNxMuP-G959Fb-R9TejyPAUGize3sRqkBBp5RJxm4Q2J2gOwJk42wDXpNwsW7dHP-Wmn13L0DsORKS1ksUT-waW7-6QC-A [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:47:06: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:06: [INFO]: 
***************** running test for testMnUpdate(...) vs. node: http://test.piscoweb.org/catalog/d1/mn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:06: [INFO]: certificate directory: /etc/dataone/client/testClientCerts/ [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:06: [INFO]: certificate filename: cnStageUNM1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:06: [INFO]: client setup as Subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:06: [INFO]: creating a test object.  pid = testMnUpdate_token_8_201632310476944 on http://test.piscoweb.org/catalog/d1/mn/v2 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:06: [INFO]: SystemMetadata for pid: testMnUpdate_token_8_201632310476944
<?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_201632310476944</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-18T18:47:06.952+00:00</dateUploaded>
    <dateSysMetadataModified>2016-11-18T18:47:06.952+00:00</dateSysMetadataModified>
    <originMemberNode>urn:node:mnStagePISCO</originMemberNode>
    <authoritativeMemberNode>urn:node:mnStagePISCO</authoritativeMemberNode>
</ns3:systemMetadata>
 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:07: [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_201632310476944; FilePart:object = /tmp/mmp.output.5088570727608954471.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.441003333620958256.tmp (1257 bytes);  [org.dataone.client.rest.RestClient]
20161118-18:47:07: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:07: [INFO]: ***  context label:   STAGE [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:07: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:07: [INFO]: **************************************************** [org.dataone.integration.ContextAwareTestCaseDataone]
                          nodeId  key                       value
=============================================================================================================
20161118-18:47:07: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:08: [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-18:47:08: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:38: [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-18:47:38: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:38: [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-18:47:38: [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-18:47:38: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:47:39: [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-18:47:39: [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-18:47:39: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk2NTgsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDc6MzguNzM1KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDg1OH0.Op1lBT3xmLg4ZIsuFgP75PiqqcMlgI9XywFwzb6FKfDIFavb0qci1KexeEkLhJrHMndHQU82z4AoH1GLR8iDCo21T5Bwj20IG0ZwiXGmr8lI8ErtD5avAItmTRcfolNYzej58RSjIY16Xsja0mqOq3xxiIJdI26KxoVStznvixSRZEUy-GGoRNaAxtDsdW6Stb5hbtuYS8jWqevSh_qqtl7eGRTahUG9JF2qUJJoD9-z0UQv9BMtX1lnHB4HVWMKR5bbBJnzWjNI5MndmPrek68o5b3AyV6L09ESX3imfbWemeNr-WDL-zrIl5IGhFYr6ejZajbKHIgmeHtX8_jLfWtXgR_H29GoUZD6IFYfxWTig-uczbEdiDVSCuWmq3ZMYzS0Uzq-9xlfNf9-kBtkX4MusQKp-dsHklEj6h_mV0FCg1RLt4ZoOH3E877oHRoGRPQRzkISmIlmtgvakEtCUL6cHSMmxjN1dRlVPVQFhrj5te6fkW4plWrRxmXhTxx43zNMJfJSsGWqcOYXZ8bDZuiULhE66N8bZ34D8OADmO_C51jsIsAY4bQtV6HnNVFK9pBroNLifSFM2VpKlw3A6XxFms4VS6-HNshPX66LYtd0N-cS_9MS5kH-XFvTVQ8mOXbZZ8lT6Pyet7It_wV8lPElxqG3JiPn8BJbeYIW-ek [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:47:39: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk2NTgsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDc6MzguNzM1KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDg1OH0.Op1lBT3xmLg4ZIsuFgP75PiqqcMlgI9XywFwzb6FKfDIFavb0qci1KexeEkLhJrHMndHQU82z4AoH1GLR8iDCo21T5Bwj20IG0ZwiXGmr8lI8ErtD5avAItmTRcfolNYzej58RSjIY16Xsja0mqOq3xxiIJdI26KxoVStznvixSRZEUy-GGoRNaAxtDsdW6Stb5hbtuYS8jWqevSh_qqtl7eGRTahUG9JF2qUJJoD9-z0UQv9BMtX1lnHB4HVWMKR5bbBJnzWjNI5MndmPrek68o5b3AyV6L09ESX3imfbWemeNr-WDL-zrIl5IGhFYr6ejZajbKHIgmeHtX8_jLfWtXgR_H29GoUZD6IFYfxWTig-uczbEdiDVSCuWmq3ZMYzS0Uzq-9xlfNf9-kBtkX4MusQKp-dsHklEj6h_mV0FCg1RLt4ZoOH3E877oHRoGRPQRzkISmIlmtgvakEtCUL6cHSMmxjN1dRlVPVQFhrj5te6fkW4plWrRxmXhTxx43zNMJfJSsGWqcOYXZ8bDZuiULhE66N8bZ34D8OADmO_C51jsIsAY4bQtV6HnNVFK9pBroNLifSFM2VpKlw3A6XxFms4VS6-HNshPX66LYtd0N-cS_9MS5kH-XFvTVQ8mOXbZZ8lT6Pyet7It_wV8lPElxqG3JiPn8BJbeYIW-ek [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:47:39: [INFO]: 
***************** running test for testCnQuery(...) vs. node: https://cn-stage-orc-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:39: [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-18:47:39: [INFO]:  ====>>>>> pid of procured test Object: testCnQuery_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:39: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:47:40: [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-18:47:41: [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-18:47:41: [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-18:47:41: [INFO]: query results: numFound = 1   <doc>s returned = 1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:41: [INFO]: CN.query results have a count of 1 logs and contain 1 returned docs. [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:47:41: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:47:42: [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-18:47:42: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:12: [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-18:48:12: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:12: [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-18:48:12: [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-18:48:13: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:48:13: [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-18:48:13: [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-18:48:13: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk2OTIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDg6MTIuOTk4KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDg5Mn0.M5l6bOMr_sW6uuyqg0CQ-aU5CpUilEeOgdf6yD8QQy5cSyK43pSS03hmX3-Up1mcuBqTdLBKrIF9vGlKdPZ2Ia6Oc6bbTX1SHdqn8HjELtd-wRHlALGXhQXAYXUceeDVisezZ6LoxLxPdpWxP1RFjI64jYq1e02CRHGRQNAsOWrw64bYQGwcZMLg-puHYc0klQZFTY43ZQejM8pY5fv68L9N-3uTBFEIsQDZnsESS6AjfTX5RvAEpnWDFBgpz8acUttTa-nSyPixtE6ItyrfgLJmv2zbK-2CGi-qDyIn_RrSBIQr-YE4LBLQiFkx1VZA_s1uZtlcaaHYxsMavc3nOBAMaNOz5j8qw7QMckUlmb5-0uYK5-q1AqQ0FzrlU8xn8efZ8ORKWompZrZNp9i2d0HsvC2wmS5FFnbbBn4yV-89AHB-0SgbxIScTLlBBwNKrKfxQ3VjVd3rVWYfCKY7bsOJdGCc43xirvvotFXdGTiX2kJnSUgf1UsqOEhCV5-BRc1s1RorBu5bJU4U69x8WeWYHxF0_AoDOvxI66KUz_e1wCUXBS8HGOIky1ETmWKQFIW3dLOSUKlmCvpZUhK-o2gnAf_VLA8bx27Ss78cmZMmBrn2nOpYLX34d2YkxMasez8H6C77tbjQjP8XHL2cCnH1lJw6kEpGs6ghl5Jsyc4 [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:48:13: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk2OTIsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDg6MTIuOTk4KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDg5Mn0.M5l6bOMr_sW6uuyqg0CQ-aU5CpUilEeOgdf6yD8QQy5cSyK43pSS03hmX3-Up1mcuBqTdLBKrIF9vGlKdPZ2Ia6Oc6bbTX1SHdqn8HjELtd-wRHlALGXhQXAYXUceeDVisezZ6LoxLxPdpWxP1RFjI64jYq1e02CRHGRQNAsOWrw64bYQGwcZMLg-puHYc0klQZFTY43ZQejM8pY5fv68L9N-3uTBFEIsQDZnsESS6AjfTX5RvAEpnWDFBgpz8acUttTa-nSyPixtE6ItyrfgLJmv2zbK-2CGi-qDyIn_RrSBIQr-YE4LBLQiFkx1VZA_s1uZtlcaaHYxsMavc3nOBAMaNOz5j8qw7QMckUlmb5-0uYK5-q1AqQ0FzrlU8xn8efZ8ORKWompZrZNp9i2d0HsvC2wmS5FFnbbBn4yV-89AHB-0SgbxIScTLlBBwNKrKfxQ3VjVd3rVWYfCKY7bsOJdGCc43xirvvotFXdGTiX2kJnSUgf1UsqOEhCV5-BRc1s1RorBu5bJU4U69x8WeWYHxF0_AoDOvxI66KUz_e1wCUXBS8HGOIky1ETmWKQFIW3dLOSUKlmCvpZUhK-o2gnAf_VLA8bx27Ss78cmZMmBrn2nOpYLX34d2YkxMasez8H6C77tbjQjP8XHL2cCnH1lJw6kEpGs6ghl5Jsyc4 [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:48:13: [INFO]: 
***************** running test for testCnQuery(...) vs. node: https://cn-stage-ucsb-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:13: [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-18:48:13: [INFO]:  ====>>>>> pid of procured test Object: testCnQuery_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:13: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:48:14: [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-18:48:14: [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-18:48:14: [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-18:48:14: [INFO]: query results: numFound = 1   <doc>s returned = 1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:14: [INFO]: CN.query results have a count of 1 logs and contain 1 returned docs. [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:48:14: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:15: [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-18:48:15: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:45: [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-18:48:45: [INFO]: created client for certificate file: cnStageUNM1 with subject: CN=urn:node:cnStageUNM1,DC=dataone,DC=org [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:45: [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-18:48:45: [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-18:48:45: [WARN]: registering ConnectionManager... [org.dataone.client.utils.HttpConnectionMonitorService]
20161118-18:48: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-18:48: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.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-18:48:46: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk3MjUsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDg6NDUuNzY5KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDkyNX0.EA2rL2-UcjTqLXZM8vNlqnCF2CRdrqEyQA5zEi1gh--MWkPyZSwBgsav_CPA_czNPFIENcQYbdA72blNvtjtjzEGZ1_nenTQ0dR_uE70b4ABCB5mR6JguiKch8eH6MxwX2hKjsm8zmorLpo3udBucdrciyW3Hw9wRQDOMJ7iGoX_D4uJ4GDrmJK-2St4kVJwRaWz212VhmcZyUojn1Vlzi3VCWmunaDePGVwbDFimHQDPtJQT5uVH9RQm42N9L8zek5bXHVI3mPr6GIl9LazyHYedJ9f9IOBwwys1q2qJoX0hLjfpqJij2c3i198gS2Bg02AwSH8T2LvsIVwMTm3ZtXgDqCJuuhIZjd48gxS5GS0bFS0QoI5b7DrGj8u1TSC-AR8QqkEsNkMWvm2gcRP8WPUKQJ670SB_S8R1wsFHbsFGt0Q-w6YfkkpOZEUo35Mlln1CkJDqIsGisdcA3vo31BGigOxEgf9I8WcnBfPNc7tY5YCGD7kbYOY0kdeg82NmCHIzxHoFA7Loap6KGo89IVMUYJW5_E0cWujAcKaxnuu2s86ZWUMpeNpYLbWOiVaHHzvB51oYgJX-PLplAwdtSAOY7XG3rDFAyOVmFRa9pJI4NwLFsvWDGexehggjz8TmyWy0YwIbGDXidsvbMYQUNU2s3cPXSduwLUY2OjMDIA [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:48:46: [INFO]: Created auth token: eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE0Nzk1NTk3MjUsInN1YiI6Imh0dHA6XC9cL29yY2lkLm9yZ1wvMDAwMC0wMDAyLTE4MjUtMDA5NyIsImNvbnN1bWVyS2V5IjpudWxsLCJpc3N1ZWRBdCI6IjIwMTYtMTEtMThUMTg6NDg6NDUuNzY5KzAwOjAwIiwidXNlcklkIjoiaHR0cDpcL1wvb3JjaWQub3JnXC8wMDAwLTAwMDItMTgyNS0wMDk3IiwiZnVsbE5hbWUiOiJKYW5lIFNjaWVudGlzdCIsInR0bCI6NjQ4MDAsImlhdCI6MTQ3OTQ5NDkyNX0.EA2rL2-UcjTqLXZM8vNlqnCF2CRdrqEyQA5zEi1gh--MWkPyZSwBgsav_CPA_czNPFIENcQYbdA72blNvtjtjzEGZ1_nenTQ0dR_uE70b4ABCB5mR6JguiKch8eH6MxwX2hKjsm8zmorLpo3udBucdrciyW3Hw9wRQDOMJ7iGoX_D4uJ4GDrmJK-2St4kVJwRaWz212VhmcZyUojn1Vlzi3VCWmunaDePGVwbDFimHQDPtJQT5uVH9RQm42N9L8zek5bXHVI3mPr6GIl9LazyHYedJ9f9IOBwwys1q2qJoX0hLjfpqJij2c3i198gS2Bg02AwSH8T2LvsIVwMTm3ZtXgDqCJuuhIZjd48gxS5GS0bFS0QoI5b7DrGj8u1TSC-AR8QqkEsNkMWvm2gcRP8WPUKQJ670SB_S8R1wsFHbsFGt0Q-w6YfkkpOZEUo35Mlln1CkJDqIsGisdcA3vo31BGigOxEgf9I8WcnBfPNc7tY5YCGD7kbYOY0kdeg82NmCHIzxHoFA7Loap6KGo89IVMUYJW5_E0cWujAcKaxnuu2s86ZWUMpeNpYLbWOiVaHHzvB51oYgJX-PLplAwdtSAOY7XG3rDFAyOVmFRa9pJI4NwLFsvWDGexehggjz8TmyWy0YwIbGDXidsvbMYQUNU2s3cPXSduwLUY2OjMDIA [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:48:46: [INFO]: 
***************** running test for testCnQuery(...) vs. node: https://cn-stage-unm-1.test.dataone.org/cn ***************** [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:46: [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-18:48:46: [INFO]:  ====>>>>> pid of procured test Object: testCnQuery_token_8 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:46: [INFO]: attempting CN getSystemMEtadata... [org.dataone.integration.it.ContextAwareAdapter]
20161118-18:48:47: [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-18:48:48: [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-18:48:48: [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-18:48:48: [INFO]: query results: numFound = 1   <doc>s returned = 1 [org.dataone.integration.ContextAwareTestCaseDataone]
20161118-18:48:48: [INFO]: CN.query results have a count of 1 logs and contain 1 returned docs. [org.dataone.integration.it.ContextAwareAdapter]
Tests run: 6, Failures: 3, Errors: 0, Skipped: 0, Time elapsed: 787.398 sec <<< FAILURE!

Results :

Failed tests: 
  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 null
  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_00055350.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.7450783390661944446.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.3032542263425708135.tmp (1133 bytes); 
  testMnUpdate(org.dataone.integration.it.functional.v2.AuthTokenFunctionalIT): Unable to create object (testMnUpdate_token_8_201632310476944), 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_00055351.tmp (Permission denied) from POST http://test.piscoweb.org/catalog/d1/mn/v2/object; MMP message has: ParamPart:pid = testMnUpdate_token_8_201632310476944; FilePart:object = /tmp/mmp.output.5088570727608954471.tmp (17181 bytes); FilePart:sysmeta = /tmp/mmp.output.441003333620958256.tmp (1257 bytes); 

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: 13:37.071s
[INFO] Finished at: Fri Nov 18 18:48:48 UTC 2016
[INFO] Final Memory: 55M/770M
[INFO] ------------------------------------------------------------------------
Waiting for Jenkins to finish collecting data
[JENKINS] Archiving /var/lib/jenkins/jobs/d1_integration_Stage_authToken/workspace/d1_integration/pom.xml to org.dataone/d1_integration/2.3-SNAPSHOT/d1_integration-2.3-SNAPSHOT.pom
channel stopped
Notifying upstream projects of job completion
Finished: UNSTABLE