|
Curiously enough, looking at db_sorter_test appears to show that macOS might have an issue with disk I/O.
A test from before SERVER-65146 reveals that a particular test in there that does I/O was already taking dramatically longer on macOS than on Linux:
macOS
[cpp_unit_test:db_sorter_test] Starting C++ unit test build/install/bin/db_sorter_test...
|
...
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:08:36.913Z I TEST 23059 [main] "Running","attr":\{"test":"mongo::sorter::(anonymous namespace)::SorterTests::LotsOfDataLittleMemory<true>","rep":1,"reps":1}
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:08:36.935Z I TEST 23051 [main] "Created temporary directory: \{path}","attr":\{"path":"/data/mci/0f7dcf7494a9efcd981711b886943cfe/tmp/sorterTests-8d59-dfec-50be-6258"}
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:08:38.893Z I TEST 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:08:47.754Z I TEST 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:08:49.554Z I TEST 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:08:56.153Z I TEST 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:08:59.703Z I TEST 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:09:11.912Z I TEST 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:09:11.912Z I TEST 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-05-09T22:09:23.833Z I TEST 6033100 [main] "Finished merging spills"
|
...
|
|
Linux
[cpp_unit_test:db_sorter_test] Starting C++ unit test build/install/bin/db_sorter_test...
|
...
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:22.395Z I TEST 23059 [main] "Running","attr":\{"test":"mongo::sorter::(anonymous namespace)::SorterTests::LotsOfDataLittleMemory<false>","rep":1,"reps":1}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:22.396Z I TEST 23051 [main] "Created temporary directory: \{path}","attr":\{"path":"/data/mci/aa39de40af1186ef98332d6d8f8db64f/tmp/sorterTests-cbf6-48b2-477a-6a73"}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:22.427Z I - 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:22.592Z I - 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:22.671Z I - 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:22.835Z I - 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:22.939Z I - 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:23.105Z I - 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:23.106Z I - 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:23.273Z I - 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:23.469Z I - 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:23.713Z I - 6033100 [main] "Finished merging spills"
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:23.713Z I - 6033104 [main] "Number of spills exceeds maximum spills to merge at a time, proceeding to merge them to reduce the number","attr":\{"currentNumSpills":62,"maxNumSpills":2}
|
[cpp_unit_test:db_sorter_test] | 2022-06-12T05:51:23.917Z I - 6033100 [main] "Finished merging spills"
|
...
|
|
Maybe the issue was brought to light with the WT change due to going from in memory to disk, thus affecting everything with slow disk I/O.
|