resmoke.py reports stale "Fixture status" message during fixture teardown

XMLWordPrintableJSON

    • Fully Compatible
    • ALL
    • v5.0
    • STM 2021-06-14
    • 2
    • None
    • 3
    • None
    • None
    • None
    • None
    • None
    • None

      The "Fixture status" prefix is cached before the test is run. However, hooks such as CleanEveryN and ContinuousStepdown may restart the MongoDB processes. This leads the teardown fixture test case to log the former pids of the MongoDB processes and can be confusing for Server engineers.

      [j2:rs0:n0] | 2021-03-24T17:14:08.089+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":27107,"port":20500,"dbPath":"/data/db/job2/rs0/node0","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [j2:rs0:n1] | 2021-03-24T17:14:09.516+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":27185,"port":20501,"dbPath":"/data/db/job2/rs0/node1","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [j2:rs0:n2] | 2021-03-24T17:14:09.656+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":27265,"port":20502,"dbPath":"/data/db/job2/rs0/node2","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [j2:rs1:n1] | 2021-03-24T17:14:30.891+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":31358,"port":20504,"dbPath":"/data/db/job2/rs1/node1","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [j2:rs1:n0] | 2021-03-24T17:14:30.465+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":31300,"port":20503,"dbPath":"/data/db/job2/rs1/node0","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [j2:rs1:n2] | 2021-03-24T17:14:33.182+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":31498,"port":20505,"dbPath":"/data/db/job2/rs1/node2","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [js_test:explain5] Fixture status:
      |ReplicaSetFixture:job2:rs0:node0|j2:rs0:n0|20500|27107|
      |ReplicaSetFixture:job2:rs0:node1|j2:rs0:n1|20501|27185|
      |ReplicaSetFixture:job2:rs0:node2|j2:rs0:n2|20502|27265|
      |ReplicaSetFixture:job2:rs1:node0|j2:rs1:n0|20503|31300|
      |ReplicaSetFixture:job2:rs1:node1|j2:rs1:n1|20504|31358|
      |ReplicaSetFixture:job2:rs1:node2|j2:rs1:n2|20505|31498|
      [ContinuousStepdown:job2] Fixture status:
      |ReplicaSetFixture:job2:rs0:node0|j2:rs0:n0|20500|2479 |
      |ReplicaSetFixture:job2:rs0:node1|j2:rs0:n1|20501|27185|
      |ReplicaSetFixture:job2:rs0:node2|j2:rs0:n2|20502|27265|
      |ReplicaSetFixture:job2:rs1:node0|j2:rs1:n0|20503|31300|
      |ReplicaSetFixture:job2:rs1:node1|j2:rs1:n1|20504|31358|
      |ReplicaSetFixture:job2:rs1:node2|j2:rs1:n2|20505|31498|
      [j2:rs0:n0] | 2021-03-24T17:14:47.600+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":2479,"port":20500,"dbPath":"/data/db/job2/rs0/node0","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [ContinuousStepdown:job2] Fixture status:
      |ReplicaSetFixture:job2:rs0:node0|j2:rs0:n0|20500|2479 |
      |ReplicaSetFixture:job2:rs0:node1|j2:rs0:n1|20501|27185|
      |ReplicaSetFixture:job2:rs0:node2|j2:rs0:n2|20502|6812 |
      |ReplicaSetFixture:job2:rs1:node0|j2:rs1:n0|20503|31300|
      |ReplicaSetFixture:job2:rs1:node1|j2:rs1:n1|20504|31358|
      |ReplicaSetFixture:job2:rs1:node2|j2:rs1:n2|20505|31498|
      [j2:rs0:n2] | 2021-03-24T17:15:18.748+00:00 I  CONTROL  4615611 [initandlisten] "MongoDB starting","attr":{"pid":6812,"port":20502,"dbPath":"/data/db/job2/rs0/node2","architecture":"64-bit","host":"ip-10-122-66-208.ec2.internal"}
      [js_test:elemmatch_object] Fixture status:
      |ReplicaSetFixture:job2:rs0:node0|j2:rs0:n0|20500|27107|
      |ReplicaSetFixture:job2:rs0:node1|j2:rs0:n1|20501|27185|
      |ReplicaSetFixture:job2:rs0:node2|j2:rs0:n2|20502|27265|
      |ReplicaSetFixture:job2:rs1:node0|j2:rs1:n0|20503|31300|
      |ReplicaSetFixture:job2:rs1:node1|j2:rs1:n1|20504|31358|
      |ReplicaSetFixture:job2:rs1:node2|j2:rs1:n2|20505|31498|
      [js_test:job2_fixture_teardown] Fixture status:
      |ReplicaSetFixture:job2:rs0:node0|j2:rs0:n0|20500|27107|
      |ReplicaSetFixture:job2:rs0:node1|j2:rs0:n1|20501|27185|
      |ReplicaSetFixture:job2:rs0:node2|j2:rs0:n2|20502|27265|
      |ReplicaSetFixture:job2:rs1:node0|j2:rs1:n0|20503|31300|
      |ReplicaSetFixture:job2:rs1:node1|j2:rs1:n1|20504|31358|
      |ReplicaSetFixture:job2:rs1:node2|j2:rs1:n2|20505|31498|
      

      https://logkeeper.mongodb.org/lobster/build/dd2650c233841c284457e4a5df2d76d1/all#bookmarks=0%2C47851%2C47852%2C47853%2C48185%2C48186%2C48187%2C54129%2C54130%2C54131%2C65990%2C65991%2C65992%2C78604%2C78605%2C78606%2C79341&f~=100~%28Fixture%20status%7C%5C%7CReplicaSetFixture%7CMongoDB%20starting%29&l=1

            Assignee:
            Robert Guo (Inactive)
            Reporter:
            Max Hirschhorn
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: