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

max_message_size.js fails in auth passthrough suite

    • Type: Icon: Bug Bug
    • Resolution: Done
    • Priority: Icon: Major - P3 Major - P3
    • 2.4.0-rc0
    • Affects Version/s: 2.3.2
    • Component/s: None
    • Labels:
      None
    • ALL

      The first failing test case causes the connection to the database to close. The shell automatically reopens a new one, but because it does not cache authentication credentials, it cannot (and does not try to) reauthenticate. This stems from the shell not faithfully implementing the driver spec (the C++ driver doesn't, either).

            Test : max_message_size.js ...
            Command : /data/buildslaves/Linux_64bit_Subscription_Amazon_AMI_Nightly/mongo/mongo --port 27999 --authenticationMechanism CRAM-MD5 /data/buildslaves/Linux_64bit_Subscription_Amazon_AMI_Nightly/mongo/jstests/max_message_size.js --eval TestData = new Object();TestData.testPath = "/data/buildslaves/Linux_64bit_Subscription_Amazon_AMI_Nightly/mongo/jstests/max_message_size.js";TestData.testFile = "max_message_size.js";TestData.testName = "max_message_size";TestData.noJournal = false;TestData.noJournalPrealloc = false;TestData.auth = true;TestData.keyFile = "/data/buildslaves/Linux_64bit_Subscription_Amazon_AMI_Nightly/mongo/jstests/libs/authTestsKey";TestData.keyFileData = "Thiskeyisonlyforrunningthesuitewithauthenticationdontuseitinanytestsdirectly";jsTest.authenticate(db.getMongo());
               Date : Wed Jan 30 07:37:50 2013
      MongoDB shell version: 2.3.3-pre-
      connecting to: 127.0.0.1:27999/test
      Wed Jan 30 07:37:50.895 [initandlisten] connection accepted from 127.0.0.1:38012 #34 (1 connection now open)
      Authenticating to admin database as admin with mechanism CRAM-MD5 on connection: connection to 127.0.0.1:27999
      Wed Jan 30 07:37:50.898 [conn34] SASL: Successfully authenticated as principal: admin
      1
      Wed Jan 30 07:37:50.915 [conn34] CMD: drop test.max_message_size
      Wed Jan 30 07:37:51.754 [FileAllocator] allocating new datafile /data/db/sconsTests/test.2, filling with zeroes...
      Wed Jan 30 07:37:51.760 [FileAllocator] done allocating datafile /data/db/sconsTests/test.2, size: 256MB,  took 0.005 secs
      Wed Jan 30 07:37:51.760 [FileAllocator] allocating new datafile /data/db/sconsTests/test.3, filling with zeroes...
      Wed Jan 30 07:37:51.767 [FileAllocator] done allocating datafile /data/db/sconsTests/test.3, size: 512MB,  took 0.006 secs
      Wed Jan 30 07:37:51.767 [FileAllocator] allocating new datafile /data/db/sconsTests/test.4, filling with zeroes...
      Wed Jan 30 07:37:51.767 [conn34] build index test.max_message_size { _id: 1 }
      Wed Jan 30 07:37:51.768 [conn34] build index done.  scanned 0 total records. 0.001 secs
      Wed Jan 30 07:37:51.788 [FileAllocator] done allocating datafile /data/db/sconsTests/test.4, size: 1024MB,  took 0.021 secs
      Wed Jan 30 07:37:51.871 [conn34] insert test.max_message_size ninserted:3 keyUpdates:0 locks(micros) w:116953 116ms
      Wed Jan 30 07:37:51.968 [conn34] CMD: drop test.max_message_size
      Wed Jan 30 07:37:52.125 [conn34] command test.$cmd command: { drop: "max_message_size" } ntoreturn:1 keyUpdates:0 locks(micros) w:156561 reslen:128 156ms
      Wed Jan 30 07:37:52.575 [conn34] build index test.max_message_size { _id: 1 }
      Wed Jan 30 07:37:52.576 [conn34] build index done.  scanned 0 total records. 0 secs
      Wed Jan 30 07:37:52.723 [conn34] insert test.max_message_size ninserted:3 keyUpdates:0 locks(micros) w:147974 148ms
      Wed Jan 30 07:37:52.747 [conn34] CMD: drop test.max_message_size
      Wed Jan 30 07:37:53.130 [conn34] build index test.max_message_size { _id: 1 }
      Wed Jan 30 07:37:53.131 [conn34] build index done.  scanned 0 total records. 0 secs
      Wed Jan 30 07:37:53.154 [conn34] CMD: drop test.max_message_size
      Wed Jan 30 07:37:53.420 [conn34] recv(): message len 48000001 is too large. Max is 48000000
      Wed Jan 30 07:37:53.421 [conn34] end connection 127.0.0.1:38012 (0 connections now open)
      Wed Jan 30 07:37:53.421 Socket say send() errno:104 Connection reset by peer 127.0.0.1:27999
      Wed Jan 30 07:37:56.438 trying reconnect to 127.0.0.1:27999
      Wed Jan 30 07:37:56.439 [initandlisten] connection accepted from 127.0.0.1:38013 #35 (1 connection now open)
      Wed Jan 30 07:37:56.439 reconnect 127.0.0.1:27999 ok
      Wed Jan 30 07:37:56.439 [conn35] command denied: { count: "max_message_size", query: {}, fields: {} }
      Wed Jan 30 07:37:56.441 count failed: { "ok" : 0, "errmsg" : "unauthorized" } src/mongo/shell/query.js:180
      failed to load: /data/buildslaves/Linux_64bit_Subscription_Amazon_AMI_Nightly/mongo/jstests/max_message_size.js
      Wed Jan 30 07:37:56.482 [conn35] end connection 127.0.0.1:38013 (0 connections now open)
                       5648.1149 ms
      Wed Jan 30 07:37:56.483 got signal 15 (Terminated), will terminate after current cmd ends
      Wed Jan 30 07:37:56.483 [interruptThread] now exiting
      Wed Jan 30 07:37:56.483 dbexit: 
      Wed Jan 30 07:37:56.483 [interruptThread] shutdown: going to close listening sockets...
      Wed Jan 30 07:37:56.483 [interruptThread] closing listening socket: 9
      Wed Jan 30 07:37:56.483 [interruptThread] closing listening socket: 10
      Wed Jan 30 07:37:56.483 [interruptThread] closing listening socket: 11
      Wed Jan 30 07:37:56.483 [interruptThread] removing socket file: /tmp/mongodb-27999.sock
      Wed Jan 30 07:37:56.483 [interruptThread] shutdown: going to flush diaglog...
      Wed Jan 30 07:37:56.483 [interruptThread] shutdown: going to close sockets...
      Wed Jan 30 07:37:56.483 [interruptThread] shutdown: waiting for fs preallocator...
      Wed Jan 30 07:37:56.483 [interruptThread] shutdown: lock for final commit...
      Wed Jan 30 07:37:56.483 [interruptThread] shutdown: final commit...
      Wed Jan 30 07:37:57.557 [interruptThread] shutdown: closing all files...
      Wed Jan 30 07:37:57.572 [interruptThread] closeAllFiles() finished
      Wed Jan 30 07:37:57.572 [interruptThread] journalCleanup...
      Wed Jan 30 07:37:57.572 [interruptThread] removeJournalFiles
      Wed Jan 30 07:37:57.577 [interruptThread] shutdown: removing fs lock...
      Wed Jan 30 07:37:57.577 dbexit: really exiting now
      test /data/buildslaves/Linux_64bit_Subscription_Amazon_AMI_Nightly/mongo/jstests/max_message_size.js exited with status 253
      

            Assignee:
            spencer@mongodb.com Spencer Brody (Inactive)
            Reporter:
            schwerin@mongodb.com Andy Schwerin
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: