I have been comparing the performance of Railo Vs Lucee after a test
migration of the Mura CMS. Lucee on the whole is running slower with
certain parts executing up to 10x slower than Railo.
I posted on the MuraCMS Group, and I may not be the only one:
https://groups.google.com/forum/#!topic/mura-cms-developers/wICxQnkuatE
https://groups.google.com/forum/#!topic/mura-cms-developers/fXBgLQQ_ajE
An Example of the impact can be seen when comparing the execution time of a
specific section of code; see tick count traces below:
Railo - Instantiating a category Bean: Total 2ms
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,“”,“resolveBeanCreate[findSetters]:
categoryBean tickCount: 1 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”“,“resolveBeanCreate[findSetters]:
CATEGORYMANAGER tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,””,“resolveBeanCreate[loopMeta]:
CATEGORYMANAGER tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”“,“resolveBeanCreate[Done]:
CATEGORYMANAGER tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,””,“resolveBeanCreate[recursed]:
categoryBean tickCount: 1 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”“,“resolveBeanCreate[findSetters]:
CONTENTUTILITY tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,””,“resolveBeanCreate[findSetters]:
MAILER tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”“,“resolveBeanCreate[loopMeta]:
MAILER tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,””,“resolveBeanCreate[Done]:
MAILER tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”“,“resolveBeanCreate[recursed]:
CONTENTUTILITY tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,””,“resolveBeanCreate[loopMeta]:
CONTENTUTILITY tickCount: 0 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”“,“resolveBeanCreate[Done]:
CONTENTUTILITY tickCount: 1 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,””,“resolveBeanCreate[recursed]:
categoryBean tickCount: 2 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”“,“resolveBeanCreate[recursed]:
categoryBean tickCount: 2 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,””,“resolveBeanCreate[loopMeta]:
categoryBean tickCount: 2 "
“INFO”,“ajp-bio-8009-exec-1”,“03/23/2016”,“11:54:30”,”","resolveBeanCreate[Done]:
categoryBean tickCount: 2 "
Lucee - Instantiating a category Bean: Total 17ms
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,“”,“resolveBeanCreate[findSetters]:
categoryBean tickCount: 1 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”“,“resolveBeanCreate[findSetters]:
CATEGORYMANAGER tickCount: 0 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,””,“resolveBeanCreate[loopMeta]:
CATEGORYMANAGER tickCount: 1 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”“,“resolveBeanCreate[Done]:
CATEGORYMANAGER tickCount: 1 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,””,“resolveBeanCreate[recursed]:
categoryBean tickCount: 6 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”“,“resolveBeanCreate[findSetters]:
CONTENTUTILITY tickCount: 0 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,””,“resolveBeanCreate[findSetters]:
MAILER tickCount: 1 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”“,“resolveBeanCreate[loopMeta]:
MAILER tickCount: 2 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,””,“resolveBeanCreate[Done]:
MAILER tickCount: 2 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”“,“resolveBeanCreate[recursed]:
CONTENTUTILITY tickCount: 5 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,””,“resolveBeanCreate[loopMeta]:
CONTENTUTILITY tickCount: 5 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”“,“resolveBeanCreate[Done]:
CONTENTUTILITY tickCount: 6 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,””,“resolveBeanCreate[recursed]:
categoryBean tickCount: 14 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”“,“resolveBeanCreate[recursed]:
categoryBean tickCount: 16 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,””,“resolveBeanCreate[loopMeta]:
categoryBean tickCount: 16 "
“INFO”,“ajp-nio-8009-exec-8”,“03/23/2016”,“11:49:08”,”","resolveBeanCreate[Done]:
categoryBean tickCount: 17 "
Further digging seemes to yield that it was the recursion that took the
most time, and a Mission Control seems to think Lucee is spending alot of
time in a set of methods called ‘_duplicate’ - Can provide thread dumps if
required.