search

eclipse-pass / pass-deposit-services

Deposit Services are responsible for the transfer of custodial content and metadata from end users to repositories.
Apache License 2.0
1 stars 4 forks source link

SSLHandshakeException when depositing to DASH #268

Closed emetsger closed 9 months ago

emetsger commented 4 years ago 
18:16:09.059 [    Deposit-Worker-0] ERROR [    DepositServiceErrorHandler] - Unrecoverable error, marking http://fcrepo:8080/fcrepo/rest/deposits/94/fb/2c/58/94fb2c58-f7b6-4670-b0e0-90f6f8a449e2 as FAILED
org.dataconservancy.pass.deposit.messaging.DepositServiceRuntimeException: Failed to perform deposit for tuple [http://fcrepo:8080/fcrepo/rest/submissions/f2/df/33/66/f2df3366-607d-4cc3-aff4-b52e762bea21, http://fcrepo:8080/fcrepo/rest/repositories/93/c5/ff/37/93c5ff37-ca2b-4652-a2af-3b6794ff8790, http://fcrepo:8080/fcrepo/rest/deposits/94/fb/2c/58/94fb2c58-f7b6-4670-b0e0-90f6f8a449e2]: Postcondition failed for http://fcrepo:8080/fcrepo/rest/deposits/94/fb/2c/58/94fb2c58-f7b6-4670-b0e0-90f6f8a449e2: Transport of package to endpoint failed: Error depositing SWORD package to 'https://dash-sword-qa.lib.harvard.edu/swordv2/collection/1/37156602': javax.net.ssl.SSLHandshakeException: Remote host closed connection during handshake
    at org.dataconservancy.pass.deposit.messaging.service.DepositTask.run(DepositTask.java:129)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: Postcondition failed for http://fcrepo:8080/fcrepo/rest/deposits/94/fb/2c/58/94fb2c58-f7b6-4670-b0e0-90f6f8a449e2: Transport of package to endpoint failed: Error depositing SWORD package to 'https://dash-sword-qa.lib.harvard.edu/swordv2/collection/1/37156602': javax.net.ssl.SSLHandshakeException: Remote host closed connection during handshake
    at org.dataconservancy.pass.deposit.messaging.service.DepositTask$DepositTaskCriFunc.lambda$depositPostcondition$2(DepositTask.java:471)
    at org.dataconservancy.pass.support.messaging.cri.CriticalPath.performCritical(CriticalPath.java:236)
    at org.dataconservancy.pass.deposit.messaging.service.DepositTask.run(DepositTask.java:104)
    ... 3 common frames omitted
Caused by: java.lang.RuntimeException: Error depositing SWORD package to 'https://dash-sword-qa.lib.harvard.edu/swordv2/collection/1/37156602': javax.net.ssl.SSLHandshakeException: Remote host closed connection during handshake
    at org.dataconservancy.pass.deposit.transport.sword2.Sword2TransportSession.send(Sword2TransportSession.java:166)
    at org.dataconservancy.pass.deposit.messaging.service.DepositTask$DepositTaskCriFunc.lambda$performDeposit$1(DepositTask.java:442)
    at org.dataconservancy.pass.support.messaging.cri.CriticalPath.performCritical(CriticalPath.java:191)
    ... 4 common frames omitted
Caused by: java.lang.RuntimeException: javax.net.ssl.SSLHandshakeException: Remote host closed connection during handshake
    at org.apache.abdera.protocol.client.AbderaClient.execute(AbderaClient.java:701)
    at org.apache.abdera.protocol.client.AbderaClient.post(AbderaClient.java:238)
    at org.swordapp.client.SWORDClient.deposit(SWORDClient.java:358)
    at org.swordapp.client.SWORDClient.deposit(SWORDClient.java:265)
    at org.dataconservancy.pass.deposit.transport.sword2.Sword2TransportSession.send(Sword2TransportSession.java:155)
    ... 6 common frames omitted
Caused by: javax.net.ssl.SSLHandshakeException: Remote host closed connection during handshake
    at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:1002)
    at sun.security.ssl.SSLSocketImpl.performInitialHandshake(SSLSocketImpl.java:1385)
    at sun.security.ssl.SSLSocketImpl.writeRecord(SSLSocketImpl.java:757)
    at sun.security.ssl.AppOutputStream.write(AppOutputStream.java:123)
    at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
    at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
    at org.apache.commons.httpclient.HttpConnection.flushRequestOutputStream(HttpConnection.java:828)
    at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.flushRequestOutputStream(MultiThreadedHttpConnectionManager.java:1565)
    at org.apache.commons.httpclient.HttpMethodBase.writeRequest(HttpMethodBase.java:2077)
    at org.apache.commons.httpclient.HttpMethodBase.execute(HttpMethodBase.java:1096)
    at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:398)
    at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171)
    at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397)
    at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:323)
    at org.apache.abdera.protocol.client.AbderaClient.execute(AbderaClient.java:688)
    ... 10 common frames omitted
Caused by: java.io.EOFException: SSL peer shut down incorrectly
    at sun.security.ssl.InputRecord.read(InputRecord.java:505)
    at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:983)
    ... 24 common frames omitted
emetsger commented 4 years ago

I have seen this exception once, two days ago. But when I started PASS up (locally) and performed a submission today, things worked fine- no SSLHandshakeException.

After bouncing PASS locally, I re-attempted a submission, and got the same SSLHandshakeException from a couple of days ago.

emetsger commented 4 years ago

Startup log:

Picked up JAVA_TOOL_OPTIONS: -agentlib:jdwp=transport=dt_socket,server=y,suspend=n,address=5007
18:05:53.399 [                main] INFO  [                    DepositApp] - Starting DepositServices (version: 1.1.0-3.5-SNAPSHOT branch: master commit: 65bcc813 commit date: 2019-11-21T08:35:45-0500 build date: 2019-12-04T09:23:52-0500)
18:05:58.792 [                main] INFO  [   DepositApp$DepositAppBanner] - Environment:
18:05:58.796 [                main] INFO  [   DepositApp$DepositAppBanner] -    ACTIVEMQ_JMS_PORT                                        '61616'
18:05:58.796 [                main] INFO  [   DepositApp$DepositAppBanner] -    ACTIVEMQ_STOMP_PORT                                      '61613'
18:05:58.860 [                main] INFO  [   DepositApp$DepositAppBanner] -    ACTIVEMQ_WEBCONSOLE_PORT                                 '8161'
18:05:58.861 [                main] INFO  [   DepositApp$DepositAppBanner] -    DEPOSIT_DEBUG_PORT                                       '5007'
18:05:58.861 [                main] INFO  [   DepositApp$DepositAppBanner] -    DEPOSIT_SERVICES_VERSION       Listening for transport dt_socket at address: 5007
                          '1.1.0-3.5-SNAPSHOT'
