Uploaded image for project: 'Dev - Nexus Repo'
  1. Dev - Nexus Repo
  2. NEXUS-19048

task logs may be from the future

    Details

    • Type: Bug
    • Status: Closed
    • Priority: Major
    • Resolution: Not A Bug
    • Affects Version/s: 3.15.1
    • Fix Version/s: None
    • Component/s: Logging
    • Labels:

      Description

      A customer support zip was received with the following timestamp and timezone information in sysinfo.json:

       "system-time": {
              "timezone": "Asia/Jerusalem",
              "current": 1549376598048,
              "iso8601": "2019-02-05T16:23:18.048+0200"
          },
      

      The log/tasks directory contained task logs from the future:

      > ls -la --time-style full-iso
      total 215M
      drwxr-xr-x 32 plynch staff 1.0K 2019-02-05 10:50:56.199786790 -0400 ./
      drwxr-xr-x  7 plynch staff  224 2019-02-05 10:50:56.292380717 -0400 ../
      -rwxr-xr-x  1 plynch staff 1.7M 2019-02-05 16:23:18.000000000 -0400 blobstore.rebuildComponentDB-20190204145802178.log*
      -rwxr-xr-x  1 plynch staff 1.7K 2019-02-05 16:23:18.000000000 -0400 blobstore.rebuildComponentDB-20190205160249009.log*
      -rwxr-xr-x  1 plynch staff 6.2K 2019-02-05 16:23:20.000000000 -0400 blobstore.rebuildComponentDB-20190205160646669.log*
      -rwxr-xr-x  1 plynch staff 1.8K 2019-02-05 16:23:20.000000000 -0400 create.browse.nodes-20190205135817413.log*
      -rwxr-xr-x  1 plynch staff  15K 2019-02-05 16:23:20.000000000 -0400 repository.docker.gc-20190206003000014.log*
      -rwxr-xr-x  1 plynch staff 2.6M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204100000012.log*
      -rwxr-xr-x  1 plynch staff 2.6M 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190204110000007.log*
      -rwxr-xr-x  1 plynch staff 2.6M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204120000008.log*
      -rwxr-xr-x  1 plynch staff  31M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204125844429.log*
      -rwxr-xr-x  1 plynch staff 2.6M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204130629593.log*
      -rwxr-xr-x  1 plynch staff  31M 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190204133030042.log*
      -rwxr-xr-x  1 plynch staff  31M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204135119440.log*
      -rwxr-xr-x  1 plynch staff 1.8M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204140000006.log*
      -rwxr-xr-x  1 plynch staff  31M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204140555928.log*
      -rwxr-xr-x  1 plynch staff  31M 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190204143021278.log*
      -rwxr-xr-x  1 plynch staff 2.6M 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190204150000008.log*
      -rwxr-xr-x  1 plynch staff  31M 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190204151035418.log*
      -rwxr-xr-x  1 plynch staff  43K 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190205154703578.log*
      -rwxr-xr-x  1 plynch staff  12K 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190205230000011.log*
      -rwxr-xr-x  1 plynch staff 314K 2019-02-05 16:23:20.000000000 -0400 repository.maven.rebuild-metadata-20190205230536794.log*
      -rwxr-xr-x  1 plynch staff 207K 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190206070000023.log*
      -rwxr-xr-x  1 plynch staff 118K 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190206090000011.log*
      -rwxr-xr-x  1 plynch staff 118K 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190206100000009.log*
      -rwxr-xr-x  1 plynch staff  15M 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190206102413551.log*
      -rwxr-xr-x  1 plynch staff 3.4M 2019-02-05 16:23:18.000000000 -0400 repository.maven.rebuild-metadata-20190206102519658.log*
      -rwxr-xr-x  1 plynch staff 8.6K 2019-02-05 16:23:18.000000000 -0400 repository.rebuild-index-20190205125047783.log*
      -rwxr-xr-x  1 plynch staff 8.6K 2019-02-05 16:23:18.000000000 -0400 repository.rebuild-index-20190205133701404.log*
      -rwxr-xr-x  1 plynch staff 8.6K 2019-02-05 16:23:18.000000000 -0400 repository.rebuild-index-20190205145700915.log*
      -rwxr-xr-x  1 plynch staff  22K 2019-02-05 16:23:20.000000000 -0400 repository.rebuild-index-20190205153406610.log*
      -rwxr-xr-x  1 plynch staff 1.1K 2019-02-05 16:23:18.000000000 -0400 repository.rebuild-index-20190205161836730.log*
      

      Example logging statement inside repository.maven.rebuild-metadata-20190206102519658.log :

      2019-02-06 10:25:19,658+0200 DEBUG [quartz-3-thread-8]  *SYSTEM org.sonatype.nexus.repository.maven.internal.hosted.MavenHostedFacetImpl - Rebuilding Maven2 hosted repository metadata: repository=maven-snapshots, update=false, g=, a=, bV=
      

      Expected

      Log files and logging statements should not have timestamps from the future.

        Attachments

          Activity

            People

            Assignee:
            Unassigned Unassigned
            Reporter:
            plynch Peter Lynch
            Last Updated By:
            Peter Lynch Peter Lynch
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved:

                tigCommentSecurity.panel-title