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

Missing 'connection accepted' entry in mongos log

    • Type: Icon: Bug Bug
    • Resolution: Done
    • Priority: Icon: Major - P3 Major - P3
    • None
    • Affects Version/s: 2.2.1
    • Component/s: Diagnostics, Logging, Sharding
    • None
    • Linux

      Trying to get to the bottom of PYTHON-482 where sockets block forever in read from a mongos on a sharded cluster. We came across an instance where the mongos log appears to be missing events.

      The mongos log we have contains these entries (no events have been chopped):

      Wed Mar  6 16:47:56 [conn4706]  authenticate db: admin { authenticate: 1, nonce: "616360f77e999eb6", user: "ahldev", key: "ca1a6a071af25060bae233a30e9c8e6d" }
      Wed Mar  6 16:48:00 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' acquired, ts : 5137733f7bb46eb6d5eed4f5
      Wed Mar  6 16:48:00 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' unlocked. 
      Wed Mar  6 16:48:18 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' acquired, ts : 513773527bb46eb6d5eed4f6
      Wed Mar  6 16:48:18 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' unlocked. 
      Wed Mar  6 16:48:24 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' acquired, ts : 513773587bb46eb6d5eed4f7
      Wed Mar  6 16:48:24 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' unlocked. 
      Wed Mar  6 16:48:41 [conn4706] end connection 127.0.0.1:54309 (2 connections now open)
      Wed Mar  6 16:48:54 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' acquired, ts : 513773767bb46eb6d5eed4f8
      Wed Mar  6 16:48:54 [Balancer] distributed lock 'balancer/dlonapahls200.maninvestments.com:27119:1362572650:1804289383' unlocked. 
      Wed Mar  6 16:49:08 [mongosMain] connection accepted from 127.0.0.1:54624 #4707 (3 connections now open)
      Wed Mar  6 16:49:08 [conn4707] end connection 127.0.0.1:54624 (2 connections now open)
      Wed Mar  6 16:49:08 [mongosMain] connection accepted from 127.0.0.1:54625 #4708 (3 connections now open)
      Wed Mar  6 16:49:08 [conn4708]  authenticate db: admin { authenticate: 1, nonce: "c9a6ca2e71b590a7", user: "ahldev", key: "2f864d0041a557ff0dfdae6a472d22d0" }
      

      Note there is no line: [mongosMain] connection accepted from x.x.x.x:y #4707

            Assignee:
            sam.helman@10gen.com sam.helman@10gen.com
            Reporter:
            jblackburn James Blackburn
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: