The following data are from later in the builder seqeunce. Here I'm not looking at builder activity with large Elapsed multiplication factors for the build. It is more of a random sampling showing examples of build-depends, lib-depends, and run-depends and some with more than one of those notably contributing. This is later when dependencies are normally involve for the package builds.
A property of the dependency based ordering of builds is that the earlier builds tend of have fewer dependencies and the later builds tend to have more. For building around 36000 packages, even a mean rate of around 1 extra second per is around 10 hrs of extra time. (But normal extra times are not generally near the mean-extra-time.) It appears that a big factor in the overall Elapsed time is the large fraction of the 30000+ packages that are "small build step" packages --the subset of that for which build later because of involved dependencies. The dependency analysis is more time consuming than the build time --or even total for all of the steps other than build-depends, lib-depends, and run-depends. Basically: for the most part, only early builder runs can be quick runs (no or very limited dependencies involved). It would take a massive decrease in most build-depends, lib-depends, and run-depends (when any) Elapsed times in later builds for this not to be the case --because of the number of packages for which the rest of the time is small. Such does not seem likely? The supporting detail . . . I'll note that the 3 load averages reported by top for the below were near the number of FreeBSD cpus whenever I happened to check that, instead of being during high load average time frames. (I.e., no packages with large builder steps were active.) There is a later explanation of this. Both build-depends and lib-depends: # sort -s -k5,5 ~/bulk-output-release-aarch64-ports-alt-2.txt | grep -B4 -A3 -e "^\[03:52:[0-9][0-9]\] .*configure$" | more . . . [03:50:57] [14] [00:00:15] Status devel/dwarves | dwarves-1.19_3: patch-depends [03:50:57] [14] [00:00:15] Status devel/dwarves | dwarves-1.19_3: patch [03:50:59] [14] [00:00:17] Status devel/dwarves | dwarves-1.19_3: build-depends [03:52:17] [14] [00:01:35] Status devel/dwarves | dwarves-1.19_3: lib-depends [03:52:28] [14] [00:01:46] Status devel/dwarves | dwarves-1.19_3: configure [03:52:31] [14] [00:01:49] Status devel/dwarves | dwarves-1.19_3: build [03:52:33] [14] [00:01:51] Status devel/dwarves | dwarves-1.19_3: run-depends [03:52:34] [14] [00:01:52] Status devel/dwarves | dwarves-1.19_3: stage . . . -- [03:52:08] [01] [00:00:08] Status security/seccure | seccure-0.5_10: patch-depends [03:52:08] [01] [00:00:08] Status security/seccure | seccure-0.5_10: patch [03:52:08] [01] [00:00:08] Status security/seccure | seccure-0.5_10: build-depends [03:52:18] [01] [00:00:18] Status security/seccure | seccure-0.5_10: lib-depends [03:52:31] [01] [00:00:31] Status security/seccure | seccure-0.5_10: configure [03:52:31] [01] [00:00:31] Status security/seccure | seccure-0.5_10: build [03:52:32] [01] [00:00:32] Status security/seccure | seccure-0.5_10: run-depends [03:52:32] [01] [00:00:32] Status security/seccure | seccure-0.5_10: stage lib-depends: # sort -s -k5,5 ~/bulk-output-release-aarch64-ports-alt-2.txt | grep -B4 -A3 -e "^\[05:20:[0-9][0-9]\] .*configure$" | more . . . -- [05:19:35] [05] [00:00:10] Status graphics/gdchart | gdchart-0.11.5_11: patch-depends [05:19:35] [05] [00:00:10] Status graphics/gdchart | gdchart-0.11.5_11: patch [05:19:36] [05] [00:00:11] Status graphics/gdchart | gdchart-0.11.5_11: build-depends [05:19:36] [05] [00:00:11] Status graphics/gdchart | gdchart-0.11.5_11: lib-depends [05:20:08] [05] [00:00:43] Status graphics/gdchart | gdchart-0.11.5_11: configure [05:20:09] [05] [00:00:44] Status graphics/gdchart | gdchart-0.11.5_11: build [05:20:11] [05] [00:00:46] Status graphics/gdchart | gdchart-0.11.5_11: run-depends [05:20:11] [05] [00:00:46] Status graphics/gdchart | gdchart-0.11.5_11: stage . . . -- [05:20:02] [06] [00:02:04] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: patch-depends [05:20:02] [06] [00:02:04] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: patch [05:20:03] [06] [00:02:05] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: build-depends [05:20:04] [06] [00:02:06] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: lib-depends [05:20:26] [06] [00:02:28] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: configure [05:20:27] [06] [00:02:29] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: build [05:20:39] [06] [00:02:41] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: run-depends [05:20:39] [06] [00:02:41] Status net-p2p/bitmark-recorder | bitmark-recorder-0.16.0_1: stage build-depends and run-depends: # sort -s -k5,5 ~/bulk-output-release-aarch64-ports-alt-2.txt | grep -B4 -A3 -e "^\[09:15:[0-9][0-9]\] .*configure$" | more [09:15:03] [07] [00:00:04] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: patch-depends [09:15:03] [07] [00:00:04] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: patch [09:15:03] [07] [00:00:04] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: build-depends [09:15:22] [07] [00:00:23] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: lib-depends [09:15:22] [07] [00:00:23] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: configure [09:15:22] [07] [00:00:23] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: build [09:15:22] [07] [00:00:23] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: run-depends [09:16:01] [07] [00:01:02] Status databases/pear-Horde_Memcache@php82 | php82-pear-horde-Horde_Memcache-2.1.2: stage . . . -- [09:15:13] [04] [00:00:13] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: patch-depends [09:15:13] [04] [00:00:13] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: patch [09:15:13] [04] [00:00:13] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: build-depends [09:15:31] [04] [00:00:31] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: lib-depends [09:15:31] [04] [00:00:31] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: configure [09:15:31] [04] [00:00:31] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: build [09:15:31] [04] [00:00:31] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: run-depends [09:16:08] [04] [00:01:08] Status devel/pear-Horde_Date@php82 | php82-pear-horde-Horde_Date-2.4.1: stage . . . -- [09:14:59] [13] [00:00:03] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: patch-depends [09:14:59] [13] [00:00:03] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: patch [09:14:59] [13] [00:00:03] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: build-depends [09:15:18] [13] [00:00:22] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: lib-depends [09:15:18] [13] [00:00:22] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: configure [09:15:18] [13] [00:00:22] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: build [09:15:18] [13] [00:00:22] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: run-depends [09:16:19] [13] [00:01:23] Status net/pear-Horde_Ldap@php82 | php82-pear-horde-Horde_Ldap-2.4.2: stage -- [09:14:44] [02] [00:00:04] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: patch-depends [09:14:44] [02] [00:00:04] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: patch [09:14:44] [02] [00:00:04] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: build-depends [09:15:03] [02] [00:00:23] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: lib-depends [09:15:03] [02] [00:00:23] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: configure [09:15:03] [02] [00:00:23] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: build [09:15:03] [02] [00:00:23] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: run-depends [09:15:21] [02] [00:00:41] Status net/pear-Horde_Url@php82 | php82-pear-horde-Horde_Url-2.2.6: stage -- [09:14:59] [09] [00:00:03] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: patch-depends [09:15:00] [09] [00:00:04] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: patch [09:15:00] [09] [00:00:04] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: build-depends [09:15:19] [09] [00:00:23] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: lib-depends [09:15:19] [09] [00:00:23] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: configure [09:15:19] [09] [00:00:23] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: build [09:15:19] [09] [00:00:23] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: run-depends [09:15:59] [09] [00:01:03] Status security/pear-Horde_Group@php82 | php82-pear-horde-Horde_Group-2.1.1: stage -- [09:15:00] [14] [00:00:04] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: patch-depends [09:15:00] [14] [00:00:04] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: patch [09:15:00] [14] [00:00:04] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: build-depends [09:15:20] [14] [00:00:24] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: lib-depends [09:15:20] [14] [00:00:24] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: configure [09:15:20] [14] [00:00:24] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: build [09:15:20] [14] [00:00:24] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: run-depends [09:16:00] [14] [00:01:04] Status www/pear-Horde_SessionHandler@php82 | php82-pear-horde-Horde_SessionHandler-2.3.0: stage Mostly build-depends but one also has lib-depends: # sort -s -k5,5 ~/bulk-output-release-aarch64-ports-alt-2.txt | grep -B4 -A3 -e "^\[11:10:[0-9][0-9]\] .*configure$" | more [11:10:02] [14] [00:00:02] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: patch-depends [11:10:02] [14] [00:00:02] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: patch [11:10:02] [14] [00:00:02] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: build-depends [11:10:46] [14] [00:00:46] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: lib-depends [11:10:46] [14] [00:00:46] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: configure [11:10:46] [14] [00:00:46] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: build [11:10:46] [14] [00:00:46] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: run-depends [11:10:46] [14] [00:00:46] Status devel/cask@nox | cask-emacs_nox-0.8.3_17: stage -- [11:09:53] [04] [00:00:10] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: patch-depends [11:09:53] [04] [00:00:10] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: patch [11:09:54] [04] [00:00:11] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: build-depends [11:10:38] [04] [00:00:55] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: lib-depends [11:10:38] [04] [00:00:55] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: configure [11:10:38] [04] [00:00:55] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: build [11:10:38] [04] [00:00:55] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: run-depends [11:10:38] [04] [00:00:55] Status devel/distel@nox | distel-emacs_nox-4.1.1_19: stage -- [11:09:36] [12] [00:00:14] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: patch-depends [11:09:36] [12] [00:00:14] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: patch [11:09:37] [12] [00:00:15] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: build-depends [11:10:00] [12] [00:00:38] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: lib-depends [11:10:00] [12] [00:00:38] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: configure [11:10:00] [12] [00:00:38] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: build [11:10:00] [12] [00:00:38] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: run-depends [11:10:00] [12] [00:00:38] Status devel/lua-mode@nox | lua-mode-emacs_nox-20210802_10: stage -- [11:09:14] [05] [00:00:17] Status devel/obby | obby-0.4.8_6: patch-depends [11:09:14] [05] [00:00:17] Status devel/obby | obby-0.4.8_6: patch [11:09:16] [05] [00:00:19] Status devel/obby | obby-0.4.8_6: build-depends [11:09:59] [05] [00:01:02] Status devel/obby | obby-0.4.8_6: lib-depends [11:10:21] [05] [00:01:24] Status devel/obby | obby-0.4.8_6: configure [11:10:26] [05] [00:01:29] Status devel/obby | obby-0.4.8_6: build [11:10:37] [05] [00:01:40] Status devel/obby | obby-0.4.8_6: run-depends [11:10:37] [05] [00:01:40] Status devel/obby | obby-0.4.8_6: stage -- [11:10:01] [07] [00:00:06] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: patch-depends [11:10:01] [07] [00:00:06] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: patch [11:10:01] [07] [00:00:06] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: build-depends [11:10:23] [07] [00:00:28] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: lib-depends [11:10:23] [07] [00:00:28] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: configure [11:10:23] [07] [00:00:28] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: build [11:10:23] [07] [00:00:28] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: run-depends [11:10:23] [07] [00:00:28] Status devel/pkg-info.el@nox | pkg-info.el-emacs_nox-0.6_18: stage -- [11:10:21] [02] [00:00:02] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: patch-depends [11:10:21] [02] [00:00:02] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: patch [11:10:21] [02] [00:00:02] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: build-depends [11:10:40] [02] [00:00:21] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: lib-depends [11:10:40] [02] [00:00:21] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: configure [11:10:40] [02] [00:00:21] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: build [11:10:40] [02] [00:00:21] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: run-depends [11:10:40] [02] [00:00:21] Status devel/tablist@nox | tablist-emacs_nox-1.0.13_10: stage -- [11:10:03] [11] [00:00:02] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: patch-depends [11:10:03] [11] [00:00:02] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: patch [11:10:03] [11] [00:00:02] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: build-depends [11:10:25] [11] [00:00:24] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: lib-depends [11:10:25] [11] [00:00:24] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: configure [11:10:25] [11] [00:00:24] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: build [11:10:25] [11] [00:00:24] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: run-depends [11:10:25] [11] [00:00:24] Status editors/apel@nox | apel-emacs_nox-10.8.20220720_10: stage -- [11:09:45] [09] [00:00:12] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: patch-depends [11:09:45] [09] [00:00:12] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: patch [11:09:46] [09] [00:00:13] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: build-depends [11:10:33] [09] [00:01:00] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: lib-depends [11:10:33] [09] [00:01:00] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: configure [11:10:33] [09] [00:01:00] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: build [11:10:37] [09] [00:01:04] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: run-depends [11:10:37] [09] [00:01:04] Status editors/slime@nox | slime-emacs_nox-2.26.1.9_14: stage -- [11:09:48] [13] [00:00:14] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: patch-depends [11:09:48] [13] [00:00:14] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: patch [11:09:51] [13] [00:00:17] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: build-depends [11:09:52] [13] [00:00:18] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: lib-depends [11:10:14] [13] [00:00:40] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: configure [11:10:15] [13] [00:00:41] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: build [11:10:15] [13] [00:00:41] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: run-depends [11:10:38] [13] [00:01:04] Status mail/mailest@nox | mailest-emacs_nox-0.9.24_21: stage -- [11:10:04] [12] [00:00:03] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: patch-depends [11:10:04] [12] [00:00:03] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: patch [11:10:04] [12] [00:00:03] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: build-depends [11:10:27] [12] [00:00:26] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: lib-depends [11:10:27] [12] [00:00:26] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: configure [11:10:27] [12] [00:00:26] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: build [11:10:27] [12] [00:00:26] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: run-depends [11:10:29] [12] [00:00:28] Status mail/x-face-e21@nox | x-face-e21-emacs_nox-20070306_33: stage build-depends: # sort -s -k5,5 ~/bulk-output-release-aarch64-ports-alt-2.txt | grep -B4 -A3 -e "^\[12:47:[0-9][0-9]\] .*configure$" | more [12:46:29] [14] [00:00:03] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: patch-depends [12:46:29] [14] [00:00:03] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: patch [12:46:29] [14] [00:00:03] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: build-depends [12:47:42] [14] [00:01:16] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: lib-depends [12:47:42] [14] [00:01:16] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: configure [12:47:42] [14] [00:01:16] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: build [12:47:42] [14] [00:01:16] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: run-depends [12:47:42] [14] [00:01:16] Status databases/p5-Amazon-SimpleDB | p5-Amazon-SimpleDB-0.03_1: stage -- [12:46:40] [13] [00:00:03] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: patch-depends [12:46:40] [13] [00:00:03] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: patch [12:46:40] [13] [00:00:03] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: build-depends [12:47:08] [13] [00:00:31] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: lib-depends [12:47:08] [13] [00:00:31] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: configure [12:47:08] [13] [00:00:31] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: build [12:47:08] [13] [00:00:31] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: run-depends [12:47:08] [13] [00:00:31] Status textproc/p5-Text-EtText | p5-Text-EtText-2.2_2: stage -- [12:45:39] [03] [00:00:03] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: patch-depends [12:45:39] [03] [00:00:03] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: patch [12:45:39] [03] [00:00:03] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: build-depends [12:47:00] [03] [00:01:24] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: lib-depends [12:47:00] [03] [00:01:24] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: configure [12:47:00] [03] [00:01:24] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: build [12:47:00] [03] [00:01:24] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: run-depends [12:47:00] [03] [00:01:24] Status www/p5-Catalyst-Engine-PSGI | p5-Catalyst-Engine-PSGI-0.14: stage -- [12:45:04] [09] [00:00:06] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: patch-depends [12:45:04] [09] [00:00:06] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: patch [12:45:04] [09] [00:00:06] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: build-depends [12:47:12] [09] [00:02:14] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: lib-depends [12:47:12] [09] [00:02:14] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: configure [12:47:12] [09] [00:02:14] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: build [12:47:13] [09] [00:02:15] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: run-depends [12:47:13] [09] [00:02:15] Status www/p5-Catalyst-View-XML-Simple | p5-Catalyst-View-XML-Simple-0.01_2: stage -- [12:46:45] [08] [00:00:05] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: patch-depends [12:46:45] [08] [00:00:05] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: patch [12:46:45] [08] [00:00:05] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: build-depends [12:47:36] [08] [00:00:56] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: lib-depends [12:47:36] [08] [00:00:56] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: configure [12:47:37] [08] [00:00:57] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: build [12:47:37] [08] [00:00:57] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: run-depends [12:47:37] [08] [00:00:57] Status www/p5-MediaWiki-API | p5-MediaWiki-API-0.52: stage -- [12:46:37] [12] [00:00:04] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: patch-depends [12:46:37] [12] [00:00:04] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: patch [12:46:37] [12] [00:00:04] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: build-depends [12:47:54] [12] [00:01:21] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: lib-depends [12:47:54] [12] [00:01:21] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: configure [12:47:54] [12] [00:01:21] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: build [12:47:54] [12] [00:01:21] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: run-depends [12:47:54] [12] [00:01:21] Status www/p5-WWW-DHL | p5-WWW-DHL-0.03_2: stage build-depends and run-depends: # sort -s -k5,5 ~/bulk-output-release-aarch64-ports-alt-2.txt | grep -B4 -A3 -e "^\[14:03:[0-9][0-9]\] .*configure$" | more [14:02:47] [14] [00:00:02] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: patch-depends [14:02:47] [14] [00:00:02] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: patch [14:02:47] [14] [00:00:02] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: build-depends [14:03:43] [14] [00:00:58] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: lib-depends [14:03:43] [14] [00:00:58] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: configure [14:03:43] [14] [00:00:58] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: build [14:03:43] [14] [00:00:58] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: run-depends [14:04:43] [14] [00:01:58] Status databases/py-flask-sqlalchemy@py311 | py311-flask-sqlalchemy-3.1.1: stage -- [14:02:22] [01] [00:00:08] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: patch-depends [14:02:23] [01] [00:00:09] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: patch [14:02:23] [01] [00:00:09] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: build-depends [14:03:16] [01] [00:01:02] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: lib-depends [14:03:16] [01] [00:01:02] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: configure [14:03:16] [01] [00:01:02] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: build [14:03:16] [01] [00:01:02] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: run-depends [14:03:43] [01] [00:01:29] Status devel/py-flask-babel@py311 | py311-flask-babel-4.0.0_1: stage -- [14:02:51] [03] [00:00:03] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: patch-depends [14:02:51] [03] [00:00:03] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: patch [14:02:51] [03] [00:00:03] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: build-depends [14:03:43] [03] [00:00:55] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: lib-depends [14:03:43] [03] [00:00:55] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: configure [14:03:43] [03] [00:00:55] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: build [14:03:43] [03] [00:00:55] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: run-depends [14:05:07] [03] [00:02:19] Status devel/py-inline-snapshot@py311 | py311-inline-snapshot-0.20.8: stage -- [14:02:47] [02] [00:00:03] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: patch-depends [14:02:47] [02] [00:00:03] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: patch [14:02:47] [02] [00:00:03] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: build-depends [14:03:43] [02] [00:00:59] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: lib-depends [14:03:43] [02] [00:00:59] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: configure [14:03:43] [02] [00:00:59] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: build [14:03:43] [02] [00:00:59] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: run-depends [14:05:38] [02] [00:02:54] Status devel/py-xsdata-plantuml@py311 | py311-xsdata-plantuml-24.3: stage -- [14:02:07] [10] [00:00:04] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: patch-depends [14:02:07] [10] [00:00:04] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: patch [14:02:07] [10] [00:00:04] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: build-depends [14:03:06] [10] [00:01:03] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: lib-depends [14:03:07] [10] [00:01:04] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: configure [14:03:07] [10] [00:01:04] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: build [14:03:07] [10] [00:01:04] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: run-depends [14:03:34] [10] [00:01:31] Status mail/py-flask-mail@py311 | py311-flask-mail-0.10.0: stage -- [14:02:31] [06] [00:00:03] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: patch-depends [14:02:31] [06] [00:00:03] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: patch [14:02:31] [06] [00:00:03] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: build-depends [14:03:26] [06] [00:00:58] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: lib-depends [14:03:26] [06] [00:00:58] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: configure [14:03:26] [06] [00:00:58] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: build [14:03:26] [06] [00:00:58] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: run-depends [14:03:54] [06] [00:01:26] Status www/py-flask-bootstrap@py311 | py311-Flask-Bootstrap-3.3.7.1_2: stage -- [14:02:59] [13] [00:00:03] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: patch-depends [14:02:59] [13] [00:00:03] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: patch [14:02:59] [13] [00:00:03] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: build-depends [14:03:27] [13] [00:00:31] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: lib-depends [14:03:27] [13] [00:00:31] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: configure [14:03:27] [13] [00:00:31] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: build [14:03:27] [13] [00:00:31] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: run-depends [14:03:54] [13] [00:00:58] Status www/py-flask-caching@py311 | py311-flask-caching-2.2.0_1: stage -- [14:02:04] [08] [00:00:03] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: patch-depends [14:02:04] [08] [00:00:03] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: patch [14:02:04] [08] [00:00:03] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: build-depends [14:03:00] [08] [00:00:59] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: lib-depends [14:03:00] [08] [00:00:59] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: configure [14:03:00] [08] [00:00:59] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: build [14:03:00] [08] [00:00:59] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: run-depends [14:04:24] [08] [00:02:23] Status www/py-flask-simpleldap@py311 | py311-Flask-SimpleLDAP-2.0.0: stage -- [14:02:47] [05] [00:00:02] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: patch-depends [14:02:47] [05] [00:00:02] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: patch [14:02:47] [05] [00:00:02] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: build-depends [14:03:15] [05] [00:00:30] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: lib-depends [14:03:15] [05] [00:00:30] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: configure [14:03:16] [05] [00:00:31] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: build [14:03:16] [05] [00:00:31] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: run-depends [14:03:43] [05] [00:00:58] Status www/py-flask-theme@py311 | py311-flask-theme-0.3.6_1: stage The log file also allows me to do the likes of the following to see what and how many (package level) dependencies were involved: # grep "\<devel/dwarves\>[^-]" ~/bulk-output-release-aarch64-ports-alt-2.txt | more [00:00:17] devel/dwarves depends on devel/argp-standalone [00:00:17] devel/dwarves depends on devel/binutils [00:00:17] devel/dwarves depends on devel/cmake-core [00:00:17] devel/dwarves depends on devel/elfutils [00:00:17] devel/dwarves depends on devel/gettext-runtime [00:00:17] devel/dwarves depends on devel/gettext-tools [00:00:17] devel/dwarves depends on devel/gnulib [00:00:17] devel/dwarves depends on devel/ninja [00:00:17] devel/dwarves depends on lang/gcc14 [00:00:17] devel/dwarves depends on ports-mgmt/pkg [03:50:42] [14] [00:00:00] Building devel/dwarves | dwarves-1.19_3 [03:50:49] [14] [00:00:07] Status devel/dwarves | dwarves-1.19_3: check-sanity [03:50:49] [14] [00:00:07] Status devel/dwarves | dwarves-1.19_3: pkg-depends [03:50:51] [14] [00:00:09] Status devel/dwarves | dwarves-1.19_3: fetch-depends [03:50:51] [14] [00:00:09] Status devel/dwarves | dwarves-1.19_3: fetch [03:50:54] [14] [00:00:12] Status devel/dwarves | dwarves-1.19_3: checksum [03:50:55] [14] [00:00:13] Status devel/dwarves | dwarves-1.19_3: extract-depends [03:50:55] [14] [00:00:13] Status devel/dwarves | dwarves-1.19_3: extract [03:50:57] [14] [00:00:15] Status devel/dwarves | dwarves-1.19_3: patch-depends [03:50:57] [14] [00:00:15] Status devel/dwarves | dwarves-1.19_3: patch [03:50:59] [14] [00:00:17] Status devel/dwarves | dwarves-1.19_3: build-depends [03:52:17] [14] [00:01:35] Status devel/dwarves | dwarves-1.19_3: lib-depends [03:52:28] [14] [00:01:46] Status devel/dwarves | dwarves-1.19_3: configure [03:52:31] [14] [00:01:49] Status devel/dwarves | dwarves-1.19_3: build [03:52:33] [14] [00:01:51] Status devel/dwarves | dwarves-1.19_3: run-depends [03:52:34] [14] [00:01:52] Status devel/dwarves | dwarves-1.19_3: stage [03:52:35] [14] [00:01:53] Status devel/dwarves | dwarves-1.19_3: package [03:52:38] [14] [00:01:56] Finished devel/dwarves | dwarves-1.19_3: Success But it does not break out build-depends vs. lib-depends vs. run-depends specifics, for example. It looks like for many of the later small package builds, those 3 activities now make up most of the elapsed-time consequences for those builds. top over various time frames shows mostly the likes of: 66921 59 root 131 0 230016Ki 179080Ki CPU5 5 0:26 99.56% /usr/local/sbin/pkg-static add -A /packages/All/py311-flask-3.1.0.pkg 66914 59 root 68 0 19536Ki 9344Ki wait 10 0:00 0.00% /usr/local/sbin/pkg-static add -A /packages/All/py311-flask-3.1.0.pkg 66804 59 root 68 0 13408Ki 3120Ki wait 10 0:00 0.00% /bin/sh /usr/ports/Mk/Scripts/do-depends.sh for for build-depends, lib-depends, and run-depends for the activity for each active builder. None of the ports are "large build step" ones for where I happen to have sampled or when I happened to watch with top. But: Most packages are not "large build step" ones so this is expected much of the time. NOTE: The above explains the load averages being near the FreeBSD number of cpus during periods when all the active builders were doing such activity, as is common over many parts of the build sequence. Other notes: There is evidence on the build cluster results that the main-* runs have a notably larger Elapsed time multiplier than the 13* and 14* runs do. 13* and 14* are still substantial of themselves. My guess is that main-* uses a debug 15000?? kernel and possibly a debug jail-world and 13* and 14* use a non-debug 15000?? kernel (and a non-debug jail-world). But such details do not seem to be in public descriptive information or published in public log files. I expect the dependency analysis activity happens to touch Elapsed-time-consuming debug code. May be a more selective variant of debug kernel and/or world builds could be used instead that avoids a notable amount of that? It looks like having a partially pre-built bulk -a to start from could avoid wait-time for establishing a context that shows the Elapsed time behavior for build-depends, lib-depends, and run-depends. I ended up with having to restart poudriere(-devel) during my investigations and ended up using a non-debug kernel. So the times on the left below are from when it queued 26014 and then inspected 7111, not from the start of the original "bulk -c -a" on the Apple silicon M4 MAX under Parallels on macOS. === Mark Millard marklmi at yahoo.com
