thread runner: tests have failed with verbose option #350
Description
Step to reproduce
Run thread based tests with verbose option on the master (e15026e) of test-unit:
$ ruby test/run.rb --parallel=thread -vExpected result
All test suites have been successful.
Actual result
'String#*': negative argument (ArgumentError) error has occurred. full log as follows:
$ ruby test/run.rb --parallel=thread -v
Loaded suite test
Started
test_fail: O
===============================================================================
Omission: memory usage collection isn't supported on this platform [test_fail(Test::Unit::TestAssertNothingLeakedMemory)]
/Users/zzz/src/github.com/test-unit/test-unit/test/test-assertions.rb:2986:in 'block in Test::Unit::TestAssertNothingLeakedMemory#test_fail'
===============================================================================
: (0.009261)
test_pass: O
===============================================================================
Omission: memory usage collection isn't supported on this platform [test_pass(Test::Unit::TestAssertNothingLeakedMemory)]
/Users/zzz/src/github.com/test-unit/test-unit/test/test-assertions.rb:2933:in 'block in Test::Unit::TestAssertNothingLeakedMemory#test_pass'
===============================================================================
: (0.006971)
test_pass_message: O
===============================================================================
Omission: memory usage collection isn't supported on this platform [test_pass_message(Test::Unit::TestAssertNothingLeakedMemory)]
/Users/zzz/src/github.com/test-unit/test-unit/test/test-assertions.rb:2944:in 'block in Test::Unit::TestAssertNothingLeakedMemory#test_pass_message'
===============================================================================
: (0.007570)
test_pass_target: O
===============================================================================
Omission: memory usage collection isn't supported on this platform [test_pass_target(Test::Unit::TestAssertNothingLeakedMemory)]
/Users/zzz/src/github.com/test-unit/test-unit/test/test-assertions.rb:2957:in 'block in Test::Unit::TestAssertNothingLeakedMemory#test_pass_target'
===============================================================================
: (0.006957)
test_call_order: .: (0.001364)
test_without_header[csv]: .: (0.001309)
test_without_header[tsv]: .: (0.000388)
test_label[csv]: .: (0.000487)
test_label[tsv]: .: (0.000388)
test_normal[csv]: .: (0.000372)
test_normal[tsv]: .: (0.000410)
test_parallel_unsafe: .: (0.000115)
test_basic_collection: .: (0.001051)
test_filtered_collection: .: (0.002906)
test_collect_file: .: (0.002333)
test_collect_multi: .: (0.002060)
test_dir: .: (0.001095)
test_filtering: .: (0.002053)
test_fs: .: (0.001372)
test_fs_entries: .: (0.001344)
test_fs_pwd: .: (0.001274)
test_fs_require: .: (0.001306)
test_fs_sub: .: (0.001135)
test_multilevel_collect: .: (0.002583)
test_nil_pattern: .: (0.001835)
test_simple_collect: .: (0.001467)
test_basic: .: (0.000936)
test_filtered: .: (0.004594)
test_sort: .: (0.001229)
test_included_in_child: .: (0.000503)
test_collect_file: .: (0.013826)
test_collect_file_no_pattern_match_file_name: .: (0.012503)
test_collect_file_test_cases: .: (0.012443)
test_collect_files: .: (0.012292)
test_collect_multi: .: (0.014389)
test_filtering: .: (0.016208)
test_multilevel_collect: .: (0.017830)
test_nil_pattern: .: (0.013984)
test_simple_collect: .: (0.013640)
test_simple_collect_test_suffix: .: (0.012981)
test: suffix: .: (0.000626)
test_fail_block: test_result_changed_notification: .: (0.006371)
test_fail_false: .: (0.000676)
test_fail_false_with_message: .: (0.000451)
test_fail_nil: .: (0.000442)
test_fail_with_assertion_message: .: (0.000322)
test_pass: .: (0.000120)
test_pass_block: .: (0.000956)
test_pass_neither_false_or_nil: .: (0.000126)
test_pass_with_message: .: (0.000098)
test_with_message: test_fault_notification: .: (0.016542)
test_fail: .: (0.001117)
test_pass: .: (0.000230)
test_pass_empty: .: (0.000282)
test_pass_message: .: (0.000149)
test_fail: .: (0.000570)
test_fail_because_no_empty_method: .: (0.000765)
test_fail_with_message: .: (0.000325)
test_pass: .: (0.000100)
test_pass_with_message: .: (0.000111)
test_fail_because_negaitve_delta: .: (0.000695)
test_fail_because_not_float_like_object: .: (0.000953)
test_fail_with_message: .: (0.001331)
test_fail_without_delta: .: (0.001470)
test_pass: .: (0.000144)
test_pass_float_like_object: .: (0.000197)
test_pass_string_expected: .: (0.000239)
test_pass_with_message: .: (0.000140)
test_pass_without_delta: .: (0.000106)
test_fail_because_negaitve_delta: .: (0.000887)
test_fail_because_not_float_like_object: .: (0.000765)
test_fail_with_float_like_object: .: (0.001866)
test_fail_with_message: .: (0.001374)
test_fail_without_delta: .: (0.001443)
test_pass: .: (0.000161)
test_pass_float_like_object: .: (0.000170)
test_pass_string_expected: .: (0.000233)
test_pass_with_message: .: (0.000121)
test_pass_without_delta: .: (0.000123)
test_fail_because_negaitve_epsilon: .: (0.000755)
test_fail_because_not_float_like_object: .: (0.000811)
test_fail_with_message: .: (0.001762)
test_fail_without_epsilon: .: (0.001847)
test_pass: .: (0.000130)
test_pass_float_like_object: .: (0.000193)
test_pass_minus_expected: .: (0.000164)
test_pass_string_expected: .: (0.000284)
test_pass_with_message: .: (0.000171)
test_pass_without_epsilon: .: (0.000173)
test_pass_zero_expected: .: (0.000157)
test_fail_because_negaitve_epsilon: .: (0.000854)
test_fail_because_not_float_like_object: .: (0.000719)
test_fail_with_float_like_object: .: (0.002101)
test_fail_with_message: .: (0.002055)
test_fail_without_epsilon: .: (0.001762)
test_pass: .: (0.000137)
test_pass_float_like_object: .: (0.000166)
test_pass_minus_expected: .: (0.000145)
test_pass_string_expected: .: (0.000178)
test_pass_with_message: .: (0.000119)
test_pass_without_epsilon: .: (0.000116)
test_pass_zero_expected: .: (0.000391)
test_fail: .: (0.000572)
test_fail_because_not_collection_like_object: .: (0.000681)
test_fail_with_message: .: (0.000509)
test_pass: .: (0.000106)
test_pass_with_message: .: (0.000113)
test_fail: .: (0.000623)
test_fail_because_no_empty_method: .: (0.000730)
test_fail_with_message: .: (0.000331)
test_pass: .: (0.000103)
test_pass_with_message: .: (0.000120)
test_fail: .: (0.001712)
test_fail_because_negaitve_delta: .: (0.000566)
test_fail_because_not_float_like_object: .: (0.000742)
test_fail_with_message: .: (0.001405)
test_fail_without_delta: .: (0.001380)
test_pass: .: (0.000160)
test_pass_float_like_object: .: (0.000150)
test_pass_string_epxected: .: (0.000157)
test_pass_with_message: .: (0.000125)
test_pass_without_delta: .: (0.000124)
test_fail: .: (0.001489)
test_fail_because_negaitve_delta: .: (0.000552)
test_fail_because_not_float_like_object: .: (0.000690)
test_fail_with_float_like_object: .: (0.003400)
test_fail_with_message: .: (0.001532)
test_fail_without_delta: .: (0.001623)
test_pass: .: (0.000193)
test_pass_float_like_object: .: (0.000171)
test_pass_string_epxected: .: (0.000181)
test_pass_with_message: .: (0.000136)
test_pass_without_delta: .: (0.000163)
test_fail: .: (0.002316)
test_fail_because_negaitve_epsilon: .: (0.000610)
test_fail_because_not_float_like_object: .: (0.000783)
test_fail_with_float_like_object: .: (0.001879)
test_fail_with_message: .: (0.001720)
test_fail_without_epsilon: .: (0.001943)
test_pass: .: (0.000184)
test_pass_float_like_object: .: (0.000174)
test_pass_string_epxected: .: (0.000157)
test_pass_with_message: .: (0.000113)
test_pass_without_epsilon: .: (0.000099)
test_fail: .: (0.002923)
test_fail_because_negaitve_epsilon: .: (0.001437)
test_fail_because_not_float_like_object: .: (0.001781)
test_fail_with_message: .: (0.002785)
test_fail_without_epsilon: .: (0.002832)
test_pass: .: (0.000178)
test_pass_float_like_object: .: (0.001270)
test_pass_string_epxected: .: (0.000748)
test_pass_with_message: .: (0.000157)
test_pass_without_epsilon: .: (0.000117)
test_fail: .: (0.001256)
test_fail_because_not_collection_like_object: .: (0.001456)
test_fail_with_message: .: (0.000729)
test_pass: .: (0.000136)
test_pass_with_message: .: (0.000121)
test_fail: .: (0.000980)
test_pass: .: (0.000168)
test_fail_unmatch_class: .: (0.002459)
test_fail_unmatch_message: .: (0.001678)
test_pass_message: .: (0.000145)
test_pass_regexp: .: (0.000115)
test_pass_string: .: (0.000108)
test_assert_alias_method: .: (0.001805)
test_assert_boolean: .: (0.001096)
test_assert_compare: .: (0.002858)
test_assert_const_defined: .: (0.000544)
test_assert_equal: .: (0.001512)
test_assert_fail_assertion: .: (0.000334)
test_assert_false: .: (0.000981)
test_assert_instance_of: .: (0.002704)
test_assert_kind_of: .: (0.001945)
test_assert_match: .: (0.001058)
test_assert_nil: .: (0.000729)
test_assert_no_match: .: (0.001369)
test_assert_not_const_defined: .: (0.000747)
test_assert_not_equal: .: (0.001131)
test_assert_not_instance_of: .: (0.002298)
test_assert_not_kind_of: .: (0.001720)
test_assert_not_match_fail_match: .: (0.000557)
test_assert_not_match_fail_match_string: .: (0.000417)
test_assert_not_match_fail_match_with_message: .: (0.000407)
test_assert_not_match_pass: .: (0.000121)
test_assert_not_match_pass_with_message: .: (0.000117)
test_assert_not_nil: .: (0.000535)
test_assert_not_operator: .: (0.000751)
test_assert_not_predicate: .: (0.001035)
test_assert_not_respond_to_fail_number: .: (0.000444)
test_assert_not_respond_to_pass_string: .: (0.000107)
test_assert_not_respond_to_pass_symbol: .: (0.000100)
test_assert_not_same: .: (0.001221)
test_assert_nothing_raised: .: (0.001720)
test_assert_nothing_thrown: .: (0.000536)
test_assert_operator: .: (0.000766)
test_assert_path_exist: test_error_invalid_message_true: .: (0.164393)
test_assert_path_not_exist: .: (0.007769)
test_assert_predicate: .: (0.001289)
test_assert_raise_fail: .: (0.002259)
test_assert_raise_instance: .: (0.004101)
test_assert_raise_jruby: O
===============================================================================
Omission: test for JRuby [test_assert_raise_jruby(Test::Unit::TestAssertions)]
/Users/zzz/src/github.com/test-unit/test-unit/test/testunit-test-util.rb:15:in 'TestUnitTestUtil#jruby_only_test'
===============================================================================
: (0.000826)
test_assert_raise_kind_of: .: (0.000709)
test_assert_raise_message: .: (0.000991)
test_assert_raise_module: .: (0.002005)
test_assert_raise_success: .: (0.000315)
test_assert_respond_to: .: (0.000961)
test_assert_same: .: (0.001586)
test_assert_send: .: (0.001033)
test_assert_throw: .: (0.001385)
test_assert_true: .: (0.001290)
test_condition_invariant: .: (0.000250)
test_flunk: .: (0.000193)
test_failure_with_message: .: (0.000263)
test_failure_without_message: .: (0.000191)
test_pass_with_message: .: (0.000115)
test_pass_without_message: .: (0.000094)
test_with_message: .: (0.001305)
test_with_message_proc: .: (0.003159)
test_without_message: .: (0.001211)
test_with_message: .: (0.000245)
test_without_message: .: (0.000122)
test_basic_object_inspection: .: (0.001209)
test_different_encoding: .: (0.002014)
test_different_hash: .: (0.002483)
test_different_type: .: (0.001770)
test_large_string: .: (0.005044)
test_long_line: .: (0.006362)
test_max_diff_target_string_size: .: (0.003110)
test_multi_lines_result: .: (0.001560)
test_numeric: .: (0.001449)
test_recursive_hash: .: (0.003160)
test_same_inspected_objects: .: (0.001128)
test_too_small_difference: .: (0.001150)
test_display: .: (0.000266)
test_error_invalid_message_true: .: (0.000666)
test_fail_with_assertion_message: .: (0.000345)
test_true: .: (0.000285)
test_with_message: .: (0.000283)
test_false: .: (0.000214)
test_nil: .: (0.000134)
test_with_message: .: (0.000202)
test_incompatible_encoding_by_diff: .: (0.002119)
test_data_driven_test: .: (0.000395)
test_gc: .: (0.000474)
test_add_failed_assertion: test_passed?: .: (0.237533)
test_suite: .: (0.001716)
test_assertion_failed_not_called: .: (0.001318)
test_test_mark: .: (0.000562)
test_add_error: test_display: .: (0.249246)
test_re_raise_exception: .: (0.000629)
test_defined_order: .: (0.000611)
test_timeout_error: test_faults: .: (0.254545)
test_add_failure_nested: .: (0.001355)
test_data_driven_test_without_parameter: .: (0.000877)
test_creation: .: (0.002109)
test_interrupted: .: (0.001299)
test_inherited_test_should_be_ignored: .: (0.000699)
test_declarative_style: .: (0.000617)
test_redefine_method: .: (0.000945)
test_setup_teardown: .: (0.003287)
test_no_tests: .: (0.001713)
test_mixin_test_should_not_be_ignored: .: (0.000351)
test_equality: .: (0.000293)
test_run: .: (0.000253)
test_pass_through_on_startup: .: (0.000640)
test_pass_through_on_shutdown: .: (0.000557)
test_on_shutdown: .: (0.000579)
test_on_startup: .: (0.000458)
test_pass_through_in_test: .: (0.000674)
test_no_data: .: (0.000390)
test_data: .: (0.000264)
test_no_data: .: (0.000307)
test_data: .: (0.000220)
test_call_order: .: (0.000668)
test_duplicated_name: .: (0.000760)
test_suite: .: (0.000405)
test_anonymous: .: (0.000274)
test_named: .: (0.000231)
test_line_after_def: .: (0.007041)
test_line_middle: .: (0.005042)
test_line_middle: .: (0.006160)
test_line_after_method: .: (0.005245)
test_def: .: (0.006055)
test_before: .: (0.005905)
test_child: .: (0.010232)
test_after: .: (0.005822)
test_before: .: (0.005306)
test_after: .: (0.005674)
test_with_setup: .: (0.007353)
test_method: .: (0.006861)
test_child: .: (0.022975)
test_not_match: .: (0.005448)
test_match: .: (0.006504)
test_not_match: .: (0.006169)
test_match: .: (0.005617)
test_no_test: .: (0.000343)
test_have_def_style_test: .: (0.006023)
test_have_method_style_test: .: (0.005136)
test_not_match: .: (0.032123)
test_absolute_path: .: (0.005149)
test_base_name: .: (0.033143)
test_base_name: .: (0.032748)
test_not_match: .: (0.032646)
test_absolute_path: .: (0.005921)
test_add: .: (0.000388)
test_delete: .: (0.000260)
test_delete_tests: .: (0.000234)
test_empty?: .: (0.000444)
test_equality: .: (0.000357)
test_run: .: (0.003797)
test_size: .: (0.000293)
test_collect_test_names: .: (0.000428)
test_collect_test_names: .: (0.000449)
test_collect_test_names: .: (0.000390)
test_collect_test_names: .: (0.000622)
test_and: .: (0.000255)
test_complex: .: (0.000145)
test_exception: .: (0.000183)
test_existent: .: (0.000143)
test_nonexistent: .: (0.000136)
test_windows_31j: .: (0.008402)
test_error_inside_jruby: O
===============================================================================
Omission: test for JRuby [test_error_inside_jruby(TestCodeSnippet::TestJRuby)]
/Users/zzz/src/github.com/test-unit/test-unit/test/testunit-test-util.rb:15:in 'TestUnitTestUtil#jruby_only_test'
===============================================================================
: (0.003234)
test_data[data set]: .: (0.000257)
test_data[n-data]: .: (0.000121)
test_data[dynamic-data-set]: .: (0.000115)
test_data[load-data-set]: .: (0.000099)
test_data_dynamic_data_set_keep: .: (0.000112)
test_data_no_arguments_without_block: .: (0.000152)
test_data_patterns: .: (0.000101)
test_data_patterns_keep: .: (0.000109)
test_equal[data set]: .: (0.000259)
test_equal[n-data]: .: (0.000229)
test_equal[dynamic-data-set]: .: (0.000225)
test_equal[load-data-set]: .: (0.000220)
test_normalize_value[true]: .: (0.000136)
test_normalize_value[false]: .: (0.000096)
test_normalize_value[integer]: .: (0.000081)
test_normalize_value[float]: .: (0.000125)
test_normalize_value[string]: .: (0.000111)
test_run[data set]: .: (0.000588)
test_run[n-data]: .: (0.000634)
test_run[dynamic-data-set]: .: (0.000551)
test_run[load-data-set]: .: (0.000513)
test_run[superclass]: .: (0.000566)
test_run[method]: .: (0.000563)
test_run_normal_test_in_subclass: .: (0.000439)
test_suite[data set]: .: (0.000252)
test_suite[n-data]: .: (0.000229)
test_suite[dynamic-data-set]: .: (0.000224)
test_suite[load-data-set]: .: (0.000224)
test_suite_patterns: .: (0.000364)
test_suite_patterns_group: .: (0.000316)
test_suite_patterns_hash: .: (0.000424)
test_error_wrong_number_of_arguments: .: (0.586448)
.: (0.011569)
.: (0.019439)
.: (0.005341)
.: (0.009619)
.: (0.003320)
.: (0.000420)
Finished in 0.800952 seconds.
-------------------------------------------------------------------------------
362 tests, 1310 assertions, 0 failures, 0 errors, 0 pendings, 6 omissions, 0 notifications
100% passed
-------------------------------------------------------------------------------
451.96 tests/s, 1635.55 assertions/s
/Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/ui/console/testrunner.rb:522:in 'String#*': negative argument (ArgumentError)
" " * @indent
^^^^^^^
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/ui/console/testrunner.rb:522:in 'Test::Unit::UI::Console::TestRunner#indent'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/ui/console/testrunner.rb:442:in 'Test::Unit::UI::Console::TestRunner#test_started'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/util/observable.rb:74:in 'block in Test::Unit::Util::Observable#notify_listeners'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/util/observable.rb:74:in 'Hash#each_value'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/util/observable.rb:74:in 'Test::Unit::Util::Observable#notify_listeners'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/ui/testrunnermediator.rb:45:in 'block (2 levels) in Test::Unit::UI::TestRunnerMediator#run'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/testsuite.rb:84:in 'block in Test::Unit::TestSuite#run'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-runner.rb:93:in 'block in Test::Unit::TestSuiteRunner#run_test'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/testcase.rb:624:in 'Test::Unit::TestCase#run'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-runner.rb:97:in 'Test::Unit::TestSuiteRunner#run_test'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-thread-runner.rb:74:in 'block (2 levels) in Test::Unit::TestSuiteThreadRunner#run_tests_recursive'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-thread-runner.rb:33:in 'block (4 levels) in Test::Unit::TestSuiteThreadRunner.run_all_tests'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-thread-runner.rb:32:in 'Kernel#catch'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-thread-runner.rb:32:in 'block (3 levels) in Test::Unit::TestSuiteThreadRunner.run_all_tests'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-thread-runner.rb:29:in 'Kernel#loop'
from /Users/zzz/src/github.com/test-unit/test-unit/lib/test/unit/test-suite-thread-runner.rb:29:in 'block (2 levels) in Test::Unit::TestSuiteThreadRunner.run_all_tests'