Uploaded image for project: 'Cassandra'
  1. Cassandra
  2. CASSANDRA-19465

Test Failure: configuration_test.TestConfiguration.test_change_durable_writes

    XMLWordPrintableJSON

Details

    • Bug
    • Status: Resolved
    • Normal
    • Resolution: Fixed
    • 5.0-rc, 5.1
    • Test/dtest/python
    • None

    Description

      https://app.circleci.com/pipelines/github/bereng/cassandra/1181/workflows/fe2ac859-f6ba-4f1e-b0b1-e6923b16e874/jobs/39449/tests

      self = <configuration_test.TestConfiguration object at 0x7ff4ecd33460>
      
          @pytest.mark.timeout(60*30)
          def test_change_durable_writes(self):
              """
              @jira_ticket CASSANDRA-9560
          
              Test that changes to the DURABLE_WRITES option on keyspaces is
              respected in subsequent writes.
          
              This test starts by writing a dataset to a cluster and asserting that
              the commitlogs have been written to. The subsequent test depends on
              the assumption that this dataset triggers an fsync.
          
              After checking this assumption, the test destroys the cluster and
              creates a fresh one. Then it tests that DURABLE_WRITES is respected by:
          
              - creating a keyspace with DURABLE_WRITES set to false,
              - using ALTER KEYSPACE to set its DURABLE_WRITES option to true,
              - writing a dataset to this keyspace that is known to trigger a commitlog fsync,
              - asserting that the commitlog has grown in size since the data was written.
              """
              def new_commitlog_cluster_node():
                  # writes should block on commitlog fsync
                  self.fixture_dtest_setup.cluster.populate(1)
                  node = self.fixture_dtest_setup.cluster.nodelist()[0]
                  self.fixture_dtest_setup.cluster.set_batch_commitlog(enabled=True, use_batch_window = self.fixture_dtest_setup.cluster.version() < '5.0')
          
                  self.fixture_dtest_setup.cluster.start()
                  return node
          
              durable_node = new_commitlog_cluster_node()
              durable_init_size = commitlog_size(durable_node)
              durable_session = self.patient_exclusive_cql_connection(durable_node)
          
              # test assumption that write_to_trigger_fsync actually triggers a commitlog fsync
              durable_session.execute("CREATE KEYSPACE ks WITH REPLICATION = {'class': 'SimpleStrategy', 'replication_factor': 1} "
                                      "AND DURABLE_WRITES = true")
              durable_session.execute('CREATE TABLE ks.tab (key int PRIMARY KEY, a int, b int, c int)')
              logger.debug('commitlog size diff = ' + str(commitlog_size(durable_node) - durable_init_size))
              write_to_trigger_fsync(durable_session, 'ks', 'tab')
          
              assert commitlog_size(durable_node) > durable_init_size, \
                  "This test will not work in this environment; write_to_trigger_fsync does not trigger fsync."
          
              # get a fresh cluster to work on
              durable_session.shutdown()
              self.fixture_dtest_setup.cleanup_and_replace_cluster()
          
              node = new_commitlog_cluster_node()
              init_size = commitlog_size(node)
              session = self.patient_exclusive_cql_connection(node)
          
              # set up a keyspace without durable writes, then alter it to use them
              session.execute("CREATE KEYSPACE ks WITH REPLICATION = {'class': 'SimpleStrategy', 'replication_factor': 1} "
                              "AND DURABLE_WRITES = false")
              session.execute('CREATE TABLE ks.tab (key int PRIMARY KEY, a int, b int, c int)')
              session.execute('ALTER KEYSPACE ks WITH DURABLE_WRITES=true')
      >       write_to_trigger_fsync(session, 'ks', 'tab')
      
      configuration_test.py:113: 
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
      configuration_test.py:186: in write_to_trigger_fsync
          execute_concurrent_with_args(session,
      ../env3.8/src/cassandra-driver/cassandra/concurrent.py:238: in execute_concurrent_with_args
          return execute_concurrent(session, zip(cycle((statement,)), parameters), *args, **kwargs)
      ../env3.8/src/cassandra-driver/cassandra/concurrent.py:94: in execute_concurrent
          return executor.execute(concurrency, raise_on_first_error)
      ../env3.8/src/cassandra-driver/cassandra/concurrent.py:201: in execute
          return super(ConcurrentExecutorListResults, self).execute(concurrency, fail_fast)
      ../env3.8/src/cassandra-driver/cassandra/concurrent.py:120: in execute
          return self._results()
      ../env3.8/src/cassandra-driver/cassandra/concurrent.py:219: in _results
          self._raise(self._exception)
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
      
      exc = NoHostAvailable('Unable to complete the operation against any hosts', {<Host: 127.0.0.1:9042 datacenter1>: ConnectionShutdown('Connection to 127.0.0.1:9042 was closed')})
      
          @staticmethod
          def _raise(exc):
              if six.PY2 and isinstance(exc, tuple):
                  (exc_type, value, traceback) = exc
                  six.reraise(exc_type, value, traceback)
              else:
      >           raise exc
      E           cassandra.cluster.NoHostAvailable: ('Unable to complete the operation against any hosts', {<Host: 127.0.0.1:9042 datacenter1>: ConnectionShutdown('Connection to 127.0.0.1:9042 was closed')})
      
      ../env3.8/src/cassandra-driver/cassandra/concurrent.py:167: NoHostAvailable
      
      failed on teardown with "Failed: Unexpected error found in node logs (see stdout for full details). Errors: [[node1] 'ERROR [MutationStage-3] 2024-03-08 09:36:11,386 StorageProxy.java:1670 - Failed to apply mutation locally : \njava.lang.IllegalArgumentException: newPosition > limit: (1048644 > 1048576)\n\tat java.base/java.nio.Buffer.createPositionException(Buffer.java:341)\n\tat java.base/java.nio.Buffer.position(Buffer.java:316)\n\tat java.base/java.nio.ByteBuffer.position(ByteBuffer.java:1516)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:321)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:73)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegment.allocate(CommitLogSegment.java:216)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegmentManagerStandard.allocate(CommitLogSegmentManagerStandard.java:52)\n\tat org.apache.cassandra.db.commitlog.CommitLog.add(CommitLog.java:307)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.addToCommitLog(CassandraKeyspaceWriteHandler.java:99)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.beginWrite(CassandraKeyspaceWriteHandler.java:53)\n\tat org.apache.cassandra.db.Keyspace.applyInternal(Keyspace.java:612)\n\tat org.apache.cassandra.db.Keyspace.apply(Keyspace.java:497)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:244)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:264)\n\tat org.apache.cassandra.service.StorageProxy$4.runMayThrow(StorageProxy.java:1664)\n\tat org.apache.cassandra.service.StorageProxy$LocalMutationRunnable.run(StorageProxy.java:2624)\n\tat org.apache.cassandra.concurrent.ExecutionFailure$2.run(ExecutionFailure.java:163)\n\tat org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:143)\n\tat io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)\n\tat java.base/java.lang.Thread.run(Thread.java:833)', [node1] 'ERROR [MutationStage-4] 2024-03-08 09:36:11,386 StorageProxy.java:1670 - Failed to apply mutation locally : \njava.lang.IllegalArgumentException: newPosition > limit: (1048722 > 1048576)\n\tat java.base/java.nio.Buffer.createPositionException(Buffer.java:341)\n\tat java.base/java.nio.Buffer.position(Buffer.java:316)\n\tat java.base/java.nio.ByteBuffer.position(ByteBuffer.java:1516)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:321)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:73)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegment.allocate(CommitLogSegment.java:216)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegmentManagerStandard.allocate(CommitLogSegmentManagerStandard.java:52)\n\tat org.apache.cassandra.db.commitlog.CommitLog.add(CommitLog.java:307)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.addToCommitLog(CassandraKeyspaceWriteHandler.java:99)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.beginWrite(CassandraKeyspaceWriteHandler.java:53)\n\tat org.apache.cassandra.db.Keyspace.applyInternal(Keyspace.java:612)\n\tat org.apache.cassandra.db.Keyspace.apply(Keyspace.java:497)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:244)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:264)\n\tat org.apache.cassandra.service.StorageProxy$4.runMayThrow(StorageProxy.java:1664)\n\tat org.apache.cassandra.service.StorageProxy$LocalMutationRunnable.run(StorageProxy.java:2624)\n\tat org.apache.cassandra.concurrent.ExecutionFailure$2.run(ExecutionFailure.java:163)\n\tat org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:143)\n\tat io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)\n\tat java.base/java.lang.Thread.run(Thread.java:833)', [node1] 'ERROR [COMMIT-LOG-WRITER] 2024-03-08 09:36:11,387 StorageService.java:631 - Stopping native transport', [node1] 'ERROR [COMMIT-LOG-WRITER] 2024-03-08 09:36:11,392 StorageService.java:636 - Stopping gossiper']"
      Unexpected error found in node logs (see stdout for full details). Errors: [[node1] 'ERROR [MutationStage-3] 2024-03-08 09:36:11,386 StorageProxy.java:1670 - Failed to apply mutation locally : \njava.lang.IllegalArgumentException: newPosition > limit: (1048644 > 1048576)\n\tat java.base/java.nio.Buffer.createPositionException(Buffer.java:341)\n\tat java.base/java.nio.Buffer.position(Buffer.java:316)\n\tat java.base/java.nio.ByteBuffer.position(ByteBuffer.java:1516)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:321)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:73)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegment.allocate(CommitLogSegment.java:216)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegmentManagerStandard.allocate(CommitLogSegmentManagerStandard.java:52)\n\tat org.apache.cassandra.db.commitlog.CommitLog.add(CommitLog.java:307)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.addToCommitLog(CassandraKeyspaceWriteHandler.java:99)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.beginWrite(CassandraKeyspaceWriteHandler.java:53)\n\tat org.apache.cassandra.db.Keyspace.applyInternal(Keyspace.java:612)\n\tat org.apache.cassandra.db.Keyspace.apply(Keyspace.java:497)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:244)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:264)\n\tat org.apache.cassandra.service.StorageProxy$4.runMayThrow(StorageProxy.java:1664)\n\tat org.apache.cassandra.service.StorageProxy$LocalMutationRunnable.run(StorageProxy.java:2624)\n\tat org.apache.cassandra.concurrent.ExecutionFailure$2.run(ExecutionFailure.java:163)\n\tat org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:143)\n\tat io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)\n\tat java.base/java.lang.Thread.run(Thread.java:833)', [node1] 'ERROR [MutationStage-4] 2024-03-08 09:36:11,386 StorageProxy.java:1670 - Failed to apply mutation locally : \njava.lang.IllegalArgumentException: newPosition > limit: (1048722 > 1048576)\n\tat java.base/java.nio.Buffer.createPositionException(Buffer.java:341)\n\tat java.base/java.nio.Buffer.position(Buffer.java:316)\n\tat java.base/java.nio.ByteBuffer.position(ByteBuffer.java:1516)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:321)\n\tat java.base/java.nio.MappedByteBuffer.position(MappedByteBuffer.java:73)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegment.allocate(CommitLogSegment.java:216)\n\tat org.apache.cassandra.db.commitlog.CommitLogSegmentManagerStandard.allocate(CommitLogSegmentManagerStandard.java:52)\n\tat org.apache.cassandra.db.commitlog.CommitLog.add(CommitLog.java:307)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.addToCommitLog(CassandraKeyspaceWriteHandler.java:99)\n\tat org.apache.cassandra.db.CassandraKeyspaceWriteHandler.beginWrite(CassandraKeyspaceWriteHandler.java:53)\n\tat org.apache.cassandra.db.Keyspace.applyInternal(Keyspace.java:612)\n\tat org.apache.cassandra.db.Keyspace.apply(Keyspace.java:497)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:244)\n\tat org.apache.cassandra.db.Mutation.apply(Mutation.java:264)\n\tat org.apache.cassandra.service.StorageProxy$4.runMayThrow(StorageProxy.java:1664)\n\tat org.apache.cassandra.service.StorageProxy$LocalMutationRunnable.run(StorageProxy.java:2624)\n\tat org.apache.cassandra.concurrent.ExecutionFailure$2.run(ExecutionFailure.java:163)\n\tat org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:143)\n\tat io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)\n\tat java.base/java.lang.Thread.run(Thread.java:833)', [node1] 'ERROR [COMMIT-LOG-WRITER] 2024-03-08 09:36:11,387 StorageService.java:631 - Stopping native transport', [node1] 'ERROR [COMMIT-LOG-WRITER] 2024-03-08 09:36:11,392 StorageService.java:636 - Stopping gossiper']
      

      Attachments

        Activity

          People

            brandon.williams Brandon Williams
            bereng Berenguer Blasi
            Brandon Williams
            Stefan Miklosovic
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: