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.