Information
This documents the experience we had at Cambridge migrating from legacy ContentHostingService to JCRContent (backed by JackRabbit JCR)
- Information
- Load test results from a dual-core laptop with 4 GB RAM
- Testing legacy CHS with DOM (no binary serialization or SAX) - like 2.4.0 and previous
- Testing legacy CHS with SAX (no binary serialization) - like 2.4.x
- Testing legacy CHS with binary serialization - like trunk/2.5.x
- Testing JCR CHS
- Summary
- Webdav test results from a dual-core laptop with 4 GB RAM
- Load tests of legacy CHS
- Load tests of JCR content hosting
Load test results from a dual-core laptop with 4 GB RAM
Testing legacy CHS with DOM (no binary serialization or SAX) - like 2.4.0 and previous
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-29 15:17:21,609 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor,afterPropertiesSet (211)) INFO: Cleanup any existing test data first (if there is any)... (2007-11-29 15:17:21,618 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (200)) INFO: Completed count of all current content items (6) in 2 ms (2007-11-29 15:17:22,358 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (217)) INFO: Current content size (6), Now we will create simulated content... (2007-11-29 15:17:22,359 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (220)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 584 ms (58.40 microsecs per operation) (2007-11-29 15:17:23,015 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 3773 ms (377.30 microsecs per operation) (2007-11-29 15:17:26,789 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 12433 ms (1,243.30 microsecs per operation) (2007-11-29 15:17:39,222 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 623 ms (62.30 microsecs per operation) (2007-11-29 15:17:39,846 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 429 ms (42.90 microsecs per operation) (2007-11-29 15:17:40,275 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 137 ms (13.70 microsecs per operation) (2007-11-29 15:17:40,413 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 63 ms (6.30 microsecs per operation) (2007-11-29 15:17:40,477 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of (7) collections with 556 content items in 18046 ms (32,456.83 microsecs per operation),average size of created items: 155224 bytes (2007-11-29 15:17:40,477 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (232)) INFO: Completed count of all current content items (570) in 10 ms (2007-11-29 15:17:40,488 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (244)) INFO: Test reading generated content and existing content... (2007-11-29 15:17:40,489 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (252)) INFO: Completed load of 556 content items in 789 ms (2007-11-29 15:17:41,280 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (265)) INFO: Test simulating usage (one thread)... (2007-11-29 15:17:41,280 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (273)) INFO: Thread 1: completed 10000 iterations with 500 inserts and 125 removes and 199940 reads (0 misses)in 307239 ms (30,723.90 microsecs per iteration) (2007-11-29 15:22:49,289 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: complete (2007-11-29 15:22:49,290 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (287)) INFO: Starting concurrent caching load test with 10 threads... (2007-11-29 15:22:50,224 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (310)) INFO: Thread 1: completed 1000 iterations with 50 inserts and 12 removes and 19940 reads (0 misses)in 194655 ms (194,655.00 microsecs per iteration) (2007-11-29 15:26:04,912 1_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: 10 threads completed 10000 iterations in 195077 ms (19,507.70 microsecs per iteration) (2007-11-29 15:26:05,302 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (324)) INFO: Test removing generated content... (2007-11-29 15:26:05,304 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (333)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 148 content items in 3032 ms (20,486.49 microsecs per item) (2007-11-29 15:26:08,336 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 191 content items in 3910 ms (20,471.20 microsecs per item) (2007-11-29 15:26:12,246 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 414 content items in 8862 ms (21,405.80 microsecs per item) (2007-11-29 15:26:21,108 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 136 content items in 2752 ms (20,235.29 microsecs per item) (2007-11-29 15:26:23,861 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 175 content items in 3607 ms (20,611.43 microsecs per item) (2007-11-29 15:26:27,469 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 124 content items in 2434 ms (19,629.03 microsecs per item) (2007-11-29 15:26:29,904 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 123 content items in 2451 ms (19,926.83 microsecs per item) (2007-11-29 15:26:32,355 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of (7) created collections with 1311 content items in 27051 ms (20,633.87 microsecs per operation) (2007-11-29 15:26:32,355 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (341)) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-29 15:26:32,356 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils,executeSpringTests (141))
Testing legacy CHS with SAX (no binary serialization) - like 2.4.x
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-29 14:52:14,277 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor,afterPropertiesSet (211)) INFO: Cleanup any existing test data first (if there is any)... (2007-11-29 14:52:14,287 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (200)) INFO: Completed count of all current content items (6) in 3 ms (2007-11-29 14:52:15,020 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (217)) INFO: Current content size (6), Now we will create simulated content... (2007-11-29 14:52:15,020 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (220)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 514 ms (51.40 microsecs per operation) (2007-11-29 14:52:15,621 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 3976 ms (397.60 microsecs per operation) (2007-11-29 14:52:19,598 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 12413 ms (1,241.30 microsecs per operation) (2007-11-29 14:52:32,011 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 674 ms (67.40 microsecs per operation) (2007-11-29 14:52:32,686 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 381 ms (38.10 microsecs per operation) (2007-11-29 14:52:33,067 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 152 ms (15.20 microsecs per operation) (2007-11-29 14:52:33,220 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 59 ms (5.90 microsecs per operation) (2007-11-29 14:52:33,280 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of (7) collections with 556 content items in 18174 ms (32,687.05 microsecs per operation),average size of created items: 151659 bytes (2007-11-29 14:52:33,281 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (232)) INFO: Completed count of all current content items (570) in 13 ms (2007-11-29 14:52:33,295 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (244)) INFO: Test reading generated content and existing content... (2007-11-29 14:52:33,296 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (252)) INFO: Completed load of 556 content items in 564 ms (2007-11-29 14:52:33,860 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (265)) INFO: Test simulating usage (one thread)... (2007-11-29 14:52:33,861 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (273)) INFO: Thread 1: completed 10000 iterations with 500 inserts and 125 removes and 199940 reads (0 misses)in 214863 ms (21,486.30 microsecs per iteration) (2007-11-29 14:56:09,381 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: complete (2007-11-29 14:56:09,382 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (287)) INFO: Starting concurrent caching load test with 10 threads... (2007-11-29 14:56:09,964 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (310)) INFO: Thread 1: completed 1000 iterations with 50 inserts and 12 removes and 19940 reads (0 misses)in 124760 ms (124,760.00 microsecs per iteration) (2007-11-29 14:58:14,757 1_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: 10 threads completed 10000 iterations in 126047 ms (12,604.70 microsecs per iteration) (2007-11-29 14:58:16,012 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (324)) INFO: Test removing generated content... (2007-11-29 14:58:16,013 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (333)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 124 content items in 2457 ms (19,814.52 microsecs per item) (2007-11-29 14:58:18,471 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 203 content items in 3701 ms (18,231.53 microsecs per item) (2007-11-29 14:58:22,173 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 440 content items in 8529 ms (19,384.09 microsecs per item) (2007-11-29 14:58:30,703 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 140 content items in 2741 ms (19,578.57 microsecs per item) (2007-11-29 14:58:33,444 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 150 content items in 2761 ms (18,406.67 microsecs per item) (2007-11-29 14:58:36,205 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 135 content items in 2463 ms (18,244.44 microsecs per item) (2007-11-29 14:58:38,669 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 119 content items in 2122 ms (17,831.93 microsecs per item) (2007-11-29 14:58:40,792 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of (7) created collections with 1311 content items in 24778 ms (18,900.08 microsecs per operation) (2007-11-29 14:58:40,792 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (341)) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-29 14:58:40,793 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils,executeSpringTests (141))
Testing legacy CHS with binary serialization - like trunk/2.5.x
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-29 17:17:42,193 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor,afterPropertiesSet (211)) INFO: Cleanup any existing test data first (if there is any)... (2007-11-29 17:17:42,202 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (200)) INFO: Completed count of all current content items (517) in 13 ms (2007-11-29 17:17:42,939 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (217)) INFO: Current content size (517), Now we will create simulated content... (2007-11-29 17:17:42,940 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (220)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 180 ms (18.00 microsecs per operation) (2007-11-29 17:17:43,130 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 2051 ms (205.10 microsecs per operation) (2007-11-29 17:17:45,182 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 8296 ms (829.60 microsecs per operation) (2007-11-29 17:17:53,478 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 416 ms (41.60 microsecs per operation) (2007-11-29 17:17:53,895 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 348 ms (34.80 microsecs per operation) (2007-11-29 17:17:54,244 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 94 ms (9.40 microsecs per operation) (2007-11-29 17:17:54,340 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 20 ms (2.00 microsecs per operation) (2007-11-29 17:17:54,361 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of (7) collections with 556 content items in 11412 ms (20,525.18 microsecs per operation),average size of created items: 148622 bytes (2007-11-29 17:17:54,361 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (232)) INFO: Completed count of all current content items (1081) in 16 ms (2007-11-29 17:17:54,378 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (244)) INFO: Test reading generated content and existing content... (2007-11-29 17:17:54,379 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (252)) INFO: Completed load of 914 content items in 412 ms (2007-11-29 17:17:54,792 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (265)) INFO: Test simulating usage (one thread)... (2007-11-29 17:17:54,793 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (273)) INFO: Thread 1: completed 10000 iterations with 500 inserts and 125 removes and 199940 reads (0 misses)in 97643 ms (9,764.30 microsecs per iteration) (2007-11-29 17:19:32,778 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: complete (2007-11-29 17:19:32,779 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (287)) INFO: Starting concurrent caching load test with 10 threads... (2007-11-29 17:19:33,153 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (310)) INFO: Preparing to send the mail digests for 20071129000000000]20071130000000000 (2007-11-29 17:20:26,653 Timer-2_org.sakaiproject.email.impl.BasicEmailService,sendDigests (272)) INFO: Thread 1: completed 1000 iterations with 50 inserts and 12 removes and 19940 reads (0 misses)in 73211 ms (73,211.00 microsecs per iteration) (2007-11-29 17:20:46,386 1_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: 10 threads completed 10000 iterations in 75038 ms (7,503.80 microsecs per iteration) (2007-11-29 17:20:48,191 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (324)) INFO: Test removing generated content... (2007-11-29 17:20:48,192 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (333)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 127 content items in 2166 ms (17,055.12 microsecs per item) (2007-11-29 17:20:50,359 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 205 content items in 4373 ms (21,331.71 microsecs per item) (2007-11-29 17:20:54,733 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 443 content items in 7672 ms (17,318.28 microsecs per item) (2007-11-29 17:21:02,406 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 135 content items in 2338 ms (17,318.52 microsecs per item) (2007-11-29 17:21:04,745 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 119 content items in 2285 ms (19,201.68 microsecs per item) (2007-11-29 17:21:07,030 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 145 content items in 2522 ms (17,393.10 microsecs per item) (2007-11-29 17:21:09,552 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 137 content items in 2541 ms (18,547.45 microsecs per item) (2007-11-29 17:21:12,442 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of (7) created collections with 1311 content items in 24249 ms (18,496.57 microsecs per operation) (2007-11-29 17:21:12,442 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (341)) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-29 17:21:12,443 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils,executeSpringTests (141))
Testing JCR CHS
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-29 17:35:38,871 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor,afterPropertiesSet (211)) INFO: Cleanup any existing test data first (if there is any)... (2007-11-29 17:35:38,881 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (200)) INFO: Completed count of all current content items (835898687) in 6988 ms (2007-11-29 17:35:45,873 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (217)) INFO: Current content size (835898687), Now we will create simulated content... (2007-11-29 17:35:45,874 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (220)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 934 ms (93.40 microsecs per operation) (2007-11-29 17:35:46,990 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 9359 ms (935.90 microsecs per operation) (2007-11-29 17:35:56,350 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 34713 ms (3,471.30 microsecs per operation) (2007-11-29 17:36:31,064 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 2112 ms (211.20 microsecs per operation) (2007-11-29 17:36:33,176 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 1114 ms (111.40 microsecs per operation) (2007-11-29 17:36:34,290 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 449 ms (44.90 microsecs per operation) (2007-11-29 17:36:34,740 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 126 ms (12.60 microsecs per operation) (2007-11-29 17:36:34,867 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of (7) collections with 556 content items in 48812 ms (87,791.37 microsecs per operation),average size of created items: 154684 bytes (2007-11-29 17:36:34,868 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (232)) INFO: Completed count of all current content items (456758862) in 195 ms (2007-11-29 17:36:35,063 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (244)) INFO: Test reading generated content and existing content... (2007-11-29 17:36:35,064 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (252)) INFO: Completed load of 1272 content items in 27 ms (2007-11-29 17:36:35,092 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (265)) INFO: Test simulating usage (one thread)... (2007-11-29 17:36:35,154 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (273)) INFO: Thread 1: completed 10000 iterations with 500 inserts and 125 removes and 199940 reads (0 misses)in 202329 ms (20,232.90 microsecs per iteration) (2007-11-29 17:40:06,513 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: complete (2007-11-29 17:40:06,514 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (287)) INFO: Starting concurrent caching load test with 10 threads... (2007-11-29 17:40:11,878 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (310)) INFO: Thread 1: completed 1000 iterations with 50 inserts and 12 removes and 19940 reads (0 misses)in 173021 ms (173,021.00 microsecs per iteration) (2007-11-29 17:43:04,923 1_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: 10 threads completed 10000 iterations in 210512 ms (21,051.20 microsecs per iteration) (2007-11-29 17:43:42,391 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (324)) INFO: Test removing generated content... (2007-11-29 17:43:42,392 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (333)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 82 content items in 5937 ms (72,402.44 microsecs per item) (2007-11-29 17:43:48,330 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 169 content items in 15854 ms (93,810.65 microsecs per item) (2007-11-29 17:44:04,184 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 469 content items in 63980 ms (136,417.91 microsecs per item) (2007-11-29 17:45:08,165 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 99 content items in 26193 ms (264,575.76 microsecs per item) (2007-11-29 17:45:34,359 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 89 content items in 32930 ms (370,000.00 microsecs per item) (2007-11-29 17:46:07,290 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 74 content items in 31011 ms (419,067.57 microsecs per item) (2007-11-29 17:46:38,301 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 74 content items in 34109 ms (460,932.43 microsecs per item) (2007-11-29 17:47:12,410 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of (7) created collections with 1056 content items in 210019 ms (198,881.63 microsecs per operation) (2007-11-29 17:47:12,411 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (341)) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-29 17:47:12,412 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils,executeSpringTests (141))
Summary
| DOM | SAX | Binary | JCR | |
|---|---|---|---|---|
| Insert | 18046 | 18174 | 11412 | 48812 |
| Simulation | 307239 | 214863 | 97643 | 202329 |
| Multithread | 195077 | 126047 | 75038 | 210512 |
| Delete | 27051 | 24778 | 24249 | 210019 |
Webdav test results from a dual-core laptop with 4 GB RAM
For these tests I used 4.3 MB of files with 152 directories and 358 files (average size 12.4 K)
Sakai was restarted each time and the database was wiped between each test. A new site was created and then a webdav connection was opened to the site so that the files and directories could be uploaded.
| Content Hosting Implementation | Seconds to upload |
|---|---|
| DOM XML | 41 |
| Saxalizer XML | 46 |
| Binary Serialization | 35 |
| JCR | 102 |
Load tests of legacy CHS
Load test results from a dual-core laptop with 4 GB RAM
Using legacy content hosting trunk (as of 26 Nov 2007)
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-28 19:16:31,242 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor,afterPropertiesSet (211)) INFO: Cleanup any existing test data first (if there is any)... (2007-11-28 19:16:31,249 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (200)) INFO: Completed count of all current content items (7) in 2 ms (2007-11-28 19:16:32,011 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (217)) INFO: Current content size (7), Now we will create simulated content... (2007-11-28 19:16:32,011 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (220)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 207 ms (20.70 microsecs per operation) (2007-11-28 19:16:32,255 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 2742 ms (274.20 microsecs per operation) (2007-11-28 19:16:34,999 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 8090 ms (809.00 microsecs per operation) (2007-11-28 19:16:43,090 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 500 ms (50.00 microsecs per operation) (2007-11-28 19:16:43,591 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 331 ms (33.10 microsecs per operation) (2007-11-28 19:16:43,923 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 147 ms (14.70 microsecs per operation) (2007-11-28 19:16:44,071 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 30 ms (3.00 microsecs per operation) (2007-11-28 19:16:44,102 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of (7) collections with 556 content items in 12055 ms (21,681.65 microsecs per operation),average size of created items: 150124 bytes (2007-11-28 19:16:44,103 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (232)) INFO: Completed count of all current content items (570) in 10 ms (2007-11-28 19:16:44,113 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (244)) INFO: Test reading generated content and existing content... (2007-11-28 19:16:44,114 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (252)) INFO: Completed load of 556 content items in 62 ms (2007-11-28 19:16:44,177 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (265)) INFO: Test simulating usage (one thread)... (2007-11-28 19:17:02,285 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (273)) INFO: Thread 1: completed 10000 iterations with 500 inserts and 125 removes and 199940 reads (0 misses)in 100606 ms (10,060.60 microsecs per iteration) (2007-11-28 19:18:42,952 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: complete (2007-11-28 19:18:42,953 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (287)) INFO: Starting concurrent caching load test with 10 threads... (2007-11-28 19:18:43,039 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (310)) INFO: Thread 1: completed 1000 iterations with 50 inserts and 12 removes and 19940 reads (0 misses)in 74163 ms (74,163.00 microsecs per iteration) (2007-11-28 19:19:57,226 1_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: 10 threads completed 10000 iterations in 75048 ms (7,504.80 microsecs per iteration) (2007-11-28 19:19:58,088 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (324)) INFO: Test removing generated content... (2007-11-28 19:19:58,090 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (333)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 140 content items in 2562 ms (18,300.00 microsecs per item) (2007-11-28 19:20:00,653 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 190 content items in 3305 ms (17,394.74 microsecs per item) (2007-11-28 19:20:03,958 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 429 content items in 7470 ms (17,412.59 microsecs per item) (2007-11-28 19:20:11,428 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 148 content items in 2970 ms (20,067.57 microsecs per item) (2007-11-28 19:20:14,398 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 148 content items in 2952 ms (19,945.95 microsecs per item) (2007-11-28 19:20:17,351 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 125 content items in 2336 ms (18,688.00 microsecs per item) (2007-11-28 19:20:19,688 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 131 content items in 2288 ms (17,465.65 microsecs per item) (2007-11-28 19:20:21,977 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of (7) created collections with 1311 content items in 23886 ms (18,219.68 microsecs per operation) (2007-11-28 19:20:21,977 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (341)) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-28 19:20:21,977 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils,executeSpringTests (141))
Load test results from the 4 CPU QA VM with 2 GB ram
Using legacy content hosting 2.4.x (as of 20 Nov 2007)
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-28 19:36:13,008 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor) INFO: Completed count of all current content items (167427) in 1889 ms (2007-11-28 19:36:47,318 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Current content size (167427), Now we will create simulated content... (2007-11-28 19:36:47,319 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 1829 ms (182.90 microsecs per operation) (2007-11-28 19:36:49,166 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 17014 ms (1,701.40 microsecs per operation) (2007-11-28 19:37:06,180 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 66471 ms (6,647.10 microsecs per operation) (2007-11-28 19:38:12,651 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 4301 ms (430.10 microsecs per operation) (2007-11-28 19:38:16,952 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 2394 ms (239.40 microsecs per operation) (2007-11-28 19:38:19,346 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 881 ms (88.10 microsecs per operation) (2007-11-28 19:38:20,227 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 230 ms (23.00 microsecs per operation) (2007-11-28 19:38:20,457 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of (7) collections with 556 content items in 93120 ms (167,482.01 microsecs per operation),average size of created items: 148677 bytes (2007-11-28 19:38:20,457 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed count of all current content items (167990) in 1833 ms (2007-11-28 19:38:22,290 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Test reading generated content and existing content... (2007-11-28 19:38:22,307 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed load of 10000 content items in 312444 ms (2007-11-28 19:43:34,751 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Test simulating usage (one thread)... (2007-11-28 19:43:34,849 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Thread 1: completed 10000 iterations with 500 inserts and 79 removes and 199940 reads (26 misses)in 554595 ms (55,459.50 microsecs per iteration) (2007-11-28 19:57:44,662 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: complete (2007-11-28 19:57:44,662 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Starting concurrent caching load test with 10 threads... (2007-11-28 20:02:36,476 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Thread 1: completed 1000 iterations with 50 inserts and 9 removes and 19940 reads (3 misses)in 311449 ms (311,449.00 microsecs per iteration) (2007-11-28 20:07:48,290 1_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: 10 threads completed 10000 iterations in 338083 ms (33,808.30 microsecs per iteration) (2007-11-28 20:08:14,560 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Test removing generated content... (2007-11-28 20:08:14,578 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 134 content items in 30754 ms (229,507.46 microsecs per item) (2007-11-28 20:08:45,333 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 216 content items in 47020 ms (217,685.19 microsecs per item) (2007-11-28 20:09:32,353 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 523 content items in 123043 ms (235,263.86 microsecs per item) (2007-11-28 20:11:35,397 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 140 content items in 32109 ms (229,350.00 microsecs per item) (2007-11-28 20:12:07,506 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 132 content items in 31155 ms (236,022.73 microsecs per item) (2007-11-28 20:12:38,661 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 123 content items in 27033 ms (219,780.49 microsecs per item) (2007-11-28 20:13:05,694 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 121 content items in 27170 ms (224,545.45 microsecs per item) (2007-11-28 20:13:32,864 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of (7) created collections with 1389 content items in 318286 ms (229,147.59 microsecs per operation) (2007-11-28 20:13:32,864 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-28 20:13:32,872 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils)
Load tests of JCR content hosting
Load test results from a dual-core laptop with 4 GB RAM
Using legacy content hosting trunk (as of 26 Nov 2007)
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-28 21:13:23,094 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor,afterPropertiesSet (211)) INFO: Cleanup any existing test data first (if there is any)... (2007-11-28 21:13:23,106 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (200)) INFO: Completed count of all current content items (63) in 62 ms (2007-11-28 21:13:23,198 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (217)) INFO: Current content size (63), Now we will create simulated content... (2007-11-28 21:13:23,199 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (220)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 2052 ms (205.20 microsecs per operation) (2007-11-28 21:13:25,354 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 14064 ms (1,406.40 microsecs per operation) (2007-11-28 21:13:39,419 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 38737 ms (3,873.70 microsecs per operation) (2007-11-28 21:14:18,157 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 2295 ms (229.50 microsecs per operation) (2007-11-28 21:14:20,453 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 1424 ms (142.40 microsecs per operation) (2007-11-28 21:14:21,878 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 444 ms (44.40 microsecs per operation) (2007-11-28 21:14:22,323 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 165 ms (16.50 microsecs per operation) (2007-11-28 21:14:22,489 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,makeAndFillCollection (494)) INFO: Completed creation of (7) collections with 556 content items in 59189 ms (106,455.04 microsecs per operation),average size of created items: 151192 bytes (2007-11-28 21:14:22,490 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (232)) INFO: Completed count of all current content items (18766) in 3216 ms (2007-11-28 21:14:25,707 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testCreateLargeContentSet (244)) INFO: Test reading generated content and existing content... (2007-11-28 21:14:25,708 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (252)) INFO: Completed load of 556 content items in 23 ms (2007-11-28 21:14:25,731 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testReadLargeContentSet (265)) INFO: Test simulating usage (one thread)... (2007-11-28 21:14:25,862 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (273)) INFO: Thread 1: completed 10000 iterations with 500 inserts and 125 removes and 199940 reads (0 misses)in 205343 ms (20,534.30 microsecs per iteration) (2007-11-28 21:17:51,659 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: complete (2007-11-28 21:17:51,660 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageOneThread (287)) INFO: Starting concurrent caching load test with 10 threads... (2007-11-28 21:17:54,033 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (310)) INFO: Thread 1: completed 1000 iterations with 50 inserts and 12 removes and 19940 reads (0 misses)in 201033 ms (201,033.00 microsecs per iteration) (2007-11-28 21:21:15,089 1_org.sakaiproject.content.test.LoadTestContentHostingService,runTestThread (425)) INFO: 10 threads completed 10000 iterations in 225176 ms (22,517.60 microsecs per iteration) (2007-11-28 21:21:39,210 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testSimulatedUsageMultiThread (324)) INFO: Test removing generated content... (2007-11-28 21:21:39,211 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (333)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 86 content items in 5740 ms (66,744.19 microsecs per item) (2007-11-28 21:21:44,953 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 159 content items in 15639 ms (98,358.49 microsecs per item) (2007-11-28 21:22:00,592 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 470 content items in 70871 ms (150,789.36 microsecs per item) (2007-11-28 21:23:11,464 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 98 content items in 34840 ms (355,510.20 microsecs per item) (2007-11-28 21:23:46,304 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 89 content items in 34187 ms (384,123.60 microsecs per item) (2007-11-28 21:24:20,492 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 65 content items in 34176 ms (525,784.62 microsecs per item) (2007-11-28 21:24:54,668 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 89 content items in 42877 ms (481,764.04 microsecs per item) (2007-11-28 21:25:37,546 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,removeCollection (449)) INFO: Completed removal of (7) created collections with 1056 content items in 238335 ms (225,696.02 microsecs per operation) (2007-11-28 21:25:37,547 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService,testRemoveLargeContentSet (341)) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-28 21:25:37,548 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils,executeSpringTests (141))
Load test results from the 4 CPU QA VM with 2 GB ram
Using legacy content hosting 2.4.x (as of 20 Nov 2007)
Load test results from our camtools-jcr-qa sluggish virtual machine. JCR CHS Implementation
November 28th SWG
INFO: Executing immediate tests for org.sakaiproject.content.test.LoadTestContentHostingService (2007-11-29 00:26:11,011 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.util.TestExecutor) INFO: Cleanup any existing test data first (if there is any)... (2007-11-29 00:26:11,047 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed count of all current content items (95) in 175 ms (2007-11-29 00:26:11,225 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Current content size (95), Now we will create simulated content... (2007-11-29 00:26:11,226 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 10 content items in 6128 ms (612.80 microsecs per operation) (2007-11-29 00:26:17,621 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) WARN: run(): ghost-busting server: steveVMqa-1196294980811 from : steveVMqa-1196295586485 (2007-11-29 00:26:44,890 SakaiClusterService.Maintenance_org.sakaiproject.cluster.impl.SakaiClusterService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 100 content items in 45920 ms (4,592.00 microsecs per operation) (2007-11-29 00:27:03,542 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 400 content items in 164229 ms (16,422.90 microsecs per operation) (2007-11-29 00:29:47,771 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 25 content items in 10948 ms (1,094.80 microsecs per operation) (2007-11-29 00:29:58,719 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 15 content items in 7123 ms (712.30 microsecs per operation) (2007-11-29 00:30:05,842 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 5 content items in 1863 ms (186.30 microsecs per operation) (2007-11-29 00:30:07,705 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 1 content items in 621 ms (62.10 microsecs per operation) (2007-11-29 00:30:08,326 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed creation of (7) collections with 556 content items in 236833 ms (425,958.63 microsecs per operation),average size of created items: 153522 bytes (2007-11-29 00:30:08,326 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed count of all current content items (22894) in 34783 ms (2007-11-29 00:30:43,110 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Test reading generated content and existing content... (2007-11-29 00:30:43,350 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed load of 556 content items in 5 ms (2007-11-29 00:30:43,355 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Test simulating usage (one thread)... (2007-11-29 00:30:43,449 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Thread 1: completed 10000 iterations with 500 inserts and 125 removes and 199940 reads (0 misses)in 4049309 ms (404,930.90 microsecs per iteration) (2007-11-29 01:38:40,162 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: complete (2007-11-29 01:38:40,162 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Starting concurrent caching load test with 10 threads... (2007-11-29 01:39:20,256 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Thread 1: completed 1000 iterations with 50 inserts and 12 removes and 19940 reads (0 misses)in 3562626 ms (3,562,626.00 microsecs per iteration) (2007-11-29 02:38:44,464 1_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: 10 threads completed 10000 iterations in 4890473 ms (489,047.30 microsecs per iteration) (2007-11-29 03:00:50,729 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Test removing generated content... (2007-11-29 03:00:50,841 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_small/) with 95 content items in 18176 ms (191,326.32 microsecs per item) (2007-11-29 03:01:09,017 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_big/) with 160 content items in 36222 ms (226,387.50 microsecs per item) (2007-11-29 03:01:45,239 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_huge/) with 455 content items in 139256 ms (306,057.14 microsecs per item) (2007-11-29 03:04:04,495 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_large/) with 87 content items in 40940 ms (470,574.71 microsecs per item) (2007-11-29 03:04:45,435 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_average/) with 89 content items in 48736 ms (547,595.51 microsecs per item) (2007-11-29 03:05:34,171 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_verysmall/) with 77 content items in 46703 ms (606,532.47 microsecs per item) (2007-11-29 03:06:20,875 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of collection (/LoadTestFolder/LOAD_TEST_ID_PREFIX_AZ_CHS_collection_tiny/) with 93 content items in 60397 ms (649,430.11 microsecs per item) (2007-11-29 03:07:21,273 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: Completed removal of (7) created collections with 1056 content items in 390433 ms (369,728.22 microsecs per operation) (2007-11-29 03:07:21,274 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.content.test.LoadTestContentHostingService) INFO: TESTING LoadTestContentHostingService: Tests passed : All 6 test(s) passed (2007-11-29 03:07:21,365 ContainerBackgroundProcessor[StandardEngine[SAKDEV:Catalina]]_org.sakaiproject.testrunner.utils.TestRunnerUtils)