Uploaded image for project: 'Java Driver'
  1. Java Driver
  2. JAVA-4987

failed: ClientSideEncryptionTest.shouldPassAllOutcomes_explain.json_Explain_a_find_with_deterministic_encryption

    XMLWordPrintableJSON

Details

    • Icon: Build Failure Build Failure
    • Resolution: Unresolved
    • Icon: Unknown Unknown
    • None
    • None
    • None

    Description

      test on tests-jdk-newer-secure__version~4.2_os~linux_topology~replicaset_auth~auth_ssl~ssl_jdk~jdk8

      Host: i-0918184916b0d6333
      Project: mongo-java-driver-3.12
      Commit: d08b993c

      Failing Test Logs:

      • com.mongodb.async.client.ClientSideEncryptionTest.shouldPassAllOutcomes_explain.json_Explain_a_find_with_deterministic_encryption - Logs | History

      Task Logs:

      test task_log

      Logs:

          20:45:44.328 [async-channel-group-0-handler-executor] INFO  org.mongodb.driver.cluster - No server chosen by ReadPreferenceServerSelector{readPreference=primary} from cluster description ClusterDescription{type=REPLICA_SET, connectionMode=MULTIPLE, serverDescriptions=[ServerDescription{address=localhost:27017, type=UNKNOWN, state=CONNECTING, exception={com.mongodb.MongoNotPrimaryException: Command failed with error 10107 (NotWritablePrimary): 'Failing command due to 'failCommand' failpoint' on server localhost:27017. The full response is {"operationTime": {"$timestamp": {"t": 1685133944, "i": 3}}, "ok": 0.0, "errmsg": "Failing command due to 'failCommand' failpoint", "code": 10107, "codeName": "NotWritablePrimary", "$clusterTime": {"clusterTime": {"$timestamp": {"t": 1685133944, "i": 3}}, "signature": {"hash": {"$binary": "BpuwgnZL12rUllN+v87H9uj8DCg=", "$type": "00"}, "keyId": {"$numberLong": "7237594783722504196"}}}}}}, ServerDescription{address=localhost:27018, type=REPLICA_SET_SECONDARY, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=5354055, setName='repl0', canonicalAddress=localhost:27018, hosts=[localhost:27018, localhost:27017], passives=[], arbiters=[localhost:27019], primary='localhost:27017', tagSet=TagSet{[Tag{name='dc', value='pa'}, Tag{name='ordinal', value='two'}]}, electionId=null, setVersion=1, lastWriteDate=Fri May 26 20:45:43 UTC 2023, lastUpdateTimeNanos=615628851069}, ServerDescription{address=localhost:27019, type=REPLICA_SET_ARBITER, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=2430461, setName='repl0', canonicalAddress=localhost:27019, hosts=[localhost:27018, localhost:27017], passives=[], arbiters=[localhost:27019], primary='localhost:27017', tagSet=TagSet{[]}, electionId=null, setVersion=1, lastWriteDate=Fri May 26 20:45:41 UTC 2023, lastUpdateTimeNanos=615626145720}]}. Waiting for 30000 ms before timing out
          20:45:44.542 [cluster-ClusterId{value='64711a77ae18c5454f6b4f72', description='null'}-localhost:27017] INFO  org.mongodb.driver.cluster - Discovered replica set primary localhost:27017
          20:45:44.581 [async-channel-group-0-handler-executor] INFO  org.mongodb.driver.connection - Opened connection [connectionId{localValue:9, serverValue:27}] to localhost:27017
           ? testResumeOneTimeOnError (3764 ms)
           ? testMissingResumeTokenThrowsException (111 ms)
           ? testNoResumeErrors (51 ms)
           ? testNoResumeForAggregateErrors (27 ms)
          com.mongodb.MongoSocketOpenException: Exception opening socket
          	at com.mongodb.internal.connection.AsynchronousSocketChannelStream$OpenCompletionHandler.failed(AsynchronousSocketChannelStream.java:117) ~[mongodb-driver-core-3.12.14-SNAPSHOT.jar:na]
          	at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:128) ~[na:1.8.0_162]
          	at sun.nio.ch.Invoker$2.run(Invoker.java:218) ~[na:1.8.0_162]
          	at sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112) ~[na:1.8.0_162]
          	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[na:1.8.0_162]
          	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[na:1.8.0_162]
          	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_162]
          20:45:50.579 [async-channel-group-30-handler-executor] INFO  org.mongodb.driver.cluster - No server chosen by com.mongodb.async.client.ClientSessionHelper$1@6cc1dff2 from cluster description ClusterDescription{type=UNKNOWN, connectionMode=SINGLE, serverDescriptions=[ServerDescription{address=localhost:27020, type=UNKNOWN, state=CONNECTING, exception={com.mongodb.MongoSocketOpenException: Exception opening socket}, caused by {java.net.ConnectException: Connection refused}}]}. Waiting for 10000 ms before timing out
          20:45:50.811 [cluster-ClusterId{value='64711a7eae18c5454f6b4f9f', description='null'}-localhost:27020] INFO  org.mongodb.driver.connection - Opened connection [connectionId{localValue:103}] to localhost:27020
          20:45:50.812 [cluster-ClusterId{value='64711a7eae18c5454f6b4f9f', description='null'}-localhost:27020] INFO  org.mongodb.driver.cluster - Monitor thread successfully connected to server with description ServerDescription{address=localhost:27020, type=STANDALONE, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=null, roundTripTimeNanos=471861}
          20:45:50.815 [Thread-39] INFO  org.mongodb.driver.connection - Opened connection [connectionId{localValue:104}] to localhost:27020
          20:45:50.840 [async-channel-group-30-handler-executor] INFO  org.mongodb.driver.connection - Opened connection [connectionId{localValue:105, serverValue:74}] to localhost:27017
          20:45:50.862 [async-channel-group-30-handler-executor] INFO  org.mongodb.driver.connection - Opened connection [connectionId{localValue:106, serverValue:75}] to localhost:27017
          20:45:50.888 [async-channel-group-30-handler-executor] INFO  org.mongodb.driver.connection - Opened connection [connectionId{localValue:107, serverValue:76}] to localhost:27017
           ? testCorpus[useLocalSchema: true] (1165 ms)
          com.mongodb.MongoClientException: Exception in encryption library: Connection reset by peer
          20:49:39.577 [Test worker] INFO  org.mongodb.driver.cluster - No server chosen by WritableServerSelector from cluster description ClusterDescription{type=REPLICA_SET, connectionMode=MULTIPLE, serverDescriptions=[ServerDescription{address=localhost:27017, type=UNKNOWN, state=CONNECTING, exception={com.mongodb.MongoNotPrimaryException: Command failed with error 10107 (NotWritablePrimary): 'not master' on server localhost:27017. The full response is {"operationTime": {"$timestamp": {"t": 1685134179, "i": 18}}, "ok": 0.0, "errmsg": "not master", "code": 10107, "codeName": "NotWritablePrimary", "$clusterTime": {"clusterTime": {"$timestamp": {"t": 1685134179, "i": 18}}, "signature": {"hash": {"$binary": "CWgeLFMGi9Ur3R/6vc7mIFQIxTo=", "$type": "00"}, "keyId": {"$numberLong": "7237594783722504196"}}}}}}, ServerDescription{address=localhost:27018, type=REPLICA_SET_SECONDARY, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=676689, setName='repl0', canonicalAddress=localhost:27018, hosts=[localhost:27018, localhost:27017], passives=[], arbiters=[localhost:27019], primary='localhost:27017', tagSet=TagSet{[Tag{name='dc', value='pa'}, Tag{name='ordinal', value='two'}]}, electionId=null, setVersion=1, lastWriteDate=Fri May 26 20:49:39 UTC 2023, lastUpdateTimeNanos=851074007830}, ServerDescription{address=localhost:27019, type=REPLICA_SET_ARBITER, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=899366, setName='repl0', canonicalAddress=localhost:27019, hosts=[localhost:27018, localhost:27017], passives=[], arbiters=[localhost:27019], primary='localhost:27017', tagSet=TagSet{[]}, electionId=null, setVersion=1, lastWriteDate=Fri May 26 20:49:38 UTC 2023, lastUpdateTimeNanos=851071868823}]}. Waiting for 30000 ms before timing out
          20:49:39.775 [cluster-ClusterId{value='64711a7dae18c5454f6b4f9a', description='null'}-localhost:27017] INFO  org.mongodb.driver.cluster - Monitor thread successfully connected to server with description ServerDescription{address=localhost:27017, type=REPLICA_SET_SECONDARY, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=1308044, setName='repl0', canonicalAddress=localhost:27017, hosts=[localhost:27018, localhost:27017], passives=[], arbiters=[localhost:27019], primary='null', tagSet=TagSet{[Tag{name='dc', value='ny'}, Tag{name='ordinal', value='one'}]}, electionId=null, setVersion=1, lastWriteDate=Fri May 26 20:49:39 UTC 2023, lastUpdateTimeNanos=851369721253}
          20:49:39.978 [cluster-ClusterId{value='64711b63ae18c5454f6b506b', description='null'}-localhost:27017] INFO  org.mongodb.driver.cluster - Monitor thread successfully connected to server with description ServerDescription{address=localhost:27017, type=REPLICA_SET_SECONDARY, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=991549, setName='repl0', canonicalAddress=localhost:27017, hosts=[localhost:27018, localhost:27017], passives=[], arbiters=[localhost:27019], primary='null', tagSet=TagSet{[Tag{name='dc', value='ny'}, Tag{name='ordinal', value='one'}]}, electionId=null, setVersion=1, lastWriteDate=Fri May 26 20:49:39 UTC 2023, lastUpdateTimeNanos=851572804057}
          20:49:40.980 [cluster-ClusterId{value='64711b63ae18c5454f6b506b', description='null'}-localhost:27018] INFO  org.mongodb.driver.cluster - Monitor thread successfully connected to server with description ServerDescription{address=localhost:27018, type=REPLICA_SET_SECONDARY, state=CONNECTED, ok=true, version=ServerVersion{versionList=[4, 2, 24]}, minWireVersion=0, maxWireVersion=8, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=692455, setName='repl0', canonicalAddress=localhost:27018, hosts=[localhost:27018, localhost:27017], passives=[], arbiters=[localhost:27019], primary='null', tagSet=TagSet{[Tag{name='dc', value='pa'}, Tag{name='ordinal', value='two'}]}, electionId=null, setVersion=1, lastWriteDate=Fri May 26 20:49:39 UTC 2023, lastUpdateTimeNanos=852574171111

      logs

      Attachments

        Activity

          People

            Unassigned Unassigned
            Xgen-BuildBaron-User xgen-buildbaron-user
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

            Dates

              Created:
              Updated: