Uploaded image for project: 'JDK'
  1. JDK
  2. JDK-8265920

4 java/lang/System/LoggerFinder/modules tests timed out

    XMLWordPrintable

    Details

    • Subcomponent:
    • CPU:
      x86_64
    • OS:
      os_x_10.15

      Description

      The following 4 tests timed in the JDK17 CI:

      java/lang/System/LoggerFinder/modules/UnnamedLoggerForImageTest.java
      java/lang/System/LoggerFinder/modules/LoggerInImageTest.java
      java/lang/System/LoggerFinder/modules/NamedLoggerForImageTest.java
      java/lang/System/LoggerFinder/modules/JDKLoggerForImageTest.java

      The first three timed out during their runs and the fourth passed
      while the timeout handler was running.

      Here's a snippet from each log file:

      UnnamedLoggerForImageTest.java

      [2021-04-25T05:51:12.488016Z] Waiting for completion for process 97524
      [2021-04-25T05:51:12.488066Z] Waiting for completion finished for process 97524
      Command line: [/System/Volumes/Data/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S248115/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/180a55da-97dc-4409-be7e-bbdc97c0a72e/runs/c590e79c-4367-4e43-9e1f-3bb62a273e60/testoutput/test-support/jtreg_open_test_jdk_tier1/scratch/2/image/bin/java --class-path cp_unnamed_logger --module-path mods_named_client -m m.t.a/pkg.a.t.TestA unnamed pkg.b.l.LoggerB]
      [2021-04-25T05:51:12.491016Z] Gathering output for process 97759
      [2021-04-25T05:51:12.693674Z] Waiting for completion for process 97759
      [2021-04-25T05:51:12.693817Z] Waiting for completion finished for process 97759
      Output and diagnostic info for process 97759 was saved into 'pid-97759-output.log'
      logger name: pkg.b.l.LoggerB, logger class: class pkg.b.l.LoggerB
      logger name: pkg.b.l.LoggerB, logger class: class pkg.b.l.LoggerB
      LoggerB Messagepkg.b.l.LoggerB$LogEvent@1e67b872
      logger name: pkg.b.l.LoggerB, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerB Messagepkg.b.l.LoggerB$LogEvent@77b52d12


      [2021-04-25T05:51:12.694573Z] Waiting for completion for process 97759
      [2021-04-25T05:51:12.694623Z] Waiting for completion finished for process 97759
      Command line: [/System/Volumes/Data/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S248115/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/180a55da-97dc-4409-be7e-bbdc97c0a72e/runs/c590e79c-4367-4e43-9e1f-3bb62a273e60/testoutput/test-support/jtreg_open_test_jdk_tier1/scratch/2/image_client/bin/java --class-path cp_unnamed_logger -m m.t.a/pkg.a.t.TestA unnamed pkg.b.l.LoggerB]
      [2021-04-25T05:51:15.988491Z] Gathering output for process 97760
      ----------System.err:(12/647)----------
      logger name: pkg.b.l.LoggerB, logger class: class pkg.b.l.LoggerB
      logger name: pkg.b.l.LoggerB, logger class: class pkg.b.l.LoggerB
      LoggerB Messagepkg.b.l.LoggerB$LogEvent@5f150435
      logger name: pkg.b.l.LoggerB, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerB Messagepkg.b.l.LoggerB$LogEvent@f5f2bb7

      logger name: pkg.b.l.LoggerB, logger class: class pkg.b.l.LoggerB
      logger name: pkg.b.l.LoggerB, logger class: class pkg.b.l.LoggerB
      LoggerB Messagepkg.b.l.LoggerB$LogEvent@1e67b872
      logger name: pkg.b.l.LoggerB, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerB Messagepkg.b.l.LoggerB$LogEvent@77b52d12

      ----------rerun:(39/7399)*----------


      LoggerInImageTest.java

      [2021-04-25T05:43:14.809108Z] Waiting for completion for process 97523
      [2021-04-25T05:43:14.809159Z] Waiting for completion finished for process 97523
      Command line: [/System/Volumes/Data/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S248115/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/180a55da-97dc-4409-be7e-bbdc97c0a72e/runs/c590e79c-4367-4e43-9e1f-3bb62a273e60/testoutput/test-support/jtreg_open_test_jdk_tier1/scratch/3/image_all/bin/java -m m.t.a/pkg.a.t.TestA named pkg.a.l.LoggerA]
      [2021-04-25T05:43:24.338549Z] Gathering output for process 97525
      Timeout refired 480 times
      [2021-04-25T05:52:31.625812Z] Waiting for completion for process 97525
      [2021-04-25T05:52:31.625955Z] Waiting for completion finished for process 97525
      Output and diagnostic info for process 97525 was saved into 'pid-97525-output.log'
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      LoggerA Message[level: WARNING, bundle: null, format: this is test log message #1, throwable: null, object: null]
      logger name: pkg.a.l.LoggerA, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerA Message[level: INFO, bundle: pkg.a.t.TestA$MyResourcesA@2752f6e2, format: THIS IS THE VALUE IN MyResourcesA, throwable: null, object: null]


      [2021-04-25T05:52:31.626730Z] Waiting for completion for process 97525
      [2021-04-25T05:52:31.626793Z] Waiting for completion finished for process 97525
      Command line: [/System/Volumes/Data/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S248115/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/180a55da-97dc-4409-be7e-bbdc97c0a72e/runs/c590e79c-4367-4e43-9e1f-3bb62a273e60/testoutput/test-support/jtreg_open_test_jdk_tier1/scratch/3/image_logger/bin/java --module-path mods_named_client -m m.t.a/pkg.a.t.TestA named pkg.a.l.LoggerA]
      [2021-04-25T05:52:34.677924Z] Gathering output for process 98298
      ----------System.err:(6/488)----------
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      LoggerA Message[level: WARNING, bundle: null, format: this is test log message #1, throwable: null, object: null]
      logger name: pkg.a.l.LoggerA, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerA Message[level: INFO, bundle: pkg.a.t.TestA$MyResourcesA@2752f6e2, format: THIS IS THE VALUE IN MyResourcesA, throwable: null, object: null]

      ----------rerun:(39/7343)*----------


      NamedLoggerForImageTest.java

      [2021-04-25T05:52:15.757567Z] Waiting for completion for process 97520
      [2021-04-25T05:52:15.757615Z] Waiting for completion finished for process 97520
      Command line: [/System/Volumes/Data/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S248115/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/180a55da-97dc-4409-be7e-bbdc97c0a72e/runs/c590e79c-4367-4e43-9e1f-3bb62a273e60/testoutput/test-support/jtreg_open_test_jdk_tier1/scratch/0/image/bin/java --module-path mods_named_logger --class-path cp_unnamed_client pkg.b.t.TestB named pkg.a.l.LoggerA]
      [2021-04-25T05:52:15.760288Z] Gathering output for process 98174
      [2021-04-25T05:52:15.934137Z] Waiting for completion for process 98174
      [2021-04-25T05:52:15.934270Z] Waiting for completion finished for process 98174
      Output and diagnostic info for process 98174 was saved into 'pid-98174-output.log'
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      LoggerA Message[level: WARNING, bundle: null, format: this is test log message #1, throwable: null, object: null]
      logger name: pkg.a.l.LoggerA, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerA Message[level: INFO, bundle: pkg.b.t.TestB$MyResourcesB@7225790e, format: THIS IS THE VALUE IN MyResourcesB, throwable: null, object: null]


      [2021-04-25T05:52:15.935002Z] Waiting for completion for process 98174
      [2021-04-25T05:52:15.935053Z] Waiting for completion finished for process 98174
      Command line: [/System/Volumes/Data/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S248115/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/180a55da-97dc-4409-be7e-bbdc97c0a72e/runs/c590e79c-4367-4e43-9e1f-3bb62a273e60/testoutput/test-support/jtreg_open_test_jdk_tier1/scratch/0/image_client/bin/java --module-path mods_named_logger -m m.t.a/pkg.a.t.TestA named pkg.a.l.LoggerA]
      [2021-04-25T05:52:19.213455Z] Gathering output for process 98175
      ----------System.err:(12/976)----------
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      LoggerA Message[level: WARNING, bundle: null, format: this is test log message #1, throwable: null, object: null]
      logger name: pkg.a.l.LoggerA, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerA Message[level: INFO, bundle: pkg.a.t.TestA$MyResourcesA@5cc7c2a6, format: THIS IS THE VALUE IN MyResourcesA, throwable: null, object: null]

      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      logger name: pkg.a.l.LoggerA, logger class: class pkg.a.l.LoggerA
      LoggerA Message[level: WARNING, bundle: null, format: this is test log message #1, throwable: null, object: null]
      logger name: pkg.a.l.LoggerA, logger class: class jdk.internal.logger.LocalizedLoggerWrapper
      LoggerA Message[level: INFO, bundle: pkg.b.t.TestB$MyResourcesB@7225790e, format: THIS IS THE VALUE IN MyResourcesB, throwable: null, object: null]

      ----------rerun:(39/7385)*----------


      JDKLoggerForImageTest.java

      [2021-04-25T05:52:00.185887Z] Waiting for completion for process 98065
      [2021-04-25T05:52:00.185949Z] Waiting for completion finished for process 98065
      Command line: [/System/Volumes/Data/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S248115/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/180a55da-97dc-4409-be7e-bbdc97c0a72e/runs/c590e79c-4367-4e43-9e1f-3bb62a273e60/testoutput/test-support/jtreg_open_test_jdk_tier1/scratch/4/image/bin/java --patch-module java.base=patched_usage/java.base --class-path patched_client PatchedClient system jdk.internal.logger.SimpleConsoleLogger]
      [2021-04-25T05:52:00.188863Z] Gathering output for process 98069
      [2021-04-25T05:52:00.402100Z] Waiting for completion for process 98069
      [2021-04-25T05:52:00.402214Z] Waiting for completion finished for process 98069
      Output and diagnostic info for process 98069 was saved into 'pid-98069-output.log'
      logger name: test.patched, logger class: class jdk.internal.logger.SimpleConsoleLogger


      [2021-04-25T05:52:00.402947Z] Waiting for completion for process 98069
      [2021-04-25T05:52:00.403015Z] Waiting for completion finished for process 98069
      ----------System.err:(9/352)----------
      logger name: test.a, logger class: class jdk.internal.logger.SimpleConsoleLogger

      logger name: test.b, logger class: class jdk.internal.logger.SimpleConsoleLogger

      logger name: test.boot, logger class: class jdk.internal.logger.SimpleConsoleLogger

      logger name: test.patched, logger class: class jdk.internal.logger.SimpleConsoleLogger

      STATUS:Passed.
      ----------rerun:(39/7371)*----------

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              dfuchs Daniel Fuchs
              Reporter:
              dcubed Daniel Daugherty
              Votes:
              0 Vote for this issue
              Watchers:
              5 Start watching this issue

                Dates

                Created:
                Updated: