Uploaded image for project: 'Core Server'
  1. Core Server
  2. SERVER-4709

Strange timing of operations reported in logs

    • Type: Icon: Question Question
    • Resolution: Cannot Reproduce
    • Priority: Icon: Major - P3 Major - P3
    • None
    • Affects Version/s: 2.0.2
    • Component/s: Logging
    • None

      While checking logs for SERVER-4100 causes (this case is unrelated, mentioned for information purposes only), I got some strange reconds on the primary:

      Sat Jan 14 02:15:37 [initandlisten] connection accepted from 10.2.1.200:48055 #108356
      Sat Jan 14 02:15:48 [conn108356] update models_stats.show_log query:

      { _id: ObjectId('4ebf7eb76b8dda061480d539') }

      update: { $unset:

      { h: 1 }

      , $set:

      { e: null, s: 1321172663 }

      } keyUpdates:1 1271310315ms
      Sat Jan 14 02:15:53 [conn108356] end connection 10.2.1.200:48055

      This part of log shows the update running for something around 14 days, but entire connection existed less than a minute, looks like there's something amiss.
      We have several (in the tens range) of such queries in logs with the same time (1271310315ms) on different connections with different operations.

        1. test.c
          2 kB
          Ben Becker

            Assignee:
            benjamin.becker Ben Becker
            Reporter:
            onyxmaster Aristarkh Zagorodnikov
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

              Created:
              Updated:
              Resolved: