Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2021 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16765624 kB
MemAvailable 31630544 kB
Buffers 0 kB
Cached 14923500 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-14 22:37:50
DTS_END                         2021-11-14 22:37:50.0440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34608
TRN_ID                          241470
ATT_ID                          651
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9308.27 23633 1116992
customer order: creation 174.19 332 20903
customer order: refuse 14.74 376 1769
order to supplier: creation 45.88 1350 5505
order to supplier: removal 3.49 97827 419
invoice (draft): creation 44.68 3632 5361
invoice (draft): removal 52.94 741 6353
invoice accept: apply 42.16 18768 5059
invoice accept: cancel 48.40 805 5808
customer reserve: creation 3854.84 189 462581
customer reserve: removal 4728.21 58 567385
realization accept: apply 83.63 195 10036
realization accept: cancel 84.63 110 10155
payment from customer: creation 60.64 115 7277
payment from customer: removal 6.18 83 741
payment to supplier: creation 48.43 118 5812
payment to supplier: removal 3.58 98 429
service: total inventory turnovers 4.56 1413 547
service: total monetary turnovers 6.23 31 747
service: aggregate perf. data 0.84 19816 101
service: refresh index statistics 0.03 326602 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2021 22:39:16 to 14-11-2021 22:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1465
TEST_TIME 2 2934
TEST_TIME 3 3094
TEST_TIME 4 2807
TEST_TIME 5 3092
TEST_TIME 6 4036
TEST_TIME 7 4187
TEST_TIME 8 4513
TEST_TIME 9 5130
TEST_TIME 10 5251
TEST_TIME 11 5349
TEST_TIME 12 5730
TEST_TIME 13 5985
TEST_TIME 14 6454
TEST_TIME 15 6562
TEST_TIME 16 6846
TEST_TIME 17 6956
TEST_TIME 18 7186
TEST_TIME 19 7540
TEST_TIME 20 8006
TEST_TIME 21 8459
TEST_TIME 22 8559
TEST_TIME 23 8707
TEST_TIME 24 8839
TEST_TIME 25 9135
TEST_TIME 26 9231
TEST_TIME 27 9232
TEST_TIME 28 9749
TEST_TIME 29 9896
TEST_TIME 30 10829
TEST_TIME 31 10622
TEST_TIME 32 10563
TEST_TIME 33 10646
TEST_TIME 34 10483
TEST_TIME 35 10303
TEST_TIME 36 10109
TEST_TIME 37 9982
TEST_TIME 38 10130
TEST_TIME 39 10065
TEST_TIME 40 9904
TEST_TIME 41 9790
TEST_TIME 42 9757
TEST_TIME 43 9757
TEST_TIME 44 9829
TEST_TIME 45 9846
TEST_TIME 46 9819
TEST_TIME 47 9696
TEST_TIME 48 9907
TEST_TIME 49 9886
TEST_TIME 50 9848
TEST_TIME 51 9850
TEST_TIME 52 9818
TEST_TIME 53 9805
TEST_TIME 54 9778
TEST_TIME 55 9891
TEST_TIME 56 9911
TEST_TIME 57 9863
TEST_TIME 58 9888
TEST_TIME 59 9951
TEST_TIME 60 9958
TEST_TIME 61 10007
TEST_TIME 62 10096
TEST_TIME 63 9982
TEST_TIME 64 9926
TEST_TIME 65 9841
TEST_TIME 66 9813
TEST_TIME 67 9811
TEST_TIME 68 9727
TEST_TIME 69 9711
TEST_TIME 70 9637
TEST_TIME 71 9777
TEST_TIME 72 9764
TEST_TIME 73 9687
TEST_TIME 74 9707
TEST_TIME 75 9706
TEST_TIME 76 9675
TEST_TIME 77 9660
TEST_TIME 78 9644
TEST_TIME 79 9694
TEST_TIME 80 9705
TEST_TIME 81 9740
TEST_TIME 82 9695
TEST_TIME 83 9734
TEST_TIME 84 9640
TEST_TIME 85 9639
TEST_TIME 86 9610
TEST_TIME 87 9595
TEST_TIME 88 9589
TEST_TIME 89 9551
TEST_TIME 90 9493
TEST_TIME 91 9426
TEST_TIME 92 9364
TEST_TIME 93 9334
TEST_TIME 94 9397
TEST_TIME 95 9425
TEST_TIME 96 9407
TEST_TIME 97 9413
TEST_TIME 98 9439
TEST_TIME 99 9483
TEST_TIME 100 9484
TEST_TIME 101 9487
TEST_TIME 102 9468
TEST_TIME 103 9498
TEST_TIME 104 9511
TEST_TIME 105 9521
TEST_TIME 106 9510
TEST_TIME 107 9538
TEST_TIME 108 9572
TEST_TIME 109 9505
TEST_TIME 110 9460
TEST_TIME 111 9460
TEST_TIME 112 9449
TEST_TIME 113 9389
TEST_TIME 114 9395
TEST_TIME 115 9359
TEST_TIME 116 9392
TEST_TIME 117 9353
TEST_TIME 118 9332
TEST_TIME 119 9341
TEST_TIME 120 9310

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-11-2021 22:39:20 to 14-11-2021 22:39:23.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1545611 1545611 0 0.00 0 20905 6 0 0
doc_list_biud 1545611 1545611 0 0.00 0 57342 27 0 0
sp_add_invoice_to_stock 5188 5059 129 2.48 39 185567 18768 0 129
sp_cancel_adding_invoice 5808 5808 0 0.00 5 96483 806 0 0
sp_cancel_client_order 1769 1769 0 0.00 4 11759 376 0 0
sp_cancel_customer_reserve 567385 567385 0 0.00 1 57344 58 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 3 7081 83 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 2 7102 99 0 0
sp_cancel_supplier_invoice 6364 6353 11 0.17 2 32102 742 0 11
sp_cancel_supplier_order 419 419 0 0.00 5 995287 97828 0 0
sp_cancel_write_off 10155 10155 0 0.00 1 18953 111 0 0
sp_client_order 20903 20903 0 0.00 3 20456 332 0 0
sp_customer_reserve 462581 462581 0 0.00 0 46069 189 0 0
sp_fill_shopping_cart 31766 31766 0 0.00 1 13956 39 0 0
sp_fill_shopping_cart_clo_res 462580 462580 0 0.00 0 19452 14 0 0
sp_get_clo_for_invoice 5058 5058 0 0.00 0 30150 320 0 0
sp_kill_cost_storno 17133 17133 0 0.00 0 16956 55 0 0
sp_kill_qstorno_ret_qs2qd 1151697 1151697 0 0.00 0 57337 31 0 0
sp_kill_qty_storno 606983 606983 0 0.00 0 57342 68 0 0
sp_lock_selected_doc 20946 20946 0 0.00 0 9342 5 0 0
sp_make_cost_storno 28183 28183 0 0.00 0 20901 50 0 0
sp_multiply_rows_for_pdistr 28183 28183 0 0.00 0 17271 27 0 0
sp_multiply_rows_for_qdistr 20903 20903 0 0.00 0 20448 154 0 0
sp_pay_from_customer 7277 7277 0 0.00 4 20734 116 0 0
sp_pay_to_supplier 5812 5812 0 0.00 4 17086 119 0 0
sp_payment_common 13089 13089 0 0.00 2 17071 32 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 0 11751 351 0 0
sp_qd_handle_on_invoice_upd_sts 10866 10866 0 0.00 0 29576 320 0 0
sp_qd_handle_on_reserve_upd_sts 10036 10036 0 0.00 0 25570 77 0 0
sp_reserve_write_off 10036 10036 0 0.00 6 25760 196 0 0
sp_supplier_invoice 5361 5361 0 0.00 6 113299 3633 0 0
sp_supplier_order 5505 5505 0 0.00 5 33597 1351 0 0
srv_aggregate_perf_data 102 101 1 0.98 2991 133242 19817 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1072 117193 43634 0 0
srv_find_qd_qs_mism 1069169 1069169 0 0.00 0 13066 6 0 0
srv_make_invnt_saldo 547 547 0 0.00 32 78591 1414 0 0
srv_make_money_saldo 747 747 0 0.00 1 2417 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 87810 640964 326603 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5060 5060 0 0.00 0 11010 150 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 10580 111 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 6447 15 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 959 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 5590 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 159 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 2127 15 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 28419 1652 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 7601 17 0 0
t$perf-norm:sp_client_order 20903 20903 0 0.00 0 8095 17 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 1399 6 0 0
t$perf-norm:sp_pay_from_customer 7277 7277 0 0.00 0 4574 8 0 0
t$perf-norm:sp_pay_to_supplier 5812 5812 0 0.00 0 10989 14 0 0
t$perf-norm:sp_reserve_write_off 10036 10036 0 0.00 0 4981 9 0 0
t$perf-norm:sp_supplier_invoice 5361 5361 0 0.00 0 4666 17 0 0
t$perf-norm:sp_supplier_order 5505 5505 0 0.00 0 11193 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 524 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 270 6 0 0
t$perf-norm:srv_make_invnt_saldo 547 547 0 0.00 0 1935 26 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 2423 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10374 10116 258 2.48 0 5549 18 0 258
v_all_customers 38262 38262 0 0.00 0 103 1 0 0
v_all_suppliers 30158 30158 0 0.00 0 56 1 0 0
v_all_wares 41806 41806 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 219 6 0 0
v_cancel_client_order 3538 3538 0 0.00 0 2859 5 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 7039 17 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 2813 8 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 2350 10 0 0
v_cancel_supplier_invoice 798 776 22 2.75 0 2527 20 0 22
v_cancel_supplier_order 838 838 0 0.00 0 2052 7 0 0
v_cancel_write_off 2030 2030 0 0.00 0 5612 21 0 0
v_max_id_clo_ord 5504 5504 0 0.00 0 324 1 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 2238 20 0 0
v_max_id_ord_sup 5359 5359 0 0.00 0 3427 3 0 0
v_max_non_paid_invoice 1596 1596 0 0.00 0 337 3 0 0
v_max_non_paid_realizn 3816 3816 0 0.00 0 1656 4 0 0
v_min_id_clo_ord 5504 5504 0 0.00 0 8095 13 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 4475 8 0 0
v_min_id_ord_sup 5359 5359 0 0.00 0 8718 18 0 0
v_min_non_paid_invoice 5812 5812 0 0.00 0 17064 20 0 0
v_min_non_paid_realizn 7277 7277 0 0.00 0 16992 32 0 0
v_our_firm 10466 10466 0 0.00 0 23 1 0 0
v_reserve_write_off 20072 20072 0 0.00 0 7957 7 0 0
x_lock_depdocs_on_canc_invoice 11773 11773 0 0.00 0 14229 103 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 5751 316 0 0
x_make_qstorno_1000_1200 5505 5505 0 0.00 2 33584 1263 0 0
x_make_qstorno_1200_2000 5361 5361 0 0.00 3 113221 3498 0 0
x_make_qstorno_2100_3300 462581 462581 0 0.00 0 45452 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2021 22:39:24 to 14-11-2021 22:39:28.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:53 1 8192000000 8797700256 8808529920 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:54 31153 8192000000 9896656544 10206601216 972738336 10.613 100 91 182 0 1002817904 6174368 23905200
20:10:33 16604 8192000000 9937917520 10258964480 1019705008 11.069 100 91 182 0 1052084368 8977600 23401760
20:11:57 7321 8192000000 9969926192 10300796928 1052386864 11.384 100 83 166 1 1084052448 10803344 20862240
20:13:09 14963 8192000000 10034775712 10379694080 1116879312 11.997 100 81 162 0 1148191520 11398144 19914064
20:14:26 7525 8192000000 10072249696 10430636032 1156932512 12.375 100 77 154 1 1187431552 12827888 17671152
20:15:41 4491 8192000000 10118637728 10485837824 1208705568 12.857 100 80 156 0 1238583328 12005536 17872224
20:16:47 2454 8192000000 10154833872 10533994496 1248754848 13.227 100 87 176 0 1279901056 12173584 18972624
20:17:51 1678 8192000000 10200929312 10583113728 1284097360 13.550 100 92 181 2 1317798224 13297744 20403120
20:18:54 14956 8192000000 10210548064 10609090560 1303098608 13.723 100 66 133 0 1329518032 12176160 14243264
20:20:11 2243 8192000000 10210017648 10613751808 1310743376 13.793 100 46 92 0 1333525936 11108080 11674480
20:21:18 8225 8192000000 10214500016 10625826816 1320517440 13.881 100 28 56 0 1333370704 6632848 6220416
20:22:29 8691 8192000000 10209136112 10633084928 1328324928 13.952 100 24 48 1 1340352528 6689584 5338016
20:23:39 57956 8192000000 10226967920 10651815936 1332332592 13.988 100 34 68 0 1346376992 5842192 8202208
20:25:40 4848 8192000000 10236866672 10650980352 1336430496 14.025 100 26 53 0 1348700928 6305968 5964464
20:26:45 7340 8192000000 10220642208 10645032960 1329093120 13.959 100 19 36 2 1342420288 9432688 3894480
20:27:54 9079 8192000000 10200343056 10614689792 1287165728 13.578 98 14 28 0 1295359232 5099360 3094144
20:29:04 33412 8192000000 10150246016 10563731456 1214857568 12.914 94 21 42 1 1224157328 5171120 4128640
20:30:38 7130 8192000000 10136084112 10532380672 1184029152 12.628 94 47 93 4 1201105584 5300176 11776256
20:31:48 7081 8192000000 10143620688 10542080000 1240620064 13.152 99 25 49 1 1251730304 5374944 5735296
20:32:56 17729 8192000000 10153499488 10559500288 1251095312 13.248 99 23 46 1 1261029936 4640144 5294480
20:34:15 9805 8192000000 10166939744 10583629824 1272812960 13.447 100 23 46 0 1288893168 11037952 5042256
20:35:27 6700 8192000000 10190104656 10601238528 1290462416 13.608 100 12 24 0 1298863648 5509280 2891952
20:36:34 6326 8192000000 10189841664 10608427008 1302528864 13.718 100 20 40 1 1312533696 5901776 4103056
20:37:45 5830 8192000000 10211646528 10632409088 1324526384 13.918 100 16 32 1 1333646320 5364240 3755696
20:39:14 45125 8192000000 10249157792 10670084096 1347376352 14.124 100 38 76 1 1365419200 7744816 10298032
20:41:27 31169 8192000000 10255836448 10683342848 1359776880 14.235 100 27 54 1 1374514000 8469456 6267664
20:43:25 15009 8192000000 10286263184 10709504000 1372797472 14.352 100 35 69 1 1395202640 13935104 8470064
20:45:09 13693 8192000000 10315446496 10739769344 1394337376 14.545 100 49 96 2 1426677216 21135680 11204160
20:46:24 16808 8192000000 10318423472 10745864192 1395064736 14.551 100 48 96 0 1430099568 23332288 11702544
20:47:52 2385 8192000000 10343203488 10761859072 1405006544 14.640 100 79 158 2 1449454864 25557616 18890704
20:48:58 4005 8192000000 10333494960 10755723264 1392568160 14.529 99 57 114 1 1432387664 25564624 14254880
20:50:08 8761 8192000000 10342062688 10766659584 1405482560 14.644 100 57 114 1 1448695536 29260080 13952896
20:51:25 10259 8192000000 10318898096 10747428864 1387021408 14.479 99 42 84 1 1427206512 30010336 10174768
20:52:51 14898 8192000000 10304947584 10725748736 1352130704 14.167 98 44 87 1 1396216192 33179920 10905568
20:54:08 5232 8192000000 10313326128 10727559168 1368118448 14.310 100 69 138 3 1420727488 35898864 16710176
20:55:22 5839 8192000000 10324544032 10730827776 1363336224 14.267 100 86 171 2 1427176256 42931328 20908704
20:56:38 7268 8192000000 10318907952 10731560960 1356315216 14.204 99 62 124 1 1413697392 41216240 16165936
20:57:51 1071 8192000000 10338077648 10738860032 1368690224 14.315 100 86 168 1 1435023888 45007568 21326096
20:58:56 6049 8192000000 10318813648 10735730688 1374187120 14.365 100 43 85 2 1418596288 33494272 10914896
21:00:08 10511 8192000000 10316786736 10744803328 1382819632 14.442 100 32 63 0 1424576560 33275392 8481536
21:01:31 8137 8192000000 10336299040 10760523776 1379871744 14.415 99 50 100 2 1432611616 38448976 14290896
21:02:42 11920 8192000000 10328096400 10758782976 1389778000 14.504 100 34 68 1 1436892800 38014144 9100656
21:04:02 6422 8192000000 10310290496 10740473856 1375838608 14.379 100 31 61 1 1417321200 32959184 8523408
21:05:11 17875 8192000000 10314732704 10754695168 1361875248 14.254 99 29 58 0 1412162512 42196224 8091040
21:06:34 28200 8192000000 10310249760 10753351680 1346733776 14.118 99 22 44 1 1396392832 44043648 5615408
21:08:17 31430 8192000000 10278218560 10713669632 1364642592 14.279 99 38 75 0 1379158896 6211120 8305184
21:10:04 86178 8192000000 10295518688 10736762880 1348334640 14.132 97 18 36 0 1368184032 16807312 3042080
21:12:36 117193 8192000000 10307273200 10749779968 1385928944 14.470 100 51 102 0 1412796048 15051872 11815232
21:15:45 29775 8192000000 10310857376 10764132352 1395268704 14.553 100 33 68 1 1418945824 15514992 8162128
21:17:18 25674 8192000000 10330717184 10777223168 1403853872 14.629 100 16 32 2 1431327200 23574112 3899216
21:18:44 49507 8192000000 10348507200 10793922560 1409759728 14.682 100 27 54 0 1440644000 24737216 6147056
21:20:37 58234 8192000000 10321186592 10782117888 1406843760 14.656 100 34 68 0 1436752912 21394720 8514432
21:23:35 55284 8192000000 10318073424 10785480704 1396300464 14.562 99 14 28 0 1413913696 14087136 3526096
21:26:10 57571 8192000000 10307062368 10766217216 1362761296 14.262 97 8 16 0 1382943712 18795440 1386976
21:28:09 54498 8192000000 10312704720 10769416192 1398800128 14.584 100 21 42 0 1413760064 10172656 4787280
21:30:16 55353 8192000000 10320108912 10770280448 1401325584 14.607 100 18 35 2 1420892720 15791792 3775344
21:32:12 75132 8192000000 10312595504 10777899008 1392138464 14.525 99 16 32 1 1410946768 15157840 3650464
21:34:27 81681 8192000000 10305299088 10753335296 1318466496 13.863 94 19 38 1 1334184592 11478560 4239536
21:36:49 101350 8192000000 10271152032 10725191680 1302799440 13.721 94 8 16 2 1317091648 12845056 1447152
21:39:39 110162 8192000000 10274966704 10728022016 1358730608 14.226 99 16 31 1 1381480800 19292592 3457600
21:42:33 26323 8192000000 10253899888 10694897664 1313174528 13.815 97 33 66 2 1328336016 8369632 6791856
21:44:01 90714 8192000000 10240166208 10704154624 1314896320 13.830 98 13 26 1 1334576864 17051888 2628656
21:47:05 63268 8192000000 10248845968 10688397312 1289131232 13.596 95 19 38 2 1306824192 13451168 4241792
21:49:13 61459 8192000000 10273489136 10705502208 1331418192 13.980 99 27 53 0 1345716832 8476912 5821728
21:51:33 39039 8192000000 10264327168 10715652096 1340351344 14.061 99 33 66 2 1361853648 14254624 7247680
21:53:38 47630 8192000000 10282985632 10720296960 1340200848 14.059 98 37 74 1 1360112592 11800720 8111024
21:55:37 40027 8192000000 10305098512 10746462208 1377238640 14.392 100 33 66 1 1399628240 15453648 6935952
21:57:18 48574 8192000000 10281109936 10737680384 1339846144 14.056 98 27 54 0 1369744992 24051280 5847568
21:59:08 79747 8192000000 10286743248 10747736064 1361682144 14.252 99 17 34 0 1380694128 15202320 3809664
22:01:39 66593 8192000000 10308482880 10755833856 1383276080 14.446 100 15 30 0 1396831904 10325696 3230128
22:03:50 42095 8192000000 10284639008 10759266304 1380101936 14.417 100 21 42 0 1404194784 19358480 4734368
22:05:33 65505 8192000000 10288190176 10744262656 1382228832 14.436 100 20 41 2 1393527904 7111968 4187104
22:07:38 60014 8192000000 10305797552 10765377536 1400817264 14.602 100 19 38 2 1417886352 12829312 4239776
22:09:39 74228 8192000000 10303741280 10768994304 1393442928 14.537 99 14 28 1 1409328608 12651056 3234624
22:11:55 81987 8192000000 10273529728 10732765184 1305159376 13.742 94 11 22 1 1319875056 12223456 2492224
22:14:21 23492 8192000000 10299050176 10743652352 1362041888 14.256 98 30 60 0 1379565440 10440880 7082672
22:15:47 59747 8192000000 10282958736 10744123392 1334583872 14.009 96 15 30 0 1348655072 10701168 3370032
22:17:47 28703 8192000000 10301853888 10747977728 1385317648 14.464 100 39 79 1 1402538096 8743264 8477184
22:19:22 41119 8192000000 10287599520 10734383104 1328554256 13.954 96 38 76 0 1347685184 10873488 8257440
22:21:03 79919 8192000000 10285305056 10736332800 1327415168 13.944 96 14 28 1 1341048000 10452144 3180688
22:23:30 25928 8192000000 10283022528 10714812416 1336940336 14.030 98 40 80 1 1356201408 10830000 8431072
22:25:00 82485 8192000000 10290964128 10763481088 1377478320 14.394 100 8 16 1 1400688368 21395168 1814880
22:28:44 46898 8192000000 10285491376 10729848832 1341803152 14.074 98 25 50 0 1363922000 16223216 5895632
22:30:36 109272 8192000000 10264810720 10735837184 1335041056 14.013 97 11 22 0 1354992352 16900656 3050640
22:33:25 65768 8192000000 10255695808 10714505216 1344074416 14.094 99 15 30 2 1363696176 15243488 4378272
22:35:36 45024 8192000000 10251149184 10716147712 1352166624 14.167 100 10 20 0 1370455968 15806896 2482448
22:37:26 31399 8192000000 9252709024 9527078912 408140752 4.745 31 5 10 0 417017344 8015888 860704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-11-2021 22:39:30 to 14-11-2021 22:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 129 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-11-2021 22:39:45 to 14-11-2021 22:39:47.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243539
MON$OLDEST_ACTIVE               243540
MON$OLDEST_SNAPSHOT             243540
MON$NEXT_TRANSACTION            243540
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2042464
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:324: 
:325: mscs-test-161.budzdorov.ru    Sun Nov 14 22:38:01 2021
:326:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:327: 
:328: 
:329: mscs-test-161.budzdorov.ru    Sun Nov 14 22:38:02 2021
:330:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:331: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

14.11.21 22:39:48. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-14 22:39:51 No severe PSQL-related problems occured
14.11.21 22:39:52. End of report.