array set allMetrics {-1,wall 1663844835} array set allMetrics {pbs_debug:9,date { 6:09:12 (Sep22)} pbs_debug:11,date { 6:14:20 (Sep22)} pbs_debug:4,multiInfo {{{Total Time} {{clock 01:28:32} {lthread 01:27:41}}} {{Stage Time} {{clock 00:00:26} {lthread 00:00:26}}} {{% Time} {{clock { 0.5}} {lthread 0.46810685634366117<PREC(5.1)>}}}} pbs_debug:6,date { 6:06:07 (Sep22)} pbs_debug:7,tcount 16 pbs_debug:14,all 27892.0 pbs_debug:6,all 25231.0 pbs_debug:10,cpuload 5.00 pbs_debug:3,date { 5:35:21 (Sep22)} pbs_debug:14,sum 36190.865895999996 pbs_debug:11,fieldInfo {{Memory {6.97 GB}} {Date { 6:14:20 (Sep22)}} {{Thread Count} 16} {{System Load} 4.72}} pbs_debug:6,sum 32434.559306000003 pbs_debug:14,cpuload 5.77 pbs_debug:9,status {} pbs_debug:0,date { 4:54:40 (Sep22)} pbs_debug:10,mem {6.94 GB} pbs_debug:2,mem {7.24 GB} pbs_debug:9,thread 7479.479809999994 pbs_debug:12,multiInfo {{{Total Time} {{clock 02:07:09} {lthread 02:08:22}}} {{Stage Time} {{clock 00:00:04} {lthread 00:00:04}}} {{% Time} {{clock { 0.1}} {lthread 0.07201643943748634<PREC(5.1)>}}}} pbs_debug:5,tcount 16 pbs_debug:8,parent 5246.0 pbs_debug:7,status {} pbs_debug:17,tcount 16 pbs_debug:15,pmem {19.26 GB} pbs_debug:5,fieldInfo {{Memory {7.28 GB}} {Date { 6:01:30 (Sep22)}} {{Thread Count} 16} {{System Load} 4.46}} pbs_debug:7,thread 7228.839250000001 pbs_debug:3,tcount 16 pbs_debug:6,parent 5142.0 pbs_debug:12,pmem {19.26 GB} pbs_debug:11,all 25845.0 pbs_debug:3,all 15136.0 pbs_debug:6,multiInfo {{{Total Time} {{clock 01:58:52} {lthread 02:00:03}}} {{Stage Time} {{clock 00:04:37} {lthread 00:04:38}}} {{% Time} {{clock { 5.2}} {lthread 5.020924961612134<PREC(5.1)>}}}} pbs_debug:7,pmem {19.26 GB} pbs_debug:16,wall 8189 pbs_debug:11,sum 33543.483972999995 pbs_debug:0,cpuload 4.33 pbs_debug:3,sum 20371.246812999998 pbs_debug:5,status {} pbs_debug:15,tcount 16 pbs_debug:4,pmem {19.26 GB} pbs_debug:13,fieldInfo {{Memory {6.97 GB}} {Date { 6:15:32 (Sep22)}} {{Thread Count} 16} {{System Load} 5.02}} pbs_debug:13,wall 7697 pbs_debug:4,cpuload 4.22 pbs_debug:5,thread 6924.682705000001 pbs_debug:1,tcount 16 pbs_debug:4,parent 4418.0 pbs_debug:1,pmem {19.26 GB} pbs_debug:8,wall 7213 pbs_debug:8,cpuload 4.30 pbs_debug:17,name FINAL_CLEAN_UP pbs_debug:10,wall 7319 pbs_debug:17,status {} pbs_debug:14,multiInfo {{{Total Time} {{clock 02:15:47} {lthread 02:18:18}}} {{Stage Time} {{clock 00:07:30} {lthread 00:08:15}}} {{% Time} {{clock { 8.4}} {lthread 8.93000925157392<PREC(5.1)>}}}} pbs_debug:15,mem {6.97 GB} pbs_debug:5,wall 6855 pbs_debug:14,name 1ST_ST pbs_debug:7,mem {7.03 GB} pbs_debug:3,status {} pbs_debug:17,thread 8359.425567999997 pbs_debug:16,parent 6417.0 pbs_debug:13,tcount 16 pbs_debug:2,wall 4994 pbs_debug:9,name {PBS_TechMap-Postmap Cleanup} pbs_debug:11,name PBS_Incr_Opt-Start pbs_debug:3,thread 5235.246813 pbs_debug:2,parent 4175.0 pbs_debug:6,name {PBS_TechMap-Datapath Postmap Operations} pbs_debug:7,fieldInfo {{Memory {7.03 GB}} {Date { 6:06:27 (Sep22)}} {{Thread Count} 16} {{System Load} 4.24}} pbs_debug:0,all 2839.0 pbs_debug:15,status {} pbs_debug:3,name PBS_TechMap-Start pbs_debug:0,sum 5644.138198000001 pbs_debug:1,status {} pbs_debug:15,thread 8348.576931999998 pbs_debug:14,parent 6344.0 pbs_debug:11,tcount 16 pbs_debug:8,multiInfo {{{Total Time} {{clock 02:00:13} {lthread 02:01:27}}} {{Stage Time} {{clock 00:01:01} {lthread 00:00:59}}} {{% Time} {{clock { 1.1}} {lthread 1.0622424817029235<PREC(5.1)>}}}} pbs_debug:16,all 27965.0 pbs_debug:0,fieldInfo {{Memory {5.53 GB}} {Date { 4:54:40 (Sep22)}} {{Thread Count} 16} {{System Load} 4.33}} pbs_debug:1,thread 4993.246813 pbs_debug:0,name PBS_Generic-Start pbs_debug:8,all 25335.0 pbs_debug:11,cpuload 4.72 pbs_debug:0,multiInfoDetail {{{Total Time} {{clock 00:47:25} {lthread 00:46:45} {threads 00:47:19} {non-thread 00:47:19}}} {{Stage Time} {{clock 00:00:00} {lthread 00:00:00} {threads 00:00:00} {non-thread 00:00:00}}} {{% Time} {{clock { 0.0}} {lthread 0.0<PREC(5.1)>} {threads 0.0<PREC(5.1)>} {non-thread 0.0<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} <PERCENT>}}}} pbs_debug:0,parent 2839.0 pbs_debug:16,sum 36313.576931999996 pbs_debug:2,multiInfoDetail {{{Total Time} {{clock 01:23:14} {lthread 01:23:38} {threads 04:08:39} {non-thread 01:09:35}}} {{Stage Time} {{clock 00:00:25} {lthread 00:00:25} {threads 00:00:25} {non-thread 00:00:25}}} {{% Time} {{clock { 0.5}} {lthread 0.4501027464842896<PREC(5.1)>} {threads 0.09945498667303179<PREC(5.1)>} {non-thread 0.6965728615213151<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} 0.0<PERCENT>}}}} pbs_debug:16,date { 6:23:44 (Sep22)} pbs_debug:8,sum 32622.83925 pbs_debug:15,fieldInfo {{Memory {6.97 GB}} {Date { 6:23:44 (Sep22)}} {{Thread Count} 16} {{System Load} 6.01}} pbs_debug:15,cpuload 6.01 pbs_debug:4,multiInfoDetail {{{Total Time} {{clock 01:28:32} {lthread 01:27:41} {threads 04:12:42} {non-thread 01:13:38}}} {{Stage Time} {{clock 00:00:26} {lthread 00:00:26} {threads 00:00:26} {non-thread 00:00:26}}} {{% Time} {{clock { 0.5}} {lthread 0.46810685634366117<PREC(5.1)>} {threads 0.10343318613995306<PREC(5.1)>} {non-thread 0.7244357759821677<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} 0.0<PERCENT>}}}} pbs_debug:13,status {} pbs_debug:1,multiInfo {{{Total Time} {{clock 01:22:49} {lthread 01:23:13}}} {{Stage Time} {{clock 00:35:24} {lthread 00:36:28}}} {{% Time} {{clock { 39.7}} {lthread 39.394947888697395<PREC(5.1)>}}}} pbs_debug:6,multiInfoDetail {{{Total Time} {{clock 01:58:52} {lthread 02:00:03} {threads 07:00:31} {non-thread 01:25:42}}} {{Stage Time} {{clock 00:04:37} {lthread 00:04:38} {threads 00:05:01} {non-thread 00:05:01}}} {{% Time} {{clock { 5.2}} {lthread 5.020924961612134<PREC(5.1)>} {threads 1.1974380395433026<PREC(5.1)>} {non-thread 8.386737252716634<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -0.0<PERCENT>} {Utilization -0.07933042399638279<PERCENT>} {{Lazy Time} -0.006774732851985377<PERCENT>}}}} pbs_debug:13,date { 6:15:32 (Sep22)} pbs_debug:12,mem {6.97 GB} pbs_debug:8,multiInfoDetail {{{Total Time} {{clock 02:00:13} {lthread 02:01:27} {threads 07:02:15} {non-thread 01:27:26}}} {{Stage Time} {{clock 00:01:01} {lthread 00:00:59} {threads 00:00:59} {non-thread 00:00:59}}} {{% Time} {{clock { 1.1}} {lthread 1.0622424817029235<PREC(5.1)>} {threads 0.23471376854835502<PREC(5.1)>} {non-thread 1.6439119531903037<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} 0.032786885245901676<PERCENT>}}}} pbs_debug:4,mem {7.25 GB} pbs_debug:13,thread 7802.867519999994 pbs_debug:12,parent 5760.0 pbs_debug:16,multiInfo {{{Total Time} {{clock 02:16:29} {lthread 02:19:08}}} {{Stage Time} {{clock 00:00:00} {lthread 00:00:00}}} {{% Time} {{clock { 0.0}} {lthread 0.0<PREC(5.1)>}}}} pbs_debug:8,date { 6:07:28 (Sep22)} pbs_debug:10,date { 6:09:14 (Sep22)} pbs_debug:5,date { 6:01:30 (Sep22)} pbs_debug:11,status {} pbs_debug:2,date { 5:30:29 (Sep22)} pbs_debug:9,fieldInfo {{Memory {6.94 GB}} {Date { 6:09:12 (Sep22)}} {{Thread Count} 16} {{System Load} 5.09}} pbs_debug:11,thread 7698.483972999995 pbs_debug:10,parent 5484.0 pbs_debug:13,all 25981.0 pbs_debug:5,all 24930.0 pbs_debug:13,sum 33783.86751999999 pbs_debug:1,cpuload 4.21 pbs_debug:2,fieldInfo {{Memory {7.24 GB}} {Date { 5:30:29 (Sep22)}} {{Thread Count} 16} {{System Load} 4.35}} pbs_debug:5,sum 31854.682705 pbs_debug:10,multiInfoDetail {{{Total Time} {{clock 02:01:59} {lthread 02:04:41} {threads 07:06:13} {non-thread 01:31:24}}} {{Stage Time} {{clock 00:00:02} {lthread 00:00:02} {threads 00:00:02} {non-thread 00:00:02}}} {{% Time} {{clock { 0.0}} {lthread 0.03600821971874317<PREC(5.1)>} {threads 0.007956398933842543<PREC(5.1)>} {non-thread 0.05572582892170521<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} 0.0<PERCENT>}}}} pbs_debug:12,multiInfoDetail {{{Total Time} {{clock 02:07:09} {lthread 02:08:22} {threads 07:10:49} {non-thread 01:36:00}}} {{Stage Time} {{clock 00:00:04} {lthread 00:00:04} {threads 00:00:04} {non-thread 00:00:04}}} {{% Time} {{clock { 0.1}} {lthread 0.07201643943748634<PREC(5.1)>} {threads 0.015912797867685086<PREC(5.1)>} {non-thread 0.11145165784341042<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} 0.0<PERCENT>}}}} pbs_debug:5,cpuload 4.46 pbs_debug:17,pmem {19.26 GB} pbs_debug:14,multiInfoDetail {{{Total Time} {{clock 02:15:47} {lthread 02:18:18} {threads 07:44:52} {non-thread 01:45:44}}} {{Stage Time} {{clock 00:07:30} {lthread 00:08:15} {threads 00:31:51} {non-thread 00:07:32}}} {{% Time} {{clock { 8.4}} {lthread 8.93000925157392<PREC(5.1)>} {threads 7.60233918128655<PREC(5.1)>} {non-thread 12.594037336305378<PREC(5.1)>}}} {{ST Metrics} {{Efficiency 2.072519676635273<PERCENT>} {Utilization 0.0887066936686931<PERCENT>} {{Lazy Time} -0.10221861333333648<PERCENT>}}}} pbs_debug:17,fieldInfo {{Memory {6.97 GB}} {Date { 6:23:55 (Sep22)}} {{Thread Count} 16} {{System Load} 5.85}} pbs_debug:1,mem {8.00 GB} pbs_debug:16,multiInfoDetail {{{Total Time} {{clock 02:16:29} {lthread 02:19:08} {threads 07:46:05} {non-thread 01:46:57}}} {{Stage Time} {{clock 00:00:00} {lthread 00:00:00} {threads 00:00:00} {non-thread 00:00:00}}} {{% Time} {{clock { 0.0}} {lthread 0.0<PREC(5.1)>} {threads 0.0<PREC(5.1)>} {non-thread 0.0<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} <PERCENT>}}}} pbs_debug:9,cpuload 5.09 pbs_debug:3,multiInfo {{{Total Time} {{clock 01:28:06} {lthread 01:27:15}}} {{Stage Time} {{clock 00:04:52} {lthread 00:03:37}}} {{% Time} {{clock { 5.5}} {lthread 3.9068918394836336<PREC(5.1)>}}}} pbs_debug:14,pmem {19.26 GB} pbs_debug:17,mem {6.97 GB} pbs_debug:9,mem {6.94 GB} pbs_debug:9,pmem {19.26 GB} pbs_debug:11,pmem {19.26 GB} pbs_debug:10,fieldInfo {{Memory {6.94 GB}} {Date { 6:09:14 (Sep22)}} {{Thread Count} 16} {{System Load} 5.00}} pbs_debug:6,pmem {19.26 GB} pbs_debug:8,tcount 16 pbs_debug:15,wall 8189 pbs_debug:11,multiInfo {{{Total Time} {{clock 02:07:05} {lthread 02:08:18}}} {{Stage Time} {{clock 00:05:06} {lthread 00:03:37}}} {{% Time} {{clock { 5.7}} {lthread 3.9069667905929877<PREC(5.1)>}}}} pbs_debug:10,all 25573.0 pbs_debug:3,pmem {19.26 GB} pbs_debug:12,wall 7629 pbs_debug:2,all 14919.0 pbs_debug:10,sum 33054.47981 pbs_debug:0,pmem {11.98 GB} pbs_debug:2,sum 19937.246812999998 pbs_debug:7,wall 7152 pbs_debug:16,name CRB_ST pbs_debug:12,cpuload 4.66 pbs_debug:6,tcount 16 pbs_debug:9,parent 5482.0 pbs_debug:4,wall 5312 pbs_debug:4,fieldInfo {{Memory {7.25 GB}} {Date { 5:35:47 (Sep22)}} {{Thread Count} 16} {{System Load} 4.22}} pbs_debug:13,name INIT_CLEAN_UP pbs_debug:16,cpuload 6.01 pbs_debug:1,wall 4969 pbs_debug:8,name {PBS_TechMap-Postmap Clock Gating} pbs_debug:10,name PBS_Techmap-Post_MBCI pbs_debug:14,mem {6.95 GB} pbs_debug:5,multiInfo {{{Total Time} {{clock 01:54:15} {lthread 01:55:24}}} {{Stage Time} {{clock 00:25:43} {lthread 00:27:43}}} {{% Time} {{clock { 28.8}} {lthread 29.94868254358979<PREC(5.1)>}}}} pbs_debug:8,status {} pbs_debug:6,mem {7.03 GB} pbs_debug:5,name {PBS_Techmap-Global Mapping} pbs_debug:8,thread 7287.839250000001 pbs_debug:4,tcount 16 pbs_debug:7,parent 5187.0 pbs_debug:12,fieldInfo {{Memory {6.97 GB}} {Date { 6:14:24 (Sep22)}} {{Thread Count} 16} {{System Load} 4.66}} pbs_debug:2,name {PBS_Generic-Postgen HBO Optimizations} pbs_debug:6,status {} pbs_debug:13,multiInfo {{{Total Time} {{clock 02:08:17} {lthread 02:10:02}}} {{Stage Time} {{clock 00:01:08} {lthread 00:01:40}}} {{% Time} {{clock { 1.3}} {lthread 1.8073164082613808<PREC(5.1)>}}}} pbs_debug:16,tcount 16 pbs_debug:15,date { 6:23:44 (Sep22)} pbs_debug:6,thread 7203.559306000001 pbs_debug:15,all 27965.0 pbs_debug:2,tcount 16 pbs_debug:5,parent 4841.0 pbs_debug:7,all 25276.0 pbs_debug:12,date { 6:14:24 (Sep22)} pbs_debug:2,cpuload 4.35 pbs_debug:15,sum 36313.576931999996 pbs_debug:7,sum 32504.83925 pbs_debug:7,date { 6:06:27 (Sep22)} pbs_debug:6,cpuload 4.22 pbs_debug:6,fieldInfo {{Memory {7.03 GB}} {Date { 6:06:07 (Sep22)}} {{Thread Count} 16} {{System Load} 4.22}} pbs_debug:4,status {} pbs_debug:11,mem {6.97 GB} pbs_debug:17,parent 6428.0 pbs_debug:14,tcount 16 pbs_debug:3,mem {7.25 GB} pbs_debug:4,date { 5:35:47 (Sep22)} pbs_debug:4,thread 5261.246813 pbs_debug:0,tcount 16 pbs_debug:3,parent 4392.0 pbs_debug:7,multiInfo {{{Total Time} {{clock 01:59:12} {lthread 02:00:28}}} {{Stage Time} {{clock 00:00:20} {lthread 00:00:25}}} {{% Time} {{clock { 0.4}} {lthread 0.45514288901475947<PREC(5.1)>}}}} pbs_debug:1,date { 5:30:04 (Sep22)} pbs_debug:16,status {} pbs_debug:14,fieldInfo {{Memory {6.95 GB}} {Date { 6:23:03 (Sep22)}} {{Thread Count} 16} {{System Load} 5.77}} pbs_debug:2,status {} pbs_debug:16,thread 8348.576931999998 pbs_debug:0,multiInfo {{{Total Time} {{clock 00:47:25} {lthread 00:46:45}}} {{Stage Time} {{clock 00:00:00} {lthread 00:00:00}}} {{% Time} {{clock { 0.0}} {lthread 0.0<PREC(5.1)>}}}} pbs_debug:15,parent 6417.0 pbs_debug:12,tcount 16 pbs_debug:2,thread 5018.246813 pbs_debug:1,parent 4150.0 pbs_debug:12,all 25849.0 pbs_debug:15,multiInfo {{{Total Time} {{clock 02:16:29} {lthread 02:19:08}}} {{Stage Time} {{clock 00:00:42} {lthread 00:00:49}}} {{% Time} {{clock { 0.8}} {lthread 0.8950029533672194<PREC(5.1)>}}}} pbs_debug:4,all 15162.0 pbs_debug:16,pmem {19.26 GB} pbs_debug:12,sum 33551.483972999995 pbs_debug:14,status {} pbs_debug:4,sum 20423.246812999998 pbs_debug:13,pmem {19.26 GB} pbs_debug:1,multiInfoDetail {{{Total Time} {{clock 01:22:49} {lthread 01:23:13} {threads 04:08:14} {non-thread 01:09:10}}} {{Stage Time} {{clock 00:35:24} {lthread 00:36:28} {threads 03:20:55} {non-thread 00:21:51}}} {{% Time} {{clock { 39.7}} {lthread 39.394947888697395<PREC(5.1)>} {threads 47.957194573735926<PREC(5.1)>} {non-thread 36.528280858177766<PREC(5.1)>}}} {{ST Metrics} {{Efficiency 0.7655836329916794<PERCENT>} {Utilization 0.40085241152437023<PERCENT>} {{Lazy Time} -0.03018296374764584<PERCENT>}}}} pbs_debug:13,cpuload 5.02 pbs_debug:0,status {} pbs_debug:14,thread 8298.865895999996 pbs_debug:3,multiInfoDetail {{{Total Time} {{clock 01:28:06} {lthread 01:27:15} {threads 04:12:16} {non-thread 01:13:12}}} {{Stage Time} {{clock 00:04:52} {lthread 00:03:37} {threads 00:03:37} {non-thread 00:03:37}}} {{% Time} {{clock { 5.5}} {lthread 3.9068918394836336<PREC(5.1)>} {threads 0.8632692843219159<PREC(5.1)>} {non-thread 6.046252438005015<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} 0.2568493150684932<PERCENT>}}}} pbs_debug:13,parent 5892.0 pbs_debug:10,tcount 16 pbs_debug:0,mem {5.53 GB} pbs_debug:5,multiInfoDetail {{{Total Time} {{clock 01:54:15} {lthread 01:55:24} {threads 06:55:30} {non-thread 01:20:41}}} {{Stage Time} {{clock 00:25:43} {lthread 00:27:43} {threads 02:42:48} {non-thread 00:07:03}}} {{% Time} {{clock { 28.8}} {lthread 29.94868254358979<PREC(5.1)>} {threads 38.85905239288698<PREC(5.1)>} {non-thread 11.786012816940652<PREC(5.1)>}}} {{ST Metrics} {{Efficiency 0.4708526283114028<PERCENT>} {Utilization 0.7457070620909749<PERCENT>} {{Lazy Time} -0.0780530732339606<PERCENT>}}}} pbs_debug:8,pmem {19.26 GB} pbs_debug:17,cpuload 5.85 pbs_debug:17,wall 8200 pbs_debug:10,pmem {19.26 GB} pbs_debug:0,thread 2805.138198 pbs_debug:8,fieldInfo {{Memory {6.90 GB}} {Date { 6:07:28 (Sep22)}} {{Thread Count} 16} {{System Load} 4.30}} pbs_debug:7,multiInfoDetail {{{Total Time} {{clock 01:59:12} {lthread 02:00:28} {threads 07:01:16} {non-thread 01:26:27}}} {{Stage Time} {{clock 00:00:20} {lthread 00:00:25} {threads 00:00:45} {non-thread 00:00:45}}} {{% Time} {{clock { 0.4}} {lthread 0.45514288901475947<PREC(5.1)>} {threads 0.17901897601145722<PREC(5.1)>} {non-thread 1.2538311507383673<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -0.0<PERCENT>} {Utilization -0.7800672343261599<PERCENT>} {{Lazy Time} -0.2639971999999944<PERCENT>}}}} pbs_debug:9,multiInfoDetail {{{Total Time} {{clock 02:01:57} {lthread 02:04:39} {threads 07:06:11} {non-thread 01:31:22}}} {{Stage Time} {{clock 00:01:44} {lthread 00:03:11} {threads 00:03:56} {non-thread 00:03:56}}} {{% Time} {{clock { 1.9}} {lthread 3.450317695751374<PREC(5.1)>} {threads 0.9388550741934201<PREC(5.1)>} {non-thread 6.575647812761215<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -0.0<PERCENT>} {Utilization -0.23147208503256334<PERCENT>} {{Lazy Time} -0.8426976923076295<PERCENT>}}}} pbs_debug:5,pmem {19.26 GB} pbs_debug:14,wall 8147 pbs_debug:16,mem {6.97 GB} pbs_debug:12,status {} pbs_debug:8,mem {6.90 GB} pbs_debug:9,multiInfo {{{Total Time} {{clock 02:01:57} {lthread 02:04:39}}} {{Stage Time} {{clock 00:01:44} {lthread 00:03:11}}} {{% Time} {{clock { 1.9}} {lthread 3.450317695751374<PREC(5.1)>}}}} pbs_debug:1,fieldInfo {{Memory {8.00 GB}} {Date { 5:30:04 (Sep22)}} {{Thread Count} 16} {{System Load} 4.21}} pbs_debug:2,pmem {19.26 GB} pbs_debug:9,wall 7317 pbs_debug:11,wall 7625 pbs_debug:12,thread 7702.483972999995 pbs_debug:11,parent 5756.0 pbs_debug:6,wall 7132 pbs_debug:16,fieldInfo {{Memory {6.97 GB}} {Date { 6:23:44 (Sep22)}} {{Thread Count} 16} {{System Load} 6.01}} pbs_debug:15,name INTRMD_CLEAN_UP pbs_debug:2,multiInfo {{{Total Time} {{clock 01:23:14} {lthread 01:23:38}}} {{Stage Time} {{clock 00:00:25} {lthread 00:00:25}}} {{% Time} {{clock { 0.5}} {lthread 0.4501027464842896<PREC(5.1)>}}}} pbs_debug:1,all 14894.0 pbs_debug:3,wall 5286 pbs_debug:12,name PBS_Incr_Opt-Uniquify_Netlist pbs_debug:10,status {} pbs_debug:1,sum 19887.246812999998 pbs_debug:17,multiInfo {{{Total Time} {{clock 02:16:40} {lthread 02:19:19}}} {{Stage Time} {{clock 00:00:11} {lthread 00:00:10}}} {{% Time} {{clock { 0.2}} {lthread 0.195320034368312<PREC(5.1)>}}}} pbs_debug:0,wall 2845 pbs_debug:7,name {PBS_TechMap-Postmap HBO Optimizations} pbs_debug:17,all 27976.0 pbs_debug:10,thread 7481.479809999994 pbs_debug:9,all 25571.0 pbs_debug:3,cpuload 4.37 pbs_debug:11,multiInfoDetail {{{Total Time} {{clock 02:07:05} {lthread 02:08:18} {threads 07:10:45} {non-thread 01:35:56}}} {{Stage Time} {{clock 00:05:06} {lthread 00:03:37} {threads 00:04:32} {non-thread 00:04:32}}} {{% Time} {{clock { 5.7}} {lthread 3.9069667905929877<PREC(5.1)>} {threads 1.0820702550025858<PREC(5.1)>} {non-thread 7.578712733351908<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -0.0<PERCENT>} {Utilization -0.2534321749394244<PERCENT>} {{Lazy Time} 0.29083606862744926<PERCENT>}}}} pbs_debug:17,sum 36335.425568 pbs_debug:4,name {PBS_TechMap-Premap HBO Optimizations} pbs_debug:13,multiInfoDetail {{{Total Time} {{clock 02:08:17} {lthread 02:10:02} {threads 07:13:01} {non-thread 01:38:12}}} {{Stage Time} {{clock 00:01:08} {lthread 00:01:40} {threads 00:02:12} {non-thread 00:02:12}}} {{% Time} {{clock { 1.3}} {lthread 1.8073164082613808<PREC(5.1)>} {threads 0.5251223296336078<PREC(5.1)>} {non-thread 3.677904708832544<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -0.0<PERCENT>} {Utilization -0.31495652370204374<PERCENT>} {{Lazy Time} -0.4762286323529332<PERCENT>}}}} pbs_debug:9,sum 33050.47981 pbs_debug:7,cpuload 4.24 pbs_debug:15,multiInfoDetail {{{Total Time} {{clock 02:16:29} {lthread 02:19:08} {threads 07:46:05} {non-thread 01:46:57}}} {{Stage Time} {{clock 00:00:42} {lthread 00:00:49} {threads 00:01:13} {non-thread 00:01:13}}} {{% Time} {{clock { 0.8}} {lthread 0.8950029533672194<PREC(5.1)>} {threads 0.2904085610852528<PREC(5.1)>} {non-thread 2.0339927556422404<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -0.0<PERCENT>} {Utilization -0.4684867963724684<PERCENT>} {{Lazy Time} -0.183596095238153<PERCENT>}}}} pbs_debug:10,multiInfo {{{Total Time} {{clock 02:01:59} {lthread 02:04:41}}} {{Stage Time} {{clock 00:00:02} {lthread 00:00:02}}} {{% Time} {{clock { 0.0}} {lthread 0.03600821971874317<PREC(5.1)>}}}} pbs_debug:17,multiInfoDetail {{{Total Time} {{clock 02:16:40} {lthread 02:19:19} {threads 07:46:16} {non-thread 01:47:08}}} {{Stage Time} {{clock 00:00:11} {lthread 00:00:10} {threads 00:00:11} {non-thread 00:00:11}}} {{% Time} {{clock { 0.2}} {lthread 0.195320034368312<PREC(5.1)>} {threads 0.04376019413613399<PREC(5.1)>} {non-thread 0.30649205906937865<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -0.0<PERCENT>} {Utilization -0.013952353088555247<PERCENT>} {{Lazy Time} 0.013760363636472261<PERCENT>}}}} pbs_debug:1,name PBS_Generic_Opt-Post pbs_debug:13,mem {6.97 GB} pbs_debug:5,mem {7.28 GB} pbs_debug:17,date { 6:23:55 (Sep22)} pbs_debug:9,tcount 16 pbs_debug:14,date { 6:23:03 (Sep22)} pbs_debug:3,fieldInfo {{Memory {7.25 GB}} {Date { 5:35:21 (Sep22)}} {{Thread Count} 16} {{System Load} 4.37}}} array set allMetrics {default:1,mem {4.32 GB} default:3,wall 7368 default:1,status {} default:0,wall 0 default:1,thread 2607.0 default:0,parent 5.0 default:4,name OPT default:1,name Elaboration default:2,all 14963.0 default:2,sum 20025.246812999998 default:1,multiInfoDetail {{{Total Time} {{clock 00:44:24} {lthread 00:43:27} {threads 00:43:27} {non-thread 00:43:27}}} {{Stage Time} {{clock 00:44:24} {lthread 00:43:22} {threads 00:43:22} {non-thread 00:43:22}}} {{% Time} {{clock { 32.3}} {lthread 30.9746212133809<PREC(5.1)>} {threads 9.287218474497626<PREC(5.1)>} {non-thread 40.222600092750035<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} 0.02327327327327322<PERCENT>}}}} default:3,multiInfoDetail {{{Total Time} {{clock 02:02:48} {lthread 02:05:30} {threads 07:07:02} {non-thread 01:32:13}}} {{Stage Time} {{clock 00:38:49} {lthread 00:41:08} {threads 02:57:39} {non-thread 00:21:54}}} {{% Time} {{clock { 28.2}} {lthread 29.382237566657473<PREC(5.1)>} {threads 38.044758539458186<PREC(5.1)>} {non-thread 20.312258463441026<PREC(5.1)>}}} {{ST Metrics} {{Efficiency 0.5060178503976721<PERCENT>} {Utilization 0.4676353482037162<PERCENT>} {{Lazy Time} -0.05978230871618484<PERCENT>}}}} default:3,date { 6:10:03 (Sep22)} default:0,date { 4:07:15 (Sep22)} default:0,multiInfo {{{Total Time} {{clock 00:00:00} {lthread 00:00:05}}} {{Stage Time} {{clock 00:00:00} {lthread 00:00:00}}} {{% Time} {{clock { 0.0}} {lthread 0.0<PREC(5.1)>}}}} default:1,cpuload 4.15 default:3,mem {6.90 GB} default:1,fieldInfo {{Memory {4.32 GB}} {Date { 4:51:39 (Sep22)}} {{Thread Count} 16} {{System Load} 4.15}} default:4,pmem {19.26 GB} default:1,pmem {11.98 GB} default:2,multiInfo {{{Total Time} {{clock 01:23:59} {lthread 01:24:22}}} {{Stage Time} {{clock 00:39:35} {lthread 00:40:55}}} {{% Time} {{clock { 28.8}} {lthread 29.227647970036756<PREC(5.1)>}}}} default:4,all 28022.0 default:4,tcount 16 default:4,sum 36427.425568 default:2,wall 5039 default:0,mem {250.4 MB} default:3,name MAPPED default:2,tcount 16 default:0,name init default:3,fieldInfo {{Memory {6.90 GB}} {Date { 6:10:03 (Sep22)}} {{Thread Count} 16} {{System Load} 4.52}} default:4,status {} default:1,all 2607.0 default:4,multiInfo {{{Total Time} {{clock 02:17:26} {lthread 02:20:05}}} {{Stage Time} {{clock 00:14:38} {lthread 00:14:34}}} {{% Time} {{clock { 10.6}} {lthread 10.41549324992487<PREC(5.1)>}}}} default:4,thread 8405.425567999997 default:3,parent 5533.0 default:0,tcount 8 default:1,sum 5214.0 default:2,cpuload 4.36 default:2,status {} default:2,date { 5:31:14 (Sep22)} default:2,thread 5062.246813 default:1,parent 2607.0 default:0,status {} default:0,thread 5.0 default:0,multiInfoDetail {{{Total Time} {{clock 00:00:00} {lthread 00:00:05} {threads 00:00:05} {non-thread 00:00:05}}} {{Stage Time} {{clock 00:00:00} {lthread 00:00:00} {threads 00:00:00} {non-thread 00:00:00}}} {{% Time} {{clock { 0.0}} {lthread 0.0<PREC(5.1)>} {threads 0.0<PREC(5.1)>} {non-thread 0.0<PREC(5.1)>}}} {{ST Metrics} {{Efficiency <PERCENT>} {Utilization <PERCENT>} {{Lazy Time} <PERCENT>}}}} default:2,multiInfoDetail {{{Total Time} {{clock 01:23:59} {lthread 01:24:22} {threads 04:09:23} {non-thread 01:10:19}}} {{Stage Time} {{clock 00:39:35} {lthread 00:40:55} {threads 03:25:56} {non-thread 00:26:52}}} {{% Time} {{clock { 28.8}} {lthread 29.227647970036756<PREC(5.1)>} {threads 44.101795338544456<PREC(5.1)>} {non-thread 24.91884371618488<PREC(5.1)>}}} {{ST Metrics} {{Efficiency 0.7963267570630003<PERCENT>} {Utilization 0.34344686185323225<PERCENT>} {{Lazy Time} -0.033788131789473486<PERCENT>}}}} default:4,multiInfoDetail {{{Total Time} {{clock 02:17:26} {lthread 02:20:05} {threads 07:47:02} {non-thread 01:47:54}}} {{Stage Time} {{clock 00:14:38} {lthread 00:14:34} {threads 00:40:00} {non-thread 00:15:41}}} {{% Time} {{clock { 10.6}} {lthread 10.41549324992487<PREC(5.1)>} {threads 8.566227647499732<PREC(5.1)>} {non-thread 14.546297727624053<PREC(5.1)>}}} {{ST Metrics} {{Efficiency -1.3804942307869272<PERCENT>} {Utilization -0.07549524230049154<PERCENT>} {{Lazy Time} 0.003478635535304586<PERCENT>}}}} default:2,mem {7.19 GB} default:3,pmem {19.26 GB} default:0,pmem {250.4 MB} default:4,wall 8246 default:1,wall 2664 default:3,all 25622.0 default:3,sum 33152.47981 default:0,fieldInfo {{Memory {250.4 MB}} {Date { 4:07:15 (Sep22)}} {{Thread Count} 8} {{System Load} 3.20}} default:3,cpuload 4.52 default:2,name GENERIC default:1,multiInfo {{{Total Time} {{clock 00:44:24} {lthread 00:43:27}}} {{Stage Time} {{clock 00:44:24} {lthread 00:43:22}}} {{% Time} {{clock { 32.3}} {lthread 30.9746212133809<PREC(5.1)>}}}} default:0,all 5.0 default:4,date { 6:24:41 (Sep22)} default:0,sum 10.0 default:1,date { 4:51:39 (Sep22)} default:2,fieldInfo {{Memory {7.19 GB}} {Date { 5:31:14 (Sep22)}} {{Thread Count} 16} {{System Load} 4.36}} default:3,tcount 16 default:3,multiInfo {{{Total Time} {{clock 02:02:48} {lthread 02:05:30}}} {{Stage Time} {{clock 00:38:49} {lthread 00:41:08}}} {{% Time} {{clock { 28.2}} {lthread 29.382237566657473<PREC(5.1)>}}}} default:4,mem {6.78 GB} default:4,parent 6474.0 default:1,tcount 16 default:3,status {} default:0,cpuload 3.20 default:2,pmem {19.26 GB} default:3,thread 7530.479809999994 default:4,fieldInfo {{Memory {6.78 GB}} {Date { 6:24:41 (Sep22)}} {{Thread Count} 16} {{System Load} 5.08}} default:4,cpuload 5.08 default:2,parent 4219.0}