Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

windows: test/command/test_fluentd.rb fails when the version number of fluentd has .rc suffix #3490

Closed
ashie opened this issue Aug 18, 2021 · 3 comments
Closed

windows: test/command/test_fluentd.rb fails when the version number of fluentd has .rc suffix #3490

ashie opened this issue Aug 18, 2021 · 3 comments
Labels
stale

Comments

@ashie
Copy link
Member

ashie commented Aug 18, 2021

Describe the bug

When the version number of fluentd has .rc suffix, dozens of tests in test/command/test_fluentd.rb always fail on Windows.

e.g.) https://github.com/fluent/fluentd/runs/3336022194

It reproduces also on my local environment.

To Reproduce

  1. Add .rc suffix to Fluent::VERSION in lib/fluent/version.rb
  2. bundle exec rake test TEST=test/command/test_fluentd.rb on Windows

Expected behavior

All tests passes

Your Environment

- Fluentd version: 1.14.0.rc
- TD Agent version:
- Operating system: Windows
- Kernel version:

Your Configuration

N/A

Your Error Log

https://github.com/fluent/fluentd/runs/3336022194

2021-08-16T02:22:00.7725312Z Failure: test: by top level <match> section with warning for missing log levels (and warnings for each log event records)(TestFluentdCommand::configured to route log events to plugins):
2021-08-16T02:22:00.8069106Z   execution timeout.
2021-08-16T02:22:00.8383915Z   <"2021-08-16 02:21:55 +0000 [info]: parsing config file is succeeded path=\"D:/a/fluentd/fluentd/test/tmp/command/fluentd/logevent_2.conf\"\r\n" +
2021-08-16T02:22:01.1053865Z   "2021-08-16 02:21:55 +0000 [info]: gem 'fluentd' version '1.14.0.rc'\r\n" +
2021-08-16T02:22:01.2913634Z   "2021-08-16 02:21:55 +0000 [warn]: [dummy] both of Plugin @id and path for <storage> are not specified. Using on-memory store.\r\n" +
2021-08-16T02:22:01.3402954Z   "2021-08-16 02:21:55 +0000 [warn]: [dummy] both of Plugin @id and path for <storage> are not specified. Using on-memory store.\r\n" +
2021-08-16T02:22:01.4706086Z   "2021-08-16 02:21:55 +0000 [warn]: define <match fluent.warn>, <match fluent.error>, <match fluent.fatal> to capture fluentd logs in top level is deprecated. Use <label @FLUENT_LOG> instead\r\n" +
2021-08-16T02:22:01.5743224Z   "2021-08-16 02:21:55 +0000 [warn]: match for some tags of log events are not defined in top level (to be ignored) tags=[\"fluent.trace\", \"fluent.debug\", \"fluent.info\"]\r\n" +
2021-08-16T02:22:01.6202979Z   "2021-08-16 02:21:55 +0000 [info]: using configuration file: <ROOT>\r\n" +
2021-08-16T02:22:01.7429834Z   "  <source>\r\n" +
2021-08-16T02:22:02.0123936Z   "    @type dummy\r\n" +
2021-08-16T02:22:02.3719457Z   "    @id dummy\r\n" +
2021-08-16T02:22:02.4995266Z   "    tag \"dummy\"\r\n" +
2021-08-16T02:22:02.8993303Z   "    dummy {\"message\":\"yay!\"}\r\n" +
2021-08-16T02:22:02.9732556Z   "  </source>\r\n" +
2021-08-16T02:22:03.0746214Z   "  <match dummy>\r\n" +
2021-08-16T02:22:03.3538733Z   "    @type null\r\n" +
2021-08-16T02:22:03.4174346Z   "    @id blackhole\r\n" +
2021-08-16T02:22:04.0182926Z   "  </match>\r\n" +
2021-08-16T02:22:04.0183988Z   "  <match fluent.warn fluent.error fluent.fatal>\r\n" +
2021-08-16T02:22:04.0542068Z   "    @type stdout\r\n" +
2021-08-16T02:22:04.1207617Z   "  </match>\r\n" +
2021-08-16T02:22:04.1627752Z   "</ROOT>\r\n" +
2021-08-16T02:22:04.3784094Z   "2021-08-16 02:21:55 +0000 [info]: starting fluentd-1.14.0.rc pid=604 ruby=\"2.7.4\"\r\n" +
2021-08-16T02:22:04.3807757Z   "2021-08-16 02:21:55 +0000 [info]: spawn command to main:  cmdline=[\"C:/hostedtoolcache/windows/Ruby/2.7.4/x64/bin/ruby.exe\", \"-rC:/hostedtoolcache/windows/Ruby/2.7.4/x64/lib/ruby/gems/2.7.0/gems/bundler-2.2.25/lib/bundler/setup\", \"-Eascii-8bit:ascii-8bit\", \"D:/a/fluentd/fluentd/bin/fluentd\", \"-c\", \"D:/a/fluentd/fluentd/test/tmp/command/fluentd/logevent_2.conf\", \"--under-supervisor\"]\r\n">
2021-08-16T02:22:04.5437875Z   was expected to include:
2021-08-16T02:22:04.6348665Z   <["fluentd worker is now running",
2021-08-16T02:22:04.7210498Z    "[warn]: #0 match for some tags of log events are not defined in top level (to be ignored) tags=[\"fluent.trace\", \"fluent.debug\", \"fluent.info\"]",
2021-08-16T02:22:05.0798422Z    "define <match fluent.warn>, <match fluent.error>, <match fluent.fatal> to capture fluentd logs in top level is deprecated. Use <label @FLUENT_LOG> instead",
2021-08-16T02:22:05.1550809Z    "[warn]: #0 no patterns matched tag=\"fluent.info\""]>
2021-08-16T02:22:05.3207606Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:22:05.5156963Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:362:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:22:05.6627210Z      359: </match>
2021-08-16T02:22:05.7548090Z      360: CONF
2021-08-16T02:22:05.9694436Z      361:       conf_path = create_conf_file('logevent_2.conf', conf)
2021-08-16T02:22:06.0954106Z   => 362:       assert_log_matches(
2021-08-16T02:22:06.1912729Z      363:         create_cmdline(conf_path),
2021-08-16T02:22:06.4589703Z      364:         "fluentd worker is now running",
2021-08-16T02:22:06.6613544Z      365:         '[warn]: #0 match for some tags of log events are not defined in top level (to be ignored) tags=["fluent.trace", "fluent.debug", "fluent.info"]',
2021-08-16T02:22:06.9152109Z ===============================================================================
2021-08-16T02:22:06.9813450Z : (10.696810)
2021-08-16T02:22:07.7950549Z   configured to run 2 workers: 
2021-08-16T02:22:08.8120864Z     test: -E option is set to RUBYOPT[-E]:		.: (7.817403)
2021-08-16T02:22:18.7573852Z     test: -E option is set to RUBYOPT[-encoding]:	.: (10.019407)
2021-08-16T02:22:28.8464088Z     test: -E option is set to RUBYOPT[-external-encoding]:	.: (10.556173)
2021-08-16T02:22:40.7781413Z     test: -E option is set to RUBYOPT[-internal-encoding]:	.: (11.857187)
2021-08-16T02:22:52.7928125Z     test: failed to start workers when configured plugins as children of MultiOutput do not support multi worker configuration:	F
2021-08-16T02:22:53.0649098Z ===============================================================================
2021-08-16T02:22:53.0689997Z Failure: test: failed to start workers when configured plugins as children of MultiOutput do not support multi worker configuration(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:22:53.0741625Z   fluentd failed to start, without specified regular expressions:
2021-08-16T02:22:53.0748024Z   2021-08-16 02:22:50 +0000 [info]: parsing config file is succeeded path="D:/a/fluentd/fluentd/test/tmp/command/fluentd/workers_invalid3.conf"
2021-08-16T02:22:53.0752733Z   2021-08-16 02:22:50 +0000 [info]: gem 'fluentd' version '1.14.0.rc'
2021-08-16T02:22:53.0756080Z   2021-08-16 02:22:50 +0000 [warn]: [single] both of Plugin @id and path for <storage> are not specified. Using on-memory store.
2021-08-16T02:22:53.0759985Z   2021-08-16 02:22:50 +0000 [warn]: [single] both of Plugin @id and path for <storage> are not specified. Using on-memory store.
2021-08-16T02:22:53.0764530Z   2021-08-16 02:22:50 +0000 [info]: using configuration file: <ROOT>
2021-08-16T02:22:53.0767401Z     <system>
2021-08-16T02:22:53.0769517Z       workers 2
2021-08-16T02:22:53.0798585Z     </system>
2021-08-16T02:22:53.0966995Z     <source>
2021-08-16T02:22:53.1889995Z       @type dummy
2021-08-16T02:22:53.2452303Z       tag "dummy"
2021-08-16T02:22:53.3451130Z       @id single
2021-08-16T02:22:53.5496862Z       @label @dummydata
2021-08-16T02:22:53.7676375Z     </source>
2021-08-16T02:22:54.1763100Z     <label @dummydata>
2021-08-16T02:22:54.2271021Z       <match dummy>
2021-08-16T02:22:54.2619205Z         @type copy
2021-08-16T02:22:54.3273101Z         <store>
2021-08-16T02:22:54.4023367Z           @type "single"
2021-08-16T02:22:54.5952692Z         </store>
2021-08-16T02:22:55.5776566Z         <store>
2021-08-16T02:22:55.6829341Z           @type "single"
2021-08-16T02:22:55.8082018Z         </store>
2021-08-16T02:22:55.9243307Z       </match>
2021-08-16T02:22:56.0552917Z     </label>
2021-08-16T02:22:56.0800650Z   </ROOT>
2021-08-16T02:22:56.2464781Z   2021-08-16 02:22:50 +0000 [info]: starting fluentd-1.14.0.rc pid=4876 ruby="2.7.4"
2021-08-16T02:22:56.4339805Z   2021-08-16 02:22:50 +0000 [info]: spawn command to main:  cmdline=["C:/hostedtoolcache/windows/Ruby/2.7.4/x64/bin/ruby.exe", "-rC:/hostedtoolcache/windows/Ruby/2.7.4/x64/lib/ruby/gems/2.7.0/gems/bundler-2.2.25/lib/bundler/setup", "-Eascii-8bit:ascii-8bit", "D:/a/fluentd/fluentd/bin/fluentd", "-c", "D:/a/fluentd/fluentd/test/tmp/command/fluentd/workers_invalid3.conf", "-p", "D:/a/fluentd/fluentd/test/tmp/command/fluentd/plugin", "--under-supervisor"]
2021-08-16T02:22:59.2253547Z   .
2021-08-16T02:22:59.2253967Z   <false> is not true.
2021-08-16T02:22:59.2254683Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:209:in `assert_fluentd_fails_to_start'
2021-08-16T02:22:59.2255773Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:723:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:22:59.2256555Z ===============================================================================
2021-08-16T02:22:59.2256953Z : (12.067584)
2021-08-16T02:23:04.0276415Z     test: failed to start workers when configured plugins do not support multi worker configuration:	F
2021-08-16T02:23:04.0538154Z ===============================================================================
2021-08-16T02:23:04.1096396Z Failure: test: failed to start workers when configured plugins do not support multi worker configuration(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:23:04.1618854Z   fluentd failed to start, without specified regular expressions:
2021-08-16T02:23:04.2886684Z   .
2021-08-16T02:23:04.3989903Z   <false> is not true.
2021-08-16T02:23:05.2367927Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:209:in `assert_fluentd_fails_to_start'
2021-08-16T02:23:05.5769205Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:648:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:23:05.5782741Z ===============================================================================
2021-08-16T02:23:05.6145558Z : (11.228944)
2021-08-16T02:23:18.6365568Z     test: failed to start workers when file buffer is configured in non-workers way:	F
2021-08-16T02:23:18.7132815Z ===============================================================================
2021-08-16T02:23:19.0085815Z Failure: test: failed to start workers when file buffer is configured in non-workers way(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:23:19.4060141Z   fluentd failed to start, without specified regular expressions:
2021-08-16T02:23:19.6620722Z   .
2021-08-16T02:23:20.1310485Z   <false> is not true.
2021-08-16T02:23:20.1906545Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:209:in `assert_fluentd_fails_to_start'
2021-08-16T02:23:20.2790691Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:677:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:23:20.4294521Z ===============================================================================
2021-08-16T02:23:20.4819127Z : (14.484754)
2021-08-16T02:23:20.6734469Z     test: invalid values are set to RUBYOPT:		O
2021-08-16T02:23:20.8671391Z ===============================================================================
2021-08-16T02:23:21.1275655Z Omission: hard to run correctly because RUBYOPT=-r/path/to/bundler/setup is required on Windows while this test set invalid RUBYOPT [test: invalid values are set to RUBYOPT(TestFluentdCommand::configured to run 2 workers)]
2021-08-16T02:23:21.4548451Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:899:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:23:21.7318183Z ===============================================================================
2021-08-16T02:23:22.3256466Z : (0.584737)
2021-08-16T02:23:33.7095973Z     test: multiple values are set to RUBYOPT:		F
2021-08-16T02:23:33.8465234Z ===============================================================================
2021-08-16T02:23:33.9445267Z Failure: test: multiple values are set to RUBYOPT(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:23:34.1869558Z   execution timeout.
2021-08-16T02:23:34.4401193Z   <"WARN: Unresolved or ambiguous specs during Gem::Specification.reset:\r\n" +
2021-08-16T02:23:34.7219799Z   "      power_assert (>= 0)\r\n" +
2021-08-16T02:23:35.6356416Z   "      Available/installed versions of this gem:\r\n" +
2021-08-16T02:23:35.9590813Z   "      - 2.0.0\r\n" +
2021-08-16T02:23:35.9637165Z   "      - 1.1.7\r\n" +
2021-08-16T02:23:35.9925702Z   "WARN: Clearing out unresolved specs. Try 'gem cleanup <gem>'\r\n" +
2021-08-16T02:23:36.0112490Z   "Please report a bug if this causes problems.\r\n" +
2021-08-16T02:23:36.0189965Z   "2021-08-16 02:23:31 +0000 [info]: parsing config file is succeeded path=\"D:/a/fluentd/fluentd/test/tmp/command/fluentd/rubyopt_test.conf\"\r\n" +
2021-08-16T02:23:36.2224951Z   "2021-08-16 02:23:31 +0000 [info]: gem 'fluentd' version '1.14.0.rc'\r\n" +
2021-08-16T02:23:36.2890645Z   "2021-08-16 02:23:32 +0000 [warn]: both of Plugin @id and path for <storage> are not specified. Using on-memory store.\r\n" +
2021-08-16T02:23:36.5179783Z   "2021-08-16 02:23:32 +0000 [warn]: both of Plugin @id and path for <storage> are not specified. Using on-memory store.\r\n" +
2021-08-16T02:23:36.6147738Z   "2021-08-16 02:23:32 +0000 [warn]: define <match fluent.**> to capture fluentd logs in top level is deprecated. Use <label @FLUENT_LOG> instead\r\n" +
2021-08-16T02:23:36.8862350Z   "2021-08-16 02:23:32 +0000 [info]: using configuration file: <ROOT>\r\n" +
2021-08-16T02:23:37.6325281Z   "  <source>\r\n" +
2021-08-16T02:23:37.8298861Z   "    @type dummy\r\n" +
2021-08-16T02:23:37.8341789Z   "    tag \"dummy\"\r\n" +
2021-08-16T02:23:38.1186407Z   "  </source>\r\n" +
2021-08-16T02:23:38.1263510Z   "  <match>\r\n" +
2021-08-16T02:23:38.1688665Z   "    @type null\r\n" +
2021-08-16T02:23:38.3059081Z   "  </match>\r\n" +
2021-08-16T02:23:38.3818396Z   "</ROOT>\r\n" +
2021-08-16T02:23:38.5771404Z   "2021-08-16 02:23:32 +0000 [info]: starting fluentd-1.14.0.rc pid=1472 ruby=\"2.7.4\"\r\n" +
2021-08-16T02:23:38.7029952Z   "2021-08-16 02:23:32 +0000 [info]: spawn command to main:  cmdline=[\"C:/hostedtoolcache/windows/Ruby/2.7.4/x64/bin/ruby.exe\", \"-rC:/hostedtoolcache/windows/Ruby/2.7.4/x64/lib/ruby/gems/2.7.0/gems/bundler-2.2.25/lib/bundler/setup\", \"-rtest-unit\", \"-rbundler/setup\", \"-Eascii-8bit:ascii-8bit\", \"D:/a/fluentd/fluentd/bin/fluentd\", \"-c\", \"D:/a/fluentd/fluentd/test/tmp/command/fluentd/rubyopt_test.conf\", \"--under-supervisor\"]\r\n">
2021-08-16T02:23:38.9679439Z   was expected to include:
2021-08-16T02:23:39.3369940Z   <["#0 fluentd worker is now running worker=0"]>
2021-08-16T02:23:39.5606607Z   and not include:
2021-08-16T02:23:39.7756617Z   <["(LoadError)"]>
2021-08-16T02:23:39.9785689Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:23:40.4611695Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:846:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:23:40.6070069Z      843: </match>
2021-08-16T02:23:40.6303509Z      844: CONF
2021-08-16T02:23:40.6688084Z      845:       conf_path = create_conf_file('rubyopt_test.conf', conf)
2021-08-16T02:23:40.8164699Z   => 846:       assert_log_matches(
2021-08-16T02:23:41.1199502Z      847:         create_cmdline(conf_path),
2021-08-16T02:23:41.1636979Z      848:         '#0 fluentd worker is now running worker=0',
2021-08-16T02:23:41.3316423Z      849:         patterns_not_match: ['(LoadError)'],
2021-08-16T02:23:41.4757717Z ===============================================================================
2021-08-16T02:23:41.4801845Z : (14.450838)
2021-08-16T02:23:46.9277843Z     test: ruby path contains spaces:			F
2021-08-16T02:23:47.2323484Z ===============================================================================
2021-08-16T02:23:47.2332867Z Failure: test: ruby path contains spaces(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:23:47.3043632Z   execution timeout.
2021-08-16T02:23:48.1249871Z   <"\r\n" +
2021-08-16T02:23:48.3711526Z   "D:\\a\\fluentd\\fluentd\\test\\tmp\\command\\fluentd>C:/hostedtoolcache/windows/Ruby/2.7.4/x64/bin/ruby.exe D:/a/fluentd/fluentd/bin/fluentd -c D:/a/fluentd/fluentd/test/tmp/command/fluentd/space_mixed_ruby_path_test.conf \r\n">
2021-08-16T02:23:48.7677097Z   was expected to include:
2021-08-16T02:23:49.2523139Z   <["spawn command to main:", "-Eascii-8bit:ascii-8bit"]>
2021-08-16T02:23:49.6254567Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:23:49.8413971Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:944:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:23:50.4400780Z      941:       cmd_path = File.expand_path(File.dirname(__FILE__) + "../../../bin/fluentd")
2021-08-16T02:23:50.4478844Z      942:       conf_path = create_conf_file('space_mixed_ruby_path_test.conf', conf)
2021-08-16T02:23:50.6443760Z      943:       args = ["bundle", "exec", tmp_ruby_path, cmd_path, "-c", conf_path]
2021-08-16T02:23:50.7164544Z   => 944:       assert_log_matches(
2021-08-16T02:23:51.0726198Z      945:         args,
2021-08-16T02:23:51.5554374Z      946:         'spawn command to main:',
2021-08-16T02:23:51.8612618Z      947:         '-Eascii-8bit:ascii-8bit'
2021-08-16T02:23:52.0884333Z ===============================================================================
2021-08-16T02:23:52.6060125Z : (13.103954)
2021-08-16T02:24:01.9370197Z     test: success to start a worker with worker specific configuration:	F
2021-08-16T02:24:02.1783146Z ===============================================================================
2021-08-16T02:24:02.2500696Z Failure: test: success to start a worker with worker specific configuration(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:24:02.3276189Z   execution timeout.
2021-08-16T02:24:02.4546912Z   <"">
2021-08-16T02:24:02.5124178Z   was expected to include:
2021-08-16T02:24:02.6210625Z   <["#0 fluentd worker is now running worker=0",
2021-08-16T02:24:03.0395561Z    "#1 fluentd worker is now running worker=1",
2021-08-16T02:24:04.0241319Z    /(?!#\d) adding source type="dummy"/,
2021-08-16T02:24:04.4118933Z    "#1 adding source type=\"dummy\""]>
2021-08-16T02:24:04.6414637Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:24:04.8468223Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:783:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:24:05.0481617Z ===============================================================================
2021-08-16T02:24:05.1020789Z : (14.942786)
2021-08-16T02:24:15.3538334Z     test: success to start a worker2 with worker specific configuration:F
2021-08-16T02:24:15.3684357Z ===============================================================================
2021-08-16T02:24:15.3704109Z Failure: test: success to start a worker2 with worker specific configuration(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:24:15.4661466Z   execution timeout.
2021-08-16T02:24:16.0897384Z   <"">
2021-08-16T02:24:16.9776086Z   was expected to include:
2021-08-16T02:24:16.9822102Z   <["spawn command to main"]>
2021-08-16T02:24:17.1086852Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:24:17.1983563Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:741:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:24:17.2010800Z      738:       FileUtils.rm_rf(@root_path) rescue nil
2021-08-16T02:24:17.3715420Z      739: 
2021-08-16T02:24:17.4016003Z      740:       assert_path_not_exist(@root_path)
2021-08-16T02:24:17.4020036Z   => 741:       assert_log_matches(create_cmdline(conf_path), 'spawn command to main') # any message is ok
2021-08-16T02:24:17.4025591Z      742:       assert_path_exist(@root_path)
2021-08-16T02:24:17.4058365Z      743:       if Fluent.windows?
2021-08-16T02:24:17.6335030Z      744:         # In Windows, dir permission is always 755.
2021-08-16T02:24:17.8704556Z ===============================================================================
2021-08-16T02:24:17.8773964Z : (13.790245)
2021-08-16T02:24:31.3250386Z     test: success to start the number of workers specified by command line option:	F
2021-08-16T02:24:31.3252569Z ===============================================================================
2021-08-16T02:24:31.5713151Z Failure: test: success to start the number of workers specified by command line option(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:24:31.7077170Z   execution timeout.
2021-08-16T02:24:31.7799582Z   <"">
2021-08-16T02:24:31.9277869Z   was expected to include:
2021-08-16T02:24:32.1105624Z   <["#0 fluentd worker is now running worker=0",
2021-08-16T02:24:32.1479931Z    "#1 fluentd worker is now running worker=1"]>
2021-08-16T02:24:32.4280644Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:24:32.6272543Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:612:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:24:32.6306614Z ===============================================================================
2021-08-16T02:24:32.6695398Z : (15.344046)
2021-08-16T02:24:45.4996752Z     test: success to start the number of workers specified in configuration:	F
2021-08-16T02:24:45.5054007Z ===============================================================================
2021-08-16T02:24:45.6518271Z Failure: test: success to start the number of workers specified in configuration(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:24:45.8571191Z   execution timeout.
2021-08-16T02:24:46.1356411Z   <"">
2021-08-16T02:24:46.2367075Z   was expected to include:
2021-08-16T02:24:46.2848454Z   <["#0 fluentd worker is now running worker=0",
2021-08-16T02:24:46.3504077Z    "#1 fluentd worker is now running worker=1"]>
2021-08-16T02:24:46.4495123Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:24:46.5012613Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:585:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:24:46.5499772Z ===============================================================================
2021-08-16T02:24:46.5614476Z : (14.177524)
2021-08-16T02:24:58.8523595Z     test: success to start workers when configured plugins as a children of MultiOutput only for specific worker do not support multi worker configuration:	F
2021-08-16T02:24:58.9439844Z ===============================================================================
2021-08-16T02:24:59.1885626Z Failure: test: success to start workers when configured plugins as a children of MultiOutput only for specific worker do not support multi worker configuration(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:24:59.3642361Z   execution timeout.
2021-08-16T02:24:59.4354961Z   <"">
2021-08-16T02:24:59.6046382Z   was expected to include:
2021-08-16T02:24:59.6587548Z   <["#0 fluentd worker is now running worker=0",
2021-08-16T02:24:59.8110369Z    "#1 fluentd worker is now running worker=1",
2021-08-16T02:24:59.8587354Z    "#1 adding match pattern=\"dummy\" type=\"copy\""]>
2021-08-16T02:24:59.8693538Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:25:00.0290262Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:1023:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:25:00.1131064Z ===============================================================================
2021-08-16T02:25:00.1493629Z : (13.787508)
2021-08-16T02:25:13.0834411Z     test: success to start workers when configured plugins only for specific worker do not support multi worker configuration:	F
2021-08-16T02:25:13.1687749Z ===============================================================================
2021-08-16T02:25:13.1698168Z Failure: test: success to start workers when configured plugins only for specific worker do not support multi worker configuration(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:25:13.4974277Z   execution timeout.
2021-08-16T02:25:13.6705011Z   <"">
2021-08-16T02:25:13.7590145Z   was expected to include:
2021-08-16T02:25:13.8468111Z   <["#0 fluentd worker is now running worker=0",
2021-08-16T02:25:13.8629245Z    "#1 fluentd worker is now running worker=1",
2021-08-16T02:25:14.2473093Z    "#1 adding source type=\"single\""]>
2021-08-16T02:25:14.6427443Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:25:15.5998748Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:827:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:25:15.6741678Z ===============================================================================
2021-08-16T02:25:16.0076019Z : (14.322403)
2021-08-16T02:25:27.0558074Z     test: success to start workers when file buffer is configured in non-workers way only for specific worker:	F
2021-08-16T02:25:27.0872141Z ===============================================================================
2021-08-16T02:25:27.2211695Z Failure: test: success to start workers when file buffer is configured in non-workers way only for specific worker(TestFluentdCommand::configured to run 2 workers):
2021-08-16T02:25:27.6457006Z   execution timeout.
2021-08-16T02:25:27.7695524Z   <"">
2021-08-16T02:25:28.1293144Z   was expected to include:
2021-08-16T02:25:28.7327779Z   <["#0 fluentd worker is now running worker=0",
2021-08-16T02:25:29.0931909Z    "#1 fluentd worker is now running worker=1",
2021-08-16T02:25:29.1746847Z    "#1 adding match pattern=\"dummy\" type=\"null\""]>
2021-08-16T02:25:29.4746711Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:25:29.5004702Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:976:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:25:29.6543701Z ===============================================================================
2021-08-16T02:25:30.1404321Z : (13.619544)
2021-08-16T02:25:42.7194648Z     test: without RUBYOPT:				.: (15.288406)
2021-08-16T02:25:42.7665429Z   configured to suppress configuration dump: 
2021-08-16T02:25:58.2687250Z     test: configured as false by system config, but overridden as true by command line option:	F
2021-08-16T02:25:58.4195381Z ===============================================================================
2021-08-16T02:25:58.5099976Z Failure: test: configured as false by system config, but overridden as true by command line option(TestFluentdCommand::configured to suppress configuration dump):
2021-08-16T02:25:58.5495466Z   execution timeout.
2021-08-16T02:25:58.5866234Z   <"">
2021-08-16T02:25:58.6367102Z   was expected to include:
2021-08-16T02:25:58.9443851Z   <["fluentd worker is now running"]>
2021-08-16T02:25:59.0224025Z   and not include:
2021-08-16T02:25:59.3498832Z   <["tag dummy"]>
2021-08-16T02:25:59.4007387Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:25:59.5130988Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:450:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:25:59.8369691Z      447: </system>
2021-08-16T02:26:00.6629942Z      448: SYSTEM
2021-08-16T02:26:00.8086738Z      449:       conf_path = create_conf_file('suppress_conf_dump_3.conf', conf)
2021-08-16T02:26:00.9968360Z   => 450:       assert_log_matches(create_cmdline(conf_path, '--suppress-config-dump'), "fluentd worker is now running", patterns_not_match: ["tag dummy"])
2021-08-16T02:26:01.4693678Z      451:     end
2021-08-16T02:26:01.4718429Z      452:   end
2021-08-16T02:26:01.4725331Z      453: 
2021-08-16T02:26:01.4729889Z ===============================================================================
2021-08-16T02:26:01.4732465Z : (15.895562)
2021-08-16T02:26:12.1605131Z     test: configured by command line option:		F
2021-08-16T02:26:12.1695702Z ===============================================================================
2021-08-16T02:26:12.3698798Z Failure: test: configured by command line option(TestFluentdCommand::configured to suppress configuration dump):
2021-08-16T02:26:12.5022881Z   execution timeout.
2021-08-16T02:26:12.6265949Z   <"">
2021-08-16T02:26:12.7954730Z   was expected to include:
2021-08-16T02:26:12.8945818Z   <["fluentd worker is now running"]>
2021-08-16T02:26:13.5118631Z   and not include:
2021-08-16T02:26:13.8405861Z   <["tag dummy"]>
2021-08-16T02:26:13.8461721Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:26:13.8521453Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:440:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:26:15.0746243Z      437: 
2021-08-16T02:26:15.4683384Z      438:     test 'configured by command line option' do
2021-08-16T02:26:16.0205697Z      439:       conf_path = create_conf_file('suppress_conf_dump_2.conf', @basic_conf)
2021-08-16T02:26:16.0686530Z   => 440:       assert_log_matches(create_cmdline(conf_path, '--suppress-config-dump'), "fluentd worker is now running", patterns_not_match: ["tag dummy"])
2021-08-16T02:26:16.4662320Z      441:     end
2021-08-16T02:26:16.5536995Z      442: 
2021-08-16T02:26:16.5548150Z      443:     test 'configured as false by system config, but overridden as true by command line option' do
2021-08-16T02:26:16.8972171Z ===============================================================================
2021-08-16T02:26:16.9974488Z : (14.332296)
2021-08-16T02:26:31.2795786Z     test: configured by system config:			F
2021-08-16T02:26:31.2800855Z ===============================================================================
2021-08-16T02:26:31.2810299Z Failure: test: configured by system config(TestFluentdCommand::configured to suppress configuration dump):
2021-08-16T02:26:31.2811392Z   execution timeout.
2021-08-16T02:26:31.2811788Z   <"">
2021-08-16T02:26:31.2812242Z   was expected to include:
2021-08-16T02:26:31.2812760Z   <["fluentd worker is now running"]>
2021-08-16T02:26:31.2813242Z   and not include:
2021-08-16T02:26:31.2813621Z   <["tag dummy"]>
2021-08-16T02:26:31.2814259Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:26:31.2815326Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:435:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:26:31.2816072Z      432: </system>
2021-08-16T02:26:31.2816460Z      433: SYSTEM
2021-08-16T02:26:31.2817015Z      434:       conf_path = create_conf_file('suppress_conf_dump_1.conf', conf)
2021-08-16T02:26:31.2818153Z   => 435:       assert_log_matches(create_cmdline(conf_path), "fluentd worker is now running", patterns_not_match: ["tag dummy"])
2021-08-16T02:26:31.2818860Z      436:     end
2021-08-16T02:26:31.2819210Z      437: 
2021-08-16T02:26:31.2819690Z      438:     test 'configured by command line option' do
2021-08-16T02:26:31.2820350Z ===============================================================================
2021-08-16T02:26:31.2820726Z : (14.913324)
2021-08-16T02:26:31.2821142Z   sahred socket options: 
2021-08-16T02:26:42.7051293Z     test: disable shared socket by command line option:	F
2021-08-16T02:26:42.7059421Z ===============================================================================
2021-08-16T02:26:42.8191558Z Failure: test: disable shared socket by command line option(TestFluentdCommand::sahred socket options):
2021-08-16T02:26:43.2637478Z   execution timeout.
2021-08-16T02:26:43.8465588Z   <"">
2021-08-16T02:26:44.3460160Z   was expected to include:
2021-08-16T02:26:44.3953281Z   <["shared socket for multiple workers is disabled"]>
2021-08-16T02:26:44.7079595Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:26:44.8821940Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:1090:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:26:44.8974555Z      1087:       conf = ""
2021-08-16T02:26:45.0064839Z      1088:       conf_path = create_conf_file('empty.conf', conf)
2021-08-16T02:26:45.0455493Z      1089:       assert File.exist?(conf_path)
2021-08-16T02:26:45.0970456Z   => 1090:       assert_log_matches(create_cmdline(conf_path, "--disable-shared-socket"),
2021-08-16T02:26:45.1977601Z      1091:                          "shared socket for multiple workers is disabled",)
2021-08-16T02:26:45.2253282Z      1092:     end
2021-08-16T02:26:46.0857719Z      1093: 
2021-08-16T02:26:46.3867219Z ===============================================================================
2021-08-16T02:26:46.5359555Z : (15.400659)
2021-08-16T02:26:58.0150434Z     test: disable shared socket by system config:	F
2021-08-16T02:26:58.2589507Z ===============================================================================
2021-08-16T02:26:58.3721373Z Failure: test: disable shared socket by system config(TestFluentdCommand::sahred socket options):
2021-08-16T02:26:58.9129714Z   execution timeout.
2021-08-16T02:26:59.2994590Z   <"">
2021-08-16T02:26:59.2996373Z   was expected to include:
2021-08-16T02:26:59.3015854Z   <["shared socket for multiple workers is disabled"]>
2021-08-16T02:26:59.4700100Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:26:59.6152804Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:1102:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:26:59.7149011Z      1099: CONF
2021-08-16T02:26:59.8527498Z      1100:       conf_path = create_conf_file('empty.conf', conf)
2021-08-16T02:26:59.9072040Z      1101:       assert File.exist?(conf_path)
2021-08-16T02:27:00.1555976Z   => 1102:       assert_log_matches(create_cmdline(conf_path, "--disable-shared-socket"),
2021-08-16T02:27:00.2077093Z      1103:                          "shared socket for multiple workers is disabled",)
2021-08-16T02:27:00.2334196Z      1104:     end
2021-08-16T02:27:00.5294421Z      1105:   end
2021-08-16T02:27:00.5324747Z ===============================================================================
2021-08-16T02:27:00.9208047Z : (15.607050)
2021-08-16T02:27:12.5576076Z     test: enable shared socket by default:		F
2021-08-16T02:27:12.5662729Z ===============================================================================
2021-08-16T02:27:12.6076279Z Failure: test: enable shared socket by default(TestFluentdCommand::sahred socket options):
2021-08-16T02:27:13.0482056Z   execution timeout.
2021-08-16T02:27:13.3152910Z   <"">
2021-08-16T02:27:13.3212057Z   was expected to include:
2021-08-16T02:27:13.9235463Z   <[]>
2021-08-16T02:27:14.4026044Z   and not include:
2021-08-16T02:27:14.8247974Z   <["shared socket for multiple workers is disabled"]>
2021-08-16T02:27:14.8761622Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:27:14.9600544Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:1082:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:27:15.0350548Z      1079:       conf = ""
2021-08-16T02:27:15.1404399Z      1080:       conf_path = create_conf_file('empty.conf', conf)
2021-08-16T02:27:15.1604756Z      1081:       assert File.exist?(conf_path)
2021-08-16T02:27:15.3499530Z   => 1082:       assert_log_matches(create_cmdline(conf_path),
2021-08-16T02:27:15.4365244Z      1083:                          patterns_not_match: ["shared socket for multiple workers is disabled"])
2021-08-16T02:27:15.4667975Z      1084:     end
2021-08-16T02:27:15.5563050Z      1085: 
2021-08-16T02:27:15.7573782Z ===============================================================================
2021-08-16T02:27:15.7845088Z : (14.274697)
2021-08-16T02:27:16.0156269Z   with --conf-encoding: 
2021-08-16T02:27:28.6179356Z     test: failed to run by invalid encoding:		F
2021-08-16T02:27:28.6634746Z ===============================================================================
2021-08-16T02:27:28.7731368Z Failure: test: failed to run by invalid encoding(TestFluentdCommand::with --conf-encoding):
2021-08-16T02:27:28.8301285Z   fluentd failed to start, without specified regular expressions:
2021-08-16T02:27:33.0447444Z   .
2021-08-16T02:27:33.0447981Z   <false> is not true.
2021-08-16T02:27:33.0448706Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:209:in `assert_fluentd_fails_to_start'
2021-08-16T02:27:33.0449823Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:266:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:27:33.0450698Z      263: </match>
2021-08-16T02:27:33.0451119Z      264: CONF
2021-08-16T02:27:33.0451693Z      265:       conf_path = create_conf_file('shift_jis.conf', conf, 'shift_jis')
2021-08-16T02:27:33.0452656Z   => 266:       assert_fluentd_fails_to_start(create_cmdline(conf_path), "invalid byte sequence in UTF-8")
2021-08-16T02:27:33.0453369Z      267:     end
2021-08-16T02:27:33.0597891Z      268:   end
2021-08-16T02:27:33.0598328Z      269: 
2021-08-16T02:27:33.0598734Z ===============================================================================
2021-08-16T02:27:33.0599206Z : (16.121889)
2021-08-16T02:27:44.2538558Z     test: runs successfully:				F
2021-08-16T02:27:44.3103220Z ===============================================================================
2021-08-16T02:27:44.5139021Z Failure: test: runs successfully(TestFluentdCommand::with --conf-encoding):
2021-08-16T02:27:44.6283411Z   execution timeout.
2021-08-16T02:27:44.6457167Z   <"">
2021-08-16T02:27:44.7003648Z   was expected to include:
2021-08-16T02:27:44.7955695Z   <["fluentd worker is now running", "worker=0"]>
2021-08-16T02:27:44.8407270Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:27:44.8992362Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:250:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:27:45.0777744Z      247: </match>
2021-08-16T02:27:46.0719264Z      248: CONF
2021-08-16T02:27:46.1806210Z      249:       conf_path = create_conf_file('shift_jis.conf', conf, 'shift_jis')
2021-08-16T02:27:46.2352181Z   => 250:       assert_log_matches(create_cmdline(conf_path, '--conf-encoding', 'shift_jis'), "fluentd worker is now running", 'worker=0')
2021-08-16T02:27:46.2856202Z      251:     end
2021-08-16T02:27:46.4096396Z      252: 
2021-08-16T02:27:46.4716436Z      253:     test 'failed to run by invalid encoding' do
2021-08-16T02:27:46.6811994Z ===============================================================================
2021-08-16T02:27:47.5210181Z : (15.189270)
2021-08-16T02:27:47.7633465Z   with system configuration about root directory: 
2021-08-16T02:27:56.6326681Z     test: creates the specified root directory if missing:	F
2021-08-16T02:27:56.6614760Z ===============================================================================
2021-08-16T02:27:56.9911398Z Failure: test: creates the specified root directory if missing(TestFluentdCommand::with system configuration about root directory):
2021-08-16T02:27:57.1895568Z   execution timeout.
2021-08-16T02:27:57.3101727Z   <"">
2021-08-16T02:27:57.3582956Z   was expected to include:
2021-08-16T02:27:57.6079959Z   <["fluentd worker is now running", "worker=0"]>
2021-08-16T02:27:57.6596183Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:27:58.0070568Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:306:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:27:58.0570201Z      303:       conf_path = create_conf_file('missing_root_dir.conf', @conf)
2021-08-16T02:27:58.1891711Z      304:       assert_false Dir.exist?(@root_path)
2021-08-16T02:27:58.2619887Z      305: 
2021-08-16T02:27:58.4482602Z   => 306:       assert_log_matches(create_cmdline(conf_path), "fluentd worker is now running", 'worker=0')
2021-08-16T02:27:59.1227528Z      307:       assert Dir.exist?(@root_path)
2021-08-16T02:27:59.2532846Z      308:     end
2021-08-16T02:27:59.5566272Z      309: 
2021-08-16T02:27:59.7389943Z ===============================================================================
2021-08-16T02:28:00.2386818Z : (12.960674)
2021-08-16T02:28:12.1309573Z     test: fails to launch fluentd if specified root path is invalid path for directory:	F
2021-08-16T02:28:12.1790276Z ===============================================================================
2021-08-16T02:28:12.7154999Z Failure: test: fails to launch fluentd if specified root path is invalid path for directory(TestFluentdCommand::with system configuration about root directory):
2021-08-16T02:28:12.7260587Z   fluentd failed to start, without specified regular expressions:
2021-08-16T02:28:12.8749566Z   .
2021-08-16T02:28:12.9307716Z   <false> is not true.
2021-08-16T02:28:13.0479604Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:209:in `assert_fluentd_fails_to_start'
2021-08-16T02:28:13.1122169Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:316:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:28:13.2357938Z      313:       end
2021-08-16T02:28:14.1482013Z      314:       conf_path = create_conf_file('existing_root_dir.conf', @conf)
2021-08-16T02:28:14.2057834Z      315: 
2021-08-16T02:28:14.2087580Z   => 316:       assert_fluentd_fails_to_start(
2021-08-16T02:28:14.2597368Z      317:         create_cmdline(conf_path),
2021-08-16T02:28:14.3598317Z      318:         "non directory entry exists:#{@root_path}",
2021-08-16T02:28:14.7483134Z      319:       )
2021-08-16T02:28:15.2010614Z ===============================================================================
2021-08-16T02:28:15.5465130Z : (15.485838)
2021-08-16T02:28:26.9916075Z     test: use the specified existing directory as root:	F
2021-08-16T02:28:27.0602758Z ===============================================================================
2021-08-16T02:28:27.0645790Z Failure: test: use the specified existing directory as root(TestFluentdCommand::with system configuration about root directory):
2021-08-16T02:28:27.0648457Z   execution timeout.
2021-08-16T02:28:27.0650706Z   <"">
2021-08-16T02:28:27.0651843Z   was expected to include:
2021-08-16T02:28:27.0654393Z   <["fluentd worker is now running", "worker=0"]>
2021-08-16T02:28:27.0657050Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:28:27.0659501Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:299:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:28:27.0662106Z      296:       conf_path = create_conf_file('existing_root_dir.conf', @conf)
2021-08-16T02:28:27.0663541Z      297:       assert Dir.exist?(@root_path)
2021-08-16T02:28:27.0664939Z      298: 
2021-08-16T02:28:27.0666503Z   => 299:       assert_log_matches(create_cmdline(conf_path), "fluentd worker is now running", 'worker=0')
2021-08-16T02:28:27.0668733Z      300:     end
2021-08-16T02:28:27.0671758Z      301: 
2021-08-16T02:28:27.0673836Z      302:     test 'creates the specified root directory if missing' do
2021-08-16T02:28:27.0676188Z ===============================================================================
2021-08-16T02:28:27.0677357Z : (14.417933)
2021-08-16T02:28:27.0679001Z   with valid configuration: 
2021-08-16T02:28:40.4620055Z     test: runs successfully:				F
2021-08-16T02:28:40.4890651Z ===============================================================================
2021-08-16T02:28:40.6099879Z Failure: test: runs successfully(TestFluentdCommand::with valid configuration):
2021-08-16T02:28:40.7373970Z   execution timeout.
2021-08-16T02:28:41.0656445Z   <"">
2021-08-16T02:28:41.1286000Z   was expected to include:
2021-08-16T02:28:41.6306989Z   <["fluentd worker is now running", "worker=0"]>
2021-08-16T02:28:41.6902133Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:162:in `assert_log_matches'
2021-08-16T02:28:41.7091293Z D:/a/fluentd/fluentd/test/command/test_fluentd.rb:232:in `block (2 levels) in <class:TestFluentdCommand>'
2021-08-16T02:28:41.8029961Z ===============================================================================
2021-08-16T02:28:42.9853614Z : (13.734773)
2021-08-16T02:28:43.0381391Z TestFluentdCtl: 
2021-08-16T02:28:43.0958911Z   test_commands[shutdown]:				.: (0.075510)
2021-08-16T02:28:43.2697143Z   test_commands[restart]:				.: (0.014718)
2021-08-16T02:28:43.6279570Z   test_commands[flush]:					.: (0.007112)
2021-08-16T02:28:43.6863828Z   test_commands[reload]:				.: (0.051405)
2021-08-16T02:28:43.7492588Z   test_commands_with_winsvcname[shutdown]:		The specified service does not exist as an installed service. - OpenService: The specified service does not exist as an installed service.

Additional context

No response
@ashie ashie mentioned this issue Aug 20, 2021
Merged
@ashie
Copy link
Member Author

ashie commented Aug 24, 2021

Hmm, each tests are succeeded when I run them individually.

e.g.)

>bundle exec rake test TEST=test/command/test_fluentd.rb TESTOPTS="--name=\"test: success to start a worker2 with worker specific configuration\""
test_file_util.rb for non-root user env. Disable this test on root environment
C:/Ruby27-x64/bin/ruby.exe -w -I"lib;test" -Eascii-8bit:ascii-8bit C:/Users/aho/Projects/fluentd/vendor/bundle/ruby/2.7.0/gems/rake-13.0.6/lib/rake/rake_test_loader.rb "test/command/test_fluentd.rb" --name="test: success to start a worker2 with worker specific configuration"
C:/Users/aho/Projects/fluentd/lib/fluent/config/literal_parser.rb:194: warning: assigned but unused variable - e
C:/Users/aho/Projects/fluentd/vendor/bundle/ruby/2.7.0/gems/serverengine-2.2.4-x64-mingw32/lib/serverengine/socket_manager_win.rb:140: warning: assigned but unused variable - type
C:/Users/aho/Projects/fluentd/lib/fluent/plugin_helper.rb:46: warning: method redefined; discarding old inherited
C:/Users/aho/Projects/fluentd/lib/fluent/plugin_helper.rb:46: warning: previous definition of inherited was here
C:/Users/aho/Projects/fluentd/lib/fluent/plugin/parser_syslog.rb:487: warning: assigned but unused variable - e
Loaded suite C:/Users/aho/Projects/fluentd/vendor/bundle/ruby/2.7.0/gems/rake-13.0.6/lib/rake/rake_test_loader
Started
.
Finished in 2.4104524 seconds.
--------------------------------------------------------------------------------------------------------------------------------------------------------------
1 tests, 4 assertions, 0 failures, 0 errors, 0 pendings, 0 omissions, 0 notifications
100% passed
--------------------------------------------------------------------------------------------------------------------------------------------------------------
0.41 tests/s, 1.66 assertions/s

@ashie ashie mentioned this issue Aug 28, 2021
Merged
@ashie ashie changed the title windows: Dozens of tests fail when the version number of fluentd has .rc suffix windows: test/command/test_fluentd.rb fails when the version number of fluentd has .rc suffix Aug 30, 2021
@github-actions
Copy link

This issue has been automatically marked as stale because it has been open 90 days with no activity. Remove stale label or comment or this issue will be closed in 30 days

@github-actions github-actions bot added the stale label Nov 28, 2021
@github-actions
Copy link

This issue was automatically closed because of stale in 30 days

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
stale
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@ashie