18:05:58.861 [                main] INFO  [   DepositApp$DepositAppBanner] -    DMS_DEBUG                                                '0'
18:05:58.864 [                main] INFO  [   DepositApp$DepositAppBanner] -    DOI_SERVICE_URL                                          '/doiservice/journal'
18:05:58.864 [                main] INFO  [   DepositApp$DepositAppBanner] -    DSPACE_BASEURI                                           'https://dash-sword-qa.lib.harvard.edu'
18:05:58.891 [                main] INFO  [   DepositApp$DepositAppBanner] -    DSPACE_COLLECTION_WORKFLOW_HANDLE                        '1/37156602'
18:05:58.891 [                main] INFO  [   DepositApp$DepositAppBanner] -    DSPACE_HOST                                              'dash-sword-qa.lib.harvard.edu'
18:05:58.894 [                main] INFO  [   DepositApp$DepositAppBanner] -    DSPACE_PORT                                              '443'
18:05:58.894 [                main] INFO  [   DepositApp$DepositAppBanner] -    DSPACE_SUBMITTER_PASSWORD                                '4PASSHarvardTesting'
18:05:58.895 [                main] INFO  [   DepositApp$DepositAppBanner] -    DSPACE_SUBMITTER_USERNAME                                'emetsger@jhu.edu'
18:05:58.895 [                main] INFO  [   DepositApp$DepositAppBanner] -    EMBER_PORT                                               '81'
18:05:58.895 [                main] INFO  [   DepositApp$DepositAppBanner] -    EMBER_ROOT_URL                                           '/app/'
18:05:58.895 [                main] INFO  [   DepositApp$DepositAppBanner] -    ENABLE_CLAMAV                                            '0'
18:05:58.895 [                main] INFO  [   DepositApp$DepositAppBanner] -    ENABLE_FAIL2BAN                                          '0'
18:05:58.896 [                main] INFO  [   DepositApp$DepositAppBanner] -    ENABLE_LDAP                                              '1'
18:05:58.896 [                main] INFO  [   DepositApp$DepositAppBanner] -    ENABLE_POSTGREY                                          '0'
18:05:58.896 [                main] INFO  [   DepositApp$DepositAppBanner] -    ENABLE_SASLAUTHD                                         '0'
18:05:58.896 [                main] INFO  [   DepositApp$DepositAppBanner] -    ENABLE_SPAMASSASSIN                                      '0'
18:05:58.896 [                main] INFO  [   DepositApp$DepositAppBanner] -    ES_HOST                                                  'elasticsearch'
18:05:58.897 [                main] INFO  [   DepositApp$DepositAppBanner] -    ES_PORT                                                  '9200'
18:05:58.919 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_DEBUG_PORT                                        '5006'
18:05:58.920 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_HOME                                              '/data'
18:05:58.920 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_HOST                                              'fcrepo'
18:05:58.920 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_JMS_BASEURL                                       'http://fcrepo:8080/fcrepo/rest'
18:05:58.921 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_JMS_CONFIGURATION                                 'classpath:/pass-jms-external.xml'
18:05:58.937 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_JMS_DESTINATION                                   'VirtualTopic.pass.docker'
18:05:58.938 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_JMS_PORT                                          '61616'
18:05:58.938 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_LOGBACK_LOCATION                                  'webapps/fcrepo/WEB-INF/classes/logback.xml'
18:05:58.942 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_LOG_JMS                                           'INFO'
18:05:58.949 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_LOG_LEVEL                                         'INFO'
18:05:58.950 [                main] INFO  [   DepositApp$DepositAppBanner] -    FCREPO_PORT                                              '8080'
18:05:58.950 [                main] INFO  [   DepositApp$DepositAppBanner] -    FTP_HOST                                                 'ftpserver'
18:05:58.950 [                main] INFO  [   DepositApp$DepositAppBanner] -    FTP_PASS                                                 'nihmsftppass'
18:05:58.950 [                main] INFO  [   DepositApp$DepositAppBanner] -    FTP_PORT                                                 '21'
18:05:58.951 [                main] INFO  [   DepositApp$DepositAppBanner] -    FTP_USER                                                 'nihmsftpuser'
18:05:58.951 [                main] INFO  [   DepositApp$DepositAppBanner] -    HOME                                                     '/root'
18:05:58.951 [                main] INFO  [   DepositApp$DepositAppBanner] -    HOSTNAME                                                 '4a98f7d82403'
18:05:58.952 [                main] INFO  [   DepositApp$DepositAppBanner] -    IMAGE_JAVA_HOME                                          '/usr/lib/jvm/java-1.8-openjdk/jre'
18:05:58.952 [                main] INFO  [   DepositApp$DepositAppBanner] -    JAVA_ALPINE_VERSION                                      '8.151.12-r0'
18:05:58.952 [                main] INFO  [   DepositApp$DepositAppBanner] -    JAVA_HOME                                                '/usr/lib/jvm/java-1.8-openjdk'
18:05:58.952 [                main] INFO  [   DepositApp$DepositAppBanner] -    JAVA_TOOL_OPTIONS                                        '-agentlib:jdwp=transport=dt_socket,server=y,suspend=n,address=5007'
18:05:58.953 [                main] INFO  [   DepositApp$DepositAppBanner] -    JAVA_VERSION                                             '8u151'
18:05:58.953 [                main] INFO  [   DepositApp$DepositAppBanner] -    JSONLD_CONTEXT_VERSION                                   '3.5'
18:05:58.960 [                main] INFO  [   DepositApp$DepositAppBanner] -    LANG                                                     'C.UTF-8'
18:05:58.965 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_BIND_DN                                             'cn=admin,dc=pass'
18:05:58.966 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_BIND_PW                                             'password'
18:05:58.966 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_QUERY_FILTER_ALIAS                                  '(&(objectClass=posixAccount)(mailAlias=%s))'
18:05:58.966 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_QUERY_FILTER_DOMAIN                                 '(|(mail=*@%s)(mailalias=*@%s)(mailGroupMember=*@%s))'
18:05:58.967 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_QUERY_FILTER_GROUP                                  '(&(objectClass=posixAccount)(mailGroupMember=%s))'
18:05:58.967 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_QUERY_FILTER_USER                                   '(&(objectClass=posixAccount)(mail=%s))'
18:05:58.967 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_SEARCH_BASE                                         'ou=People,dc=pass'
18:05:58.967 [                main] INFO  [   DepositApp$DepositAppBanner] -    LDAP_SERVER_HOST                                         'ldap'
18:05:58.969 [                main] INFO  [   DepositApp$DepositAppBanner] -    LD_LIBRARY_PATH                                          '/usr/lib/jvm/java-1.8-openjdk/jre/lib/amd64/server:/usr/lib/jvm/java-1.8-openjdk/jre/lib/amd64:/usr/lib/jvm/java-1.8-openjdk/jre/../lib/amd64'
18:05:58.969 [                main] INFO  [   DepositApp$DepositAppBanner] -    LOGGING_LEVEL_ORG_DATACONSERVANCY_PASS_DEPOSIT           'TRACE'
18:05:58.969 [                main] INFO  [   DepositApp$DepositAppBanner] -    LOGGING_LEVEL_ORG_DATACONSERVANCY_PASS_DEPOSIT_MESSAGING 'TRACE'
18:05:58.970 [                main] INFO  [   DepositApp$DepositAppBanner] -    LOGGING_LEVEL_org.dataconservancy.pass.deposit           'DEBUG'
18:05:58.993 [                main] INFO  [   DepositApp$DepositAppBanner] -    MAIL_IMAPS                                               '11993'
18:05:58.993 [                main] INFO  [   DepositApp$DepositAppBanner] -    MAIL_MSP                                                 '11587'
18:05:58.993 [                main] INFO  [   DepositApp$DepositAppBanner] -    MAIL_SMTP                                                '11025'
18:05:58.993 [                main] INFO  [   DepositApp$DepositAppBanner] -    METADATA_SCHEMA_URI                                      'https://oa-pass.github.io/metadata-schemas/harvard/global.json'
18:05:58.994 [                main] INFO  [   DepositApp$DepositAppBanner] -    NLSPATH                                                  '/usr/dt/lib/nls/msg/%L/%N.cat'
18:05:58.994 [                main] INFO  [   DepositApp$DepositAppBanner] -    NOTIFICATION_DEBUG_PORT                                  '5011'
18:05:58.994 [                main] INFO  [   DepositApp$DepositAppBanner] -    NOTIFICATION_OPTS                                        '-Dorg.dataconservancy.pass.notification.level=DEBUG'
18:05:58.994 [                main] INFO  [   DepositApp$DepositAppBanner] -    ONE_DIR                                                  '1'
18:05:58.995 [                main] INFO  [   DepositApp$DepositAppBanner] -    OVERRIDE_HOSTNAME                                        'mail.jhu.edu'
18:05:58.995 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_AUTHZ_QUEUE                                         'Consumer.authz.VirtualTopic.pass.docker'
18:05:58.995 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_BACKEND_ROLE                                        'http://oapass.org/ns/roles/harvard.edu#pass-backend'
18:05:58.995 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DEPOSIT_JOBS_DEFAULT_INTERVAL_MS                    '30000'
18:05:58.995 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DEPOSIT_OAICLIENT_DASH_OAIBASEURL                   'https://dspace6-qa.lib.harvard.edu/oai/request'
18:05:58.996 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DEPOSIT_PROVIDER_DASH_COPYBASEURL                   'http://nrstest.harvard.edu:9031/'
18:05:58.999 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DEPOSIT_QUEUE_DEPOSIT_NAME                          'Consumer.deposit.VirtualTopic.pass.docker'
18:05:58.999 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DEPOSIT_QUEUE_SUBMISSION_NAME                       'Consumer.submission.VirtualTopic.pass.docker'
18:05:59.000 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DEPOSIT_REPOSITORY_CONFIGURATION                    'file:/repositories-harvard.json'
18:05:59.030 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DEPOSIT_TRANSPORT_SWORDV2_FOLLOWREDIRECTS           'true'
18:05:59.030 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_DOI_SERVICE_PORT                                    '8090'
18:05:59.030 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_ELASTICSEARCH_LIMIT                                 '100'
18:05:59.030 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_ELASTICSEARCH_URL                                   'http://elasticsearch:9200'
18:05:59.031 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_EXTERNAL_FEDORA_BASEURL                             'https://pass.local/fcrepo/rest/'
18:05:59.031 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_FEDORA_BASEURL                                      'http://fcrepo:8080/fcrepo/rest/'
18:05:59.031 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_FEDORA_PASSWORD                                     'moo'
18:05:59.032 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_FEDORA_USER                                         'fedoraAdmin'
18:05:59.032 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_GRANTADMIN_ROLE                                     'http://oapass.org/ns/roles/harvard.edu#admin'
18:05:59.032 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_LINK_HOST                                           'pass.local'
18:05:59.032 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_LINK_SCHEME                                         'https'
18:05:59.032 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_LINK_USERTOKEN_GENERATOR_KEY                        'BETKPFHWGGDIEWIIYKYQ33LUS4'
18:05:59.033 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_CONFIGURATION                          'file:/notification.json'
18:05:59.033 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_DEMO_FROM_ADDRESS                      'noreply@pass.jh.edu'
18:05:59.035 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_DEMO_GLOBAL_CC_ADDRESS                 'notification-demo-cc@jhu.edu'
18:05:59.035 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_MODE                                   'DEMO'
18:05:59.043 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_PRODUCTION_FROM_ADDRESS                'noreply@pass.jh.edu'
18:05:59.044 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_PRODUCTION_GLOBAL_CC_ADDRESS           ''
18:05:59.044 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_QUEUE_EVENT_NAME                       'Consumer.event.VirtualTopic.pass.docker'
18:05:59.044 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_SMTP_HOST                              'mail'
18:05:59.044 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_SMTP_PASS                              ''
18:05:59.045 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_SMTP_PORT                              '587'
18:05:59.059 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_SMTP_TRANSPORT                         'SMTP'
18:05:59.059 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_SMTP_USER                              ''
18:05:59.059 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_APPROVAL_INVITE_BODY          'file:/templates/approval-invite-body.hbr'
18:05:59.059 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_APPROVAL_INVITE_FOOTER        'file:/templates/footer.hbr'
18:05:59.060 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_APPROVAL_INVITE_SUBJECT       'file:/templates/approval-invite-subject.hbr'
18:05:59.061 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_APPROVAL_REQUESTED_BODY       'file:/templates/approval-requested-body.hbr'
18:05:59.061 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_APPROVAL_REQUESTED_FOOTER     'file:/templates/footer.hbr'
18:05:59.061 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_APPROVAL_REQUESTED_SUBJECT    'file:/templates/approval-requested-subject.hbr'
18:05:59.061 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_CHANGES_REQUESTED_BODY        'file:/templates/changes-requested-body.hbr'
18:05:59.062 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_CHANGES_REQUESTED_FOOTER      'file:/templates/footer.hbr'
18:05:59.062 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_CHANGES_REQUESTED_SUBJECT     'file:/templates/changes-requested-subject.hbr'
18:05:59.062 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_SUBMISSION_CANCELLED_BODY     'file:/templates/submission-cancelled-body.hbr'
18:05:59.083 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_SUBMISSION_CANCELLED_FOOTER   'file:/templates/footer.hbr'
18:05:59.084 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_SUBMISSION_CANCELLED_SUBJECT  'file:/templates/submission-cancelled-subject.hbr'
18:05:59.084 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_SUBMISSION_SUBMITTED_BODY     'file:/templates/submission-submitted-body.hbr'
18:05:59.084 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_SUBMISSION_SUBMITTED_FOOTER   'file:/templates/footer.hbr'
18:05:59.084 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_NOTIFICATION_TEMPLATE_SUBMISSION_SUBMITTED_SUBJECT  'file:/templates/submission-submitted-subject.hbr'
18:05:59.085 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_SUBMITTER_ROLE                                      'http://oapass.org/ns/roles/harvard.edu#submitter'
18:05:59.085 [                main] INFO  [   DepositApp$DepositAppBanner] -    PASS_USER_TOKEN_KEY                                      'BETKPFHWGGDIEWIIYKYQ33LUS4'
18:05:59.085 [                main] INFO  [   DepositApp$DepositAppBanner] -    PATH                                                     '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/lib/jvm/java-1.8-openjdk/jre/bin:/usr/lib/jvm/java-1.8-openjdk/bin'
18:05:59.085 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_ES_BASE                                               'http://elasticsearch:9200/'
18:05:59.085 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_ES_CONFIG                                             'https://raw.githubusercontent.com/emetsger/pass-data-model/dash/src/main/resources/esconfig-3.5.json'
18:05:59.086 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_ES_INDEX                                              'http://elasticsearch:9200/pass/'
18:05:59.086 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_FEDORA_INTERNAL_BASE                                  'http://fcrepo:8080/fcrepo/rest/'
18:05:59.094 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_FEDORA_JMS_QUEUE                                      'Consumer.indexer.VirtualTopic.pass.docker'
18:05:59.094 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_FEDORA_PASS                                           'moo'
18:05:59.104 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_FEDORA_USER                                           'fedoraAdmin'
18:05:59.105 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_LOG_LEVEL                                             'info'
18:05:59.105 [                main] INFO  [   DepositApp$DepositAppBanner] -    PI_TYPE_PREFIX                                           'http://oapass.org/ns/pass#'
18:05:59.106 [                main] INFO  [   DepositApp$DepositAppBanner] -    POLICY_FILE                                              'harvard.json'
18:05:59.106 [                main] INFO  [   DepositApp$DepositAppBanner] -    POLICY_SERVICE_PORT                                      '8088'
18:05:59.106 [                main] INFO  [   DepositApp$DepositAppBanner] -    POLICY_SERVICE_URL                                       '/policyservice'
18:05:59.106 [                main] INFO  [   DepositApp$DepositAppBanner] -    POSTGRES_DB_PORT                                         '6543'
18:05:59.107 [                main] INFO  [   DepositApp$DepositAppBanner] -    POSTMASTER_ADDRESS                                       'root'
18:05:59.107 [                main] INFO  [   DepositApp$DepositAppBanner] -    SCHEMA_SERVICE_PORT                                      '8086'
18:05:59.107 [                main] INFO  [   DepositApp$DepositAppBanner] -    SMTP_ONLY                                                '0'
18:05:59.111 [                main] INFO  [   DepositApp$DepositAppBanner] -    SPRING_ACTIVEMQ_BROKER_URL                               'failover:(tcp://activemq:61616)?trackMessages=true&maxCacheSize=100000000'
18:05:59.114 [                main] INFO  [   DepositApp$DepositAppBanner] -    SPRING_ACTIVEMQ_PASSWORD                                 'moo'
18:05:59.115 [                main] INFO  [   DepositApp$DepositAppBanner] -    SPRING_ACTIVEMQ_USER                                     'messaging'
18:05:59.117 [                main] INFO  [   DepositApp$DepositAppBanner] -    SSL_CERT_PATH                                            '/tmp/docker-mailserver/cert.pem'
18:05:59.117 [                main] INFO  [   DepositApp$DepositAppBanner] -    SSL_KEY_PATH                                             '/tmp/docker-mailserver/key.rsa'
18:05:59.144 [                main] INFO  [   DepositApp$DepositAppBanner] -    SSL_TYPE                                                 'manual'
18:05:59.159 [                main] INFO  [   DepositApp$DepositAppBanner] -    STATIC_HTML_PORT                                         '82'
18:05:59.159 [                main] INFO  [   DepositApp$DepositAppBanner] -    TLS_LEVEL                                                'intermediate'
18:05:59.159 [                main] INFO  [   DepositApp$DepositAppBanner] -    XFILESEARCHPATH                                          '/usr/dt/app-defaults/%L/Dt'
18:06:00.640 [                main] INFO  [             StartupInfoLogger] - Starting DepositApp on 4a98f7d82403 with PID 1 (/app/BOOT-INF/classes started by root in /app)
18:06:00.640 [                main] DEBUG [             StartupInfoLogger] - Running with Spring Boot v2.1.2.RELEASE, Spring v5.1.4.RELEASE
18:06:00.644 [                main] INFO  [             SpringApplication] - No active profile set, falling back to default profiles: default
18:06:21.988 [                main] DEBUG [                 DepositConfig] - Discovered Assembler implementation nihmsAssembler: org.dataconservancy.pass.deposit.provider.nihms.NihmsAssembler
18:06:21.992 [                main] DEBUG [                 DepositConfig] - Discovered Assembler implementation bagItAssembler: edu.jhu.library.pass.deposit.provider.bagit.BagItAssembler
18:06:21.994 [                main] DEBUG [                 DepositConfig] - Discovered Assembler implementation dashDspaceMetsAssembler: edu.jhu.library.pass.deposit.provider.dash.DashDspaceMetsAssembler
18:06:21.994 [                main] DEBUG [                 DepositConfig] - Discovered Assembler implementation j10PDspaceMetsAssembler: edu.jhu.library.pass.deposit.provider.j10p.J10PDspaceMetsAssembler
18:06:22.183 [                main] DEBUG [                 DepositConfig] - Discovered Transport implementation filesystemTransport: org.dataconservancy.pass.deposit.transport.fs.FilesystemTransport
18:06:22.183 [                main] DEBUG [                 DepositConfig] - Discovered Transport implementation ftpTransport: org.dataconservancy.pass.deposit.transport.ftp.FtpTransport
18:06:22.183 [                main] DEBUG [                 DepositConfig] - Discovered Transport implementation sword2Transport: org.dataconservancy.pass.deposit.transport.sword2.Sword2Transport
18:06:22.234 [                main] INFO  [                 DepositConfig] - Configuring Packager for Repository configuration pmc
18:06:22.235 [                main] INFO  [                 DepositConfig] -   Repository Key: pmc
18:06:22.235 [                main] INFO  [                 DepositConfig] -   Assembler: nihmsAssembler
18:06:22.235 [                main] INFO  [                 DepositConfig] -   Transport Binding: ftp
18:06:22.235 [                main] INFO  [                 DepositConfig] -   Transport Implementation: org.dataconservancy.pass.deposit.transport.ftp.FtpTransport@48c40605
18:06:23.137 [                main] INFO  [                 DepositConfig] - Configuring Packager for Repository configuration dash
18:06:23.137 [                main] INFO  [                 DepositConfig] -   Repository Key: dash
18:06:23.180 [                main] INFO  [                 DepositConfig] -   Assembler: dashDspaceMetsAssembler
18:06:23.181 [                main] INFO  [                 DepositConfig] -   Transport Binding: SWORDv2
18:06:23.181 [                main] INFO  [                 DepositConfig] -   Transport Implementation: org.dataconservancy.pass.deposit.transport.sword2.Sword2Transport@69f63d95
18:06:23.182 [                main] INFO  [                 DepositConfig] -   Deposit Status Processor: oaiPmhStatusProcessor
18:06:23.508 [                main] INFO  [        FilesystemModelBuilder] - FilesystemModelBuilder will build DepositSubmission objects using Fedora resources instead of local resources
18:06:33.352 [                main] INFO  [             StartupInfoLogger] - Started DepositApp in 39.175 seconds (JVM running for 45.568)
18:06:33.467 [     pool-2-thread-1] TRACE [                ListenerRunner] - Executing GET http://fcrepo:8080/fcrepo/rest/
18:06:44.619 [     pool-2-thread-1] TRACE [                ListenerRunner] - Executing GET http://fcrepo:8080/fcrepo/rest/
18:06:55.391 [     Quartz-Worker-0] TRACE [    SubmissionStatusUpdaterJob] - Starting SubmissionStatusUpdaterJob
18:06:56.137 [     pool-2-thread-1] TRACE [                ListenerRunner] - Executing GET http://fcrepo:8080/fcrepo/rest/
18:06:56.261 [     Quartz-Worker-0] TRACE [       SubmissionStatusUpdater] - Updating the Submission.submissionStatus of 54 Submissions
18:06:56.262 [     Quartz-Worker-0] TRACE [       SubmissionStatusUpdater] - Updating Submission.submissionStatus for http://fcrepo:8080/fcrepo/rest/submissions/ea/16/22/a0/ea1622a0-02ee-419f-ab31-4c7fb5a6e74c
18:06:59.594 [                main] INFO  [                ListenerRunner] - Fedora repository is up at 'http://fcrepo:8080/fcrepo/rest/'
emetsger commented 4 years ago

The SSLHandshakeException fails the Submission (i.e. it is a terminal state, there is no recovery). So you have to start over and re-create the submission.

When I re-created the submission, it went through, no problem. No SSLHandshakeException was encountered.

rpoet-jh commented 9 months ago

The migration of PASS repositories to eclipse-pass is complete. This issue is no longer relevant and is being closed as part of archiving https://github.com/eclipse-pass/main/issues/585.