You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@cassandra.apache.org by "Stefania (JIRA)" <ji...@apache.org> on 2016/09/14 06:10:20 UTC

[jira] [Commented] (CASSANDRA-12430) dtest failure in cqlsh_tests.cqlsh_copy_tests.CqlshCopyTest.test_writing_with_token_boundaries

    [ https://issues.apache.org/jira/browse/CASSANDRA-12430?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15489545#comment-15489545 ] 

Stefania commented on CASSANDRA-12430:
--------------------------------------

Thanks [~jkni]! 

It's an OperationTimedOut error for one of the pages that caused some records to be missing:

{code}
dtest: DEBUG: Exporting tokens 1000000000000000000 - None for 10000 records to csv file: /tmp/tmpKZOxfD
dtest: DEBUG: COPY ks.testtokens TO '/tmp/tmpKZOxfD'WITH BEGINTOKEN = '1000000000000000000'
dtest: DEBUG: Using CQL driver: <module 'cassandra' from '/home/automaton/cassandra/bin/../lib/cassandra-driver-internal-only-3.5.0.post0-d8d0456.zip/cassandra-driver-3.5.0.post0-d8d0456/cassandra/__init__.py'>
Using connect timeout: 5 seconds
Using 'utf-8' encoding
Using ssl: False
<stdin>:2:Error for (2350569513275064150, 4339695056833745219): OperationTimedOut - errors={'127.0.0.1': 'Client request timeout. See Session.execute[_async](timeout)'}, last_host=127.0.0.1 (permanently given up after 1000 rows and 1 attempts)
<stdin>:2:Exported 8 ranges out of 9 total ranges, some records might be missing

dtest: DEBUG: Detected 4 core(s)
Using 3 child processes

Starting copy of ks.testtokens with columns [a].
Closing parent cluster sockets
Closing parent cluster sockets
Closing parent cluster sockets
Created connection to ('127.0.0.1',) with page size 1000 and timeout 10 seconds per page
Created connection to ('127.0.0.1',) with page size 1000 and timeout 10 seconds per page
Created connection to ('127.0.0.1',) with page size 1000 and timeout 10 seconds per page
OperationTimedOut - errors={'127.0.0.1': 'Client request timeout. See Session.execute[_async](timeout)'}, last_host=127.0.0.1
Processed: 4440 rows; Rate:     440 rows/s; Avg. rate:     440 rows/s

4440 rows exported to 1 files in 10.088 seconds.
{code}

Increasing the page timeout should be sufficient to fix this, the pull request is [here|https://github.com/riptano/cassandra-dtest/pull/1317].

> dtest failure in cqlsh_tests.cqlsh_copy_tests.CqlshCopyTest.test_writing_with_token_boundaries
> ----------------------------------------------------------------------------------------------
>
>                 Key: CASSANDRA-12430
>                 URL: https://issues.apache.org/jira/browse/CASSANDRA-12430
>             Project: Cassandra
>          Issue Type: Bug
>            Reporter: Craig Kodman
>            Assignee: Stefania
>              Labels: dtest
>             Fix For: 3.0.x
>
>         Attachments: node1.log, node1_debug.log, node1_gc.log
>
>
> example failure:
> http://cassci.datastax.com/job/cassandra-3.0_dtest/787/testReport/cqlsh_tests.cqlsh_copy_tests/CqlshCopyTest/test_writing_with_token_boundaries
> Failed on CassCI build cassandra-3.0_dtest build #787
> {code}
> Stacktrace
>   File "/usr/lib/python2.7/unittest/case.py", line 329, in run
>     testMethod()
>   File "/home/automaton/cassandra-dtest/cqlsh_tests/cqlsh_copy_tests.py", line 1022, in test_writing_with_token_boundaries
>     self._test_writing_with_token_boundaries(10000, None, 2000000000000000000)
>   File "/home/automaton/cassandra-dtest/cqlsh_tests/cqlsh_copy_tests.py", line 1059, in _test_writing_with_token_boundaries
>     self.assertItemsEqual(csv_values, result)
>   File "/usr/lib/python2.7/unittest/case.py", line 901, in assertItemsEqual
>     self.fail(msg)
>   File "/usr/lib/python2.7/unittest/case.py", line 410, in fail
>     raise self.failureException(msg)
> "Element counts were not equal:\nFirst has 0, Second has 1:  ('130', -4364617663693876050L)\nFirst has 0, Second has 1:  ('1504', -4313346088993828066L)\nFirst has 0, Second has 1:  ('1657', -4298243044528711865L)\nFirst has 0, Second has 1:  ('1908', -4357762565998238890L)\nFirst has 0, Second has 1:  ('196', -4311842292754600676L)\nFirst has 0, Second has 1:  ('2069', -4364398944370882217L)\nFirst has 0, Second has 1:  ('2840', -4341639477649832153L)\nFirst has 0, Second has 1:  ('2887', -4318016824479819783L)\nFirst has 0, Second has 1:  ('2899', -4302748366908469185L)\nFirst has 0, Second has 1:  ('2928', -4320094196758787736L)\nFirst has 0, Second has 1:  ('2985', -4314356124534988584L)\nFirst has 0, Second has 1:  ('3684', -4338074463992249966L)\nFirst has 0, Second has 1:  ('371', -4314424123257001171L)\nFirst has 0, Second has 1:  ('3726', -4327342039280507889L)\nFirst has 0, Second has 1:  ('3767', -4314615789624913427L)\nFirst has 0, Second has 1:  ('3837', -4345782419910891107L)\nFirst has 0, Second has 1:  ('3917', -4288469607605675346L)\nFirst has 0, Second has 1:  ('4023', -4327319429102869913L)\nFirst has 0, Second has 1:  ('4340', -4364719196309290555L)\nFirst has 0, Second has 1:  ('4775', -4334399295585005795L)\nFirst has 0, Second has 1:  ('480', -4297721626756162038L)\nFirst has 0, Second has 1:  ('4927', -4363012199808638126L)\nFirst has 0, Second has 1:  ('5227', -4322405738833807588L)\nFirst has 0, Second has 1:  ('564', -4294201317243228473L)\nFirst has 0, Second has 1:  ('585', -4359001293509999319L)\nFirst has 0, Second has 1:  ('5869', -4350305245827564608L)\nFirst has 0, Second has 1:  ('6907', -4350623491924194304L)\nFirst has 0, Second has 1:  ('709', -4304008865600291097L)\nFirst has 0, Second has 1:  ('7415', -4315752378065264743L)\nFirst has 0, Second has 1:  ('7476', -4300546270541034340L)\nFirst has 0, Second has 1:  ('7805', -4344641724309508742L)\nFirst has 0, Second has 1:  ('7922', -4363605089028496367L)\nFirst has 0, Second has 1:  ('8026', -4319008002233878821L)\nFirst has 0, Second has 1:  ('8180', -4361912691055780971L)\nFirst has 0, Second has 1:  ('8371', -4309172311179179912L)\nFirst has 0, Second has 1:  ('8988', -4326093437666683541L)\nFirst has 0, Second has 1:  ('9492', -4347264403260361686L)\nFirst has 0, Second has 1:  ('9783', -4329297319597600121L)\nFirst has 0, Second has 1:  ('9911', -4320490295580904236L)\n-------------------- >> begin captured logging << --------------------\ndtest: DEBUG: cluster ccm directory: /tmp/dtest-2v8O54\ndtest: DEBUG: Done setting configuration options:\n{   'initial_token': None,\n    'num_tokens': '32',\n    'phi_convict_threshold': 5,\n    'range_request_timeout_in_ms': 10000,\n    'read_request_timeout_in_ms': 10000,\n    'request_timeout_in_ms': 10000,\n    'truncate_request_timeout_in_ms': 10000,\n    'write_request_timeout_in_ms': 10000}\ndtest: DEBUG: Exporting to csv file: /tmp/tmpDS47Vq\ndtest: DEBUG: Exporting to csv file: /tmp/tmpQvnl6e\ndtest: DEBUG: Exporting to csv file: /tmp/tmpfEEiAz\ndtest: DEBUG: Exporting to csv file: /tmp/tmpc7T8av\ndtest: DEBUG: Exporting to csv file: /tmp/tmplxBTNa\ndtest: DEBUG: Exporting to csv file: /tmp/tmpb5VYGq\n--------------------- >> end captured logging << ---------------------"
> {code}



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)