Investigate Performance Drop
$ time allpix -c ../etc/unittests/test_performance/test_03_multithreading.conf
|15:53:09.088| (STATUS) Welcome to Allpix^2 v2.4.1-dirty
|15:53:09.089| (STATUS) Initialized PRNG with configured seed 1
|15:53:09.482| (STATUS) Loaded 6 modules
|15:53:13.219| (STATUS) Initialized 14 module instantiations
|15:53:13.219| (STATUS) Multithreading enabled, processing events in parallel on 4 worker threads
|15:53:13.219| (STATUS) Allocating a total of 2048 event slots for buffered modules
|15:53:13.226| (STATUS) Starting event loop
|15:54:02.665| (STATUS) Finished run of 500 events
|15:54:02.679| (STATUS) Finalization completed
|15:54:02.679| (STATUS) Executed 14 instantiations in 53 seconds, spending 34% of time in slowest instantiation GenericPropagation:telescope2
|15:54:02.679| (STATUS) Average processing time is 98879.9ms/event, event generation at 0 Hz
|15:54:02.679| (STATUS) This corresponds to a processing time of 395520ms/event per worker
________________________________________________________
Executed in 53.95 secs fish external
usr time 164.53 secs 0.00 millis 164.53 secs
sys time 0.91 secs 2.33 millis 0.91 secs
$ time allpix -c ../etc/unittests/test_performance/test_03_multithreading.conf
|15:56:16.672| (STATUS) Welcome to Allpix^2 v3.0.0-dirty
|15:56:16.673| (STATUS) Initialized PRNG with configured seed 1
|15:56:17.412| (STATUS) Loaded 6 modules
|15:56:17.412| (STATUS) Multithreading enabled, processing events in parallel on 4 worker threads
|15:56:17.412| (STATUS) Allocating a total of 1024 event slots for buffered modules
|15:56:21.419| (STATUS) Initialized 14 module instantiations
|15:56:21.425| (STATUS) Starting event loop
|15:57:20.043| (STATUS) Finished run of 500 events
|15:57:20.057| (STATUS) Finalization completed
|15:57:20.058| (STATUS) Executed 14 instantiations in 1 minutes 2 seconds, spending 35% of time in slowest instantiation GenericPropagation:telescope2
|15:57:20.058| (STATUS) Average processing time is 117.236ms/event, event generation at 9 Hz
|15:57:20.058| (STATUS) This corresponds to a processing time of 468.942ms/event per worker
________________________________________________________
Executed in 63.76 secs fish external
usr time 201.68 secs 1.35 millis 201.68 secs
sys time 1.14 secs 0.10 millis 1.14 secs