After upgrading from 1.8 to 2.04 - authenticate errors being logged

17 views
Skip to first unread message

hiyall

unread,
May 9, 2012, 2:53:02 PM5/9/12
to mongodb-user
Wed May 9 14:48:11 [conn241] authenticate: { authenticate: 1, nonce:
"b489d14f5732414", user: "__system", key:
"7a6ad96f3a481312fe03e9ed5bf59ae5" }


Any assistance appreciated.

hiyall

unread,
May 9, 2012, 3:37:19 PM5/9/12
to mongodb-user
PRIMARY> rs.status()
{
"set" : "UCS",
"date" : ISODate("2012-05-09T19:36:27Z"),
"myState" : 1,
"members" : [
{
"_id" : 0,
"name" : "s605202la1sl78.uslsan6.savvis.net:
8100",
"health" : 1,
"state" : 1,
"stateStr" : "PRIMARY",
"optime" : {
"t" : 1336591192000,
"i" : 2
},
"optimeDate" :
ISODate("2012-05-09T19:19:52Z"),
"self" : true
},
{
"_id" : 1,
"name" : "s605202la1sl79.uslsan6.savvis.net:
8200",
"health" : 1,
"state" : 2,
"stateStr" : "SECONDARY",
"uptime" : 6030,
"optime" : {
"t" : 1336591192000,
"i" : 2
},
"optimeDate" :
ISODate("2012-05-09T19:19:52Z"),
"lastHeartbeat" :
ISODate("2012-05-09T19:36:25Z"),
"pingMs" : 0
},
{
"_id" : 2,
"name" : "s605202la1sl78.uslsan6.savvis.net:
8300",
"health" : 1,
"state" : 7,
"stateStr" : "ARBITER",
"uptime" : 6001,
"optime" : {
"t" : 0,
"i" : 0
},
"optimeDate" :
ISODate("1970-01-01T00:00:00Z"),
"lastHeartbeat" :
ISODate("2012-05-09T19:36:26Z"),
"pingMs" : 0
}
],
"ok" : 1
}

PRIMARY> db.adminCommand({"getCmdLineOpts":1})
{
"argv" : [
"./mongod",
"-f",
"/data/timeinc/servers/mongodb/ucslaq_r1/mongodb.conf"
],
"parsed" : {
"config" : "/data/timeinc/servers/mongodb/ucslaq_r1/
mongodb.conf",
"dbpath" : "/data/timeinc/servers/mongodb/ucslaq_r1/
data",
"fork" : "true",
"journal" : "true",
"keyFile" : "/data/timeinc/servers/mongodb/ucslaq_r1/
mongodb.key",
"logappend" : "true",
"logpath" : "/data/timeinc/servers/mongodb/ucslaq_r1/
log/mongodb.log",
"maxConns" : 100,
"nohttpinterface" : "true",
"nounixsocket" : "true",
"nssize" : 16,
"oplogSize" : 128,
"pidfilepath" : "/data/timeinc/servers/mongodb/
ucslaq_r1/mongodb.pid",
"port" : 8100,
"quiet" : "true",
"replSet" : "UCS"
},
"ok" : 1
}

SECONDARY> db.adminCommand({"getCmdLineOpts":1})
{
"argv" : [
"./mongod",
"-f",
"/data/timeinc/servers/mongodb/ucslaq_r2/mongodb.conf"
],
"parsed" : {
"config" : "/data/timeinc/servers/mongodb/ucslaq_r2/
mongodb.conf",
"dbpath" : "/data/timeinc/servers/mongodb/ucslaq_r2/
data",
"fork" : "true",
"journal" : "true",
"keyFile" : "/data/timeinc/servers/mongodb/ucslaq_r2/
mongodb.key",
"logappend" : "true",
"logpath" : "/data/timeinc/servers/mongodb/ucslaq_r2/
log/mongodb.log",
"maxConns" : 100,
"nohttpinterface" : "true",
"nounixsocket" : "true",
"nssize" : 16,
"oplogSize" : 128,
"pidfilepath" : "/data/timeinc/servers/mongodb/
ucslaq_r2/mongodb.pid",
"port" : 8200,
"quiet" : "true",
"replSet" : "UCS"
},
"ok" : 1

Spencer T Brody

unread,
May 9, 2012, 3:45:50 PM5/9/12
to mongod...@googlegroups.com
That's a normal expected message.  We log whenever a connection is authenticated, and if you're using authentication with replica sets then the set members have to authenticate communication to each other.  Internal authentication is done using the user __system, so that's what's causing these messages to appear.

Rohan Sahgal

unread,
May 15, 2012, 3:29:48 AM5/15/12
to mongod...@googlegroups.com
If this is normal behavior then do you think it makes sense to log this at the default log levels?

I am seeing a similar message in my logs, almost every 10 seconds.

Do the replica set members authenticate that often?

Rob LaRubbio

unread,
May 15, 2012, 9:12:54 AM5/15/12
to mongod...@googlegroups.com
We were seeing the same issue so I submitted this pull request to change the log level on that message


I probably should have opened a ticket with them as well so the pull request gets more visibility.  If you want to do that it would be great.

-Rob

Spencer T Brody

unread,
May 15, 2012, 11:10:42 AM5/15/12
to mongod...@googlegroups.com
I think we probably still want to log real authentication requests from the user at the normal level, but the authentication done internally could probably be logged at a higher level.  I created SERVER-5847 for that.

--
You received this message because you are subscribed to the Google
Groups "mongodb-user" group.
To post to this group, send email to mongod...@googlegroups.com
To unsubscribe from this group, send email to

Reply all
Reply to author
Forward
0 new messages