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

Strange timing of operations reported in logs

    Details

    • Type: Question
    • Status: Closed
    • Priority: Major - P3
    • Resolution: Cannot Reproduce
    • Affects Version/s: 2.0.2
    • Fix Version/s: None
    • Component/s: Logging
    • Labels:
      None

      Description

      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.

        Attachments

          Issue Links

            Activity

              People

              • Votes:
                0 Vote for this issue
                Watchers:
                2 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: