Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.03.2022 04: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 11233040 kB
MemAvailable 31664888 kB
Buffers 20 kB
Cached 20221996 kB
SwapCached 1744 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2022-03-22 04:37:10
DTS_END                         2022-03-22 04:37:10.6940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51798
TRN_ID                          194725
ATT_ID                          590
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: 8637.07 23843 1036448
customer order: creation 166.85 501 20022
customer order: refuse 14.75 486 1770
order to supplier: creation 44.53 1647 5343
order to supplier: removal 3.18 191933 382
invoice (draft): creation 42.85 3878 5142
invoice (draft): removal 45.75 1233 5490
invoice accept: apply 40.55 30396 4866
invoice accept: cancel 41.78 1668 5014
customer reserve: creation 3856.15 280 462738
customer reserve: removal 4101.60 117 492192
realization accept: apply 80.94 321 9713
realization accept: cancel 72.42 196 8690
payment from customer: creation 57.45 194 6894
payment from customer: removal 5.61 147 673
payment to supplier: creation 46.78 232 5613
payment to supplier: removal 3.37 156 404
service: total inventory turnovers 3.75 5524 450
service: total monetary turnovers 7.88 469 945
service: aggregate perf. data 0.85 19859 102
service: refresh index statistics 0.04 217631 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-03-2022 04:38:59 to 22-03-2022 04:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7618
TEST_TIME 2 6359
TEST_TIME 3 6277
TEST_TIME 4 6149
TEST_TIME 5 5859
TEST_TIME 6 5639
TEST_TIME 7 5679
TEST_TIME 8 6027
TEST_TIME 9 6525
TEST_TIME 10 6457
TEST_TIME 11 6885
TEST_TIME 12 7013
TEST_TIME 13 7030
TEST_TIME 14 7035
TEST_TIME 15 7146
TEST_TIME 16 7128
TEST_TIME 17 7092
TEST_TIME 18 7107
TEST_TIME 19 7295
TEST_TIME 20 7497
TEST_TIME 21 7939
TEST_TIME 22 8370
TEST_TIME 23 8634
TEST_TIME 24 8595
TEST_TIME 25 8854
TEST_TIME 26 9124
TEST_TIME 27 9332
TEST_TIME 28 9400
TEST_TIME 29 9762
TEST_TIME 30 10046
TEST_TIME 31 10329
TEST_TIME 32 10137
TEST_TIME 33 10283
TEST_TIME 34 10398
TEST_TIME 35 10454
TEST_TIME 36 10444
TEST_TIME 37 10421
TEST_TIME 38 10466
TEST_TIME 39 10540
TEST_TIME 40 10426
TEST_TIME 41 10421
TEST_TIME 42 10425
TEST_TIME 43 10418
TEST_TIME 44 10392
TEST_TIME 45 10314
TEST_TIME 46 10185
TEST_TIME 47 10097
TEST_TIME 48 10027
TEST_TIME 49 10047
TEST_TIME 50 10002
TEST_TIME 51 9847
TEST_TIME 52 9815
TEST_TIME 53 9743
TEST_TIME 54 9627
TEST_TIME 55 9535
TEST_TIME 56 9474
TEST_TIME 57 9419
TEST_TIME 58 9346
TEST_TIME 59 9392
TEST_TIME 60 9264
TEST_TIME 61 9209
TEST_TIME 62 9239
TEST_TIME 63 9179
TEST_TIME 64 9079
TEST_TIME 65 9158
TEST_TIME 66 9170
TEST_TIME 67 9229
TEST_TIME 68 9180
TEST_TIME 69 9133
TEST_TIME 70 9097
TEST_TIME 71 8992
TEST_TIME 72 8990
TEST_TIME 73 8938
TEST_TIME 74 8866
TEST_TIME 75 8811
TEST_TIME 76 8809
TEST_TIME 77 8817
TEST_TIME 78 8802
TEST_TIME 79 8781
TEST_TIME 80 8766
TEST_TIME 81 8767
TEST_TIME 82 8734
TEST_TIME 83 8699
TEST_TIME 84 8723
TEST_TIME 85 8742
TEST_TIME 86 8765
TEST_TIME 87 8786
TEST_TIME 88 8807
TEST_TIME 89 8829
TEST_TIME 90 8887
TEST_TIME 91 8834
TEST_TIME 92 8834
TEST_TIME 93 8924
TEST_TIME 94 8871
TEST_TIME 95 8813
TEST_TIME 96 8760
TEST_TIME 97 8741
TEST_TIME 98 8731
TEST_TIME 99 8709
TEST_TIME 100 8663
TEST_TIME 101 8616
TEST_TIME 102 8569
TEST_TIME 103 8545
TEST_TIME 104 8597
TEST_TIME 105 8640
TEST_TIME 106 8641
TEST_TIME 107 8642
TEST_TIME 108 8637
TEST_TIME 109 8614
TEST_TIME 110 8641
TEST_TIME 111 8615
TEST_TIME 112 8612
TEST_TIME 113 8657
TEST_TIME 114 8712
TEST_TIME 115 8824
TEST_TIME 116 8818
TEST_TIME 117 8810
TEST_TIME 118 8784
TEST_TIME 119 8734
TEST_TIME 120 8668

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-03-2022 04:39:03 to 22-03-2022 04:39:07.

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 1458283 1458283 0 0.00 0 17600 15 0 0
doc_list_biud 1458268 1458268 0 0.00 0 28955 46 0 0
sp_add_invoice_to_stock 4992 4866 126 2.52 54 210336 30397 0 126
sp_cancel_adding_invoice 5014 5014 0 0.00 7 77328 1668 0 0
sp_cancel_client_order 1770 1770 0 0.00 7 28963 486 0 0
sp_cancel_customer_reserve 492192 492192 0 0.00 1 23997 117 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 3875 147 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 3478 156 0 0
sp_cancel_supplier_invoice 5500 5490 10 0.18 4 25082 1233 0 10
sp_cancel_supplier_order 382 382 0 0.00 12 1513218 191933 0 0
sp_cancel_write_off 8690 8690 0 0.00 2 13754 197 0 0
sp_client_order 20022 20022 0 0.00 6 34342 502 0 0
sp_customer_reserve 462738 462738 0 0.00 1 108534 280 0 0
sp_fill_shopping_cart 30497 30497 0 0.00 2 18222 131 0 0
sp_fill_shopping_cart_clo_res 462703 462703 0 0.00 0 14296 22 0 0
sp_get_clo_for_invoice 4819 4819 0 0.00 1 33209 447 0 0
sp_kill_cost_storno 14781 14781 0 0.00 0 12764 97 0 0
sp_kill_qstorno_ret_qs2qd 998946 998946 0 0.00 0 25061 51 0 0
sp_kill_qty_storno 528066 528066 0 0.00 0 28955 118 0 0
sp_lock_selected_doc 20092 20092 0 0.00 0 7518 6 0 0
sp_make_cost_storno 27038 27038 0 0.00 0 14091 97 0 0
sp_multiply_rows_for_pdistr 27037 27037 0 0.00 0 10834 44 0 0
sp_multiply_rows_for_qdistr 20021 20021 0 0.00 0 8551 172 0 0
sp_pay_from_customer 6894 6894 0 0.00 5 12055 194 0 0
sp_pay_to_supplier 5613 5613 0 0.00 7 14893 232 0 0
sp_payment_common 12506 12506 0 0.00 2 7348 53 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 1 28955 403 0 0
sp_qd_handle_on_invoice_upd_sts 9830 9830 0 0.00 0 28037 657 0 0
sp_qd_handle_on_reserve_upd_sts 9712 9712 0 0.00 0 21808 74 0 0
sp_reserve_write_off 9713 9713 0 0.00 9 21868 321 0 0
sp_supplier_invoice 5142 5142 0 0.00 11 105677 3878 0 0
sp_supplier_order 5343 5343 0 0.00 9 34846 1647 0 0
srv_aggregate_perf_data 103 102 1 0.97 2274 178273 19859 0 1
srv_fill_mon_cache_memory 73 73 0 0.00 881 149641 32054 0 0
srv_find_qd_qs_mism 986393 986393 0 0.00 0 17597 12 0 0
srv_make_invnt_saldo 450 450 0 0.00 70 89316 5525 0 0
srv_make_money_saldo 945 945 0 0.00 1 20324 469 0 0
srv_recalc_idx_stat 5 5 0 0.00 107983 327782 217631 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4874 4874 0 0.00 1 12530 1086 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 2 6692 629 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 1614 29 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 4501 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2155 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 6440 42 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1158 38 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 113532 9266 0 0
t$perf-norm:sp_cancel_write_off 902 902 0 0.00 0 1408 20 0 0
t$perf-norm:sp_client_order 20022 20022 0 0.00 0 23999 43 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 3252 21 0 0
t$perf-norm:sp_pay_from_customer 6894 6894 0 0.00 0 9817 22 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 1742 21 0 0
t$perf-norm:sp_reserve_write_off 9713 9713 0 0.00 0 14616 26 0 0
t$perf-norm:sp_supplier_invoice 5142 5142 0 0.00 0 3859 33 0 0
t$perf-norm:sp_supplier_order 5343 5343 0 0.00 0 5326 37 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 348 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 4439 83 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 2646 26 0 0
t$perf-norm:srv_make_money_saldo 945 945 0 0.00 0 923 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 178 90 0 0
v_add_invoice_to_stock 9874 9622 252 2.55 0 3457 28 0 252
v_all_customers 36860 36860 0 0.00 0 66 4 0 0
v_all_suppliers 29696 29696 0 0.00 0 74 5 0 0
v_all_wares 40038 40038 0 0.00 0 123 5 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 231 17 0 0
v_cancel_client_order 3540 3540 0 0.00 0 3558 12 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3691 23 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 1511 15 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 3294 22 0 0
v_cancel_supplier_invoice 806 786 20 2.48 0 4229 49 0 20
v_cancel_supplier_order 764 764 0 0.00 0 1219 17 0 0
v_cancel_write_off 1804 1804 0 0.00 0 4211 32 0 0
v_max_id_clo_ord 5333 5333 0 0.00 0 2344 7 0 0
v_max_id_clo_res 944 944 0 0.00 0 2053 16 0 0
v_max_id_ord_sup 5139 5139 0 0.00 0 10049 9 0 0
v_max_non_paid_invoice 1237 1237 0 0.00 0 325 9 0 0
v_max_non_paid_realizn 3445 3445 0 0.00 0 1317 8 0 0
v_min_id_clo_ord 5333 5333 0 0.00 0 3133 15 0 0
v_min_id_clo_res 944 944 0 0.00 0 2314 16 0 0
v_min_id_ord_sup 5139 5139 0 0.00 0 9419 13 0 0
v_min_non_paid_invoice 5613 5613 0 0.00 0 5594 21 0 0
v_min_non_paid_realizn 6893 6893 0 0.00 0 7340 20 0 0
v_our_firm 10074 10074 0 0.00 0 170 5 0 0
v_reserve_write_off 19420 19420 0 0.00 0 3553 12 0 0
x_lock_depdocs_on_canc_invoice 10111 10111 0 0.00 0 24801 158 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 3463 375 0 0
x_make_qstorno_1000_1200 5343 5343 0 0.00 2 34646 1250 0 0
x_make_qstorno_1200_2000 5142 5142 0 0.00 5 100913 3611 0 0
x_make_qstorno_2100_3300 462738 462738 0 0.00 0 108529 244 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-03-2022 04:39:08 to 22-03-2022 04:39:12.

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
02:15:36 19769 8192000000 9875688192 10210914304 919679632 10.093 100 70 140 1 981983472 47038512 15265328
02:17:00 13228 8192000000 9907291616 10251964416 955964320 10.450 100 75 150 0 1022334624 50630384 15739920
02:18:16 6621 8192000000 9938310544 10291585024 980911744 10.693 100 85 170 0 1055249488 56672688 17665056
02:19:30 5146 8192000000 9969764128 10331189248 1002435104 10.902 100 83 162 0 1091118448 72030368 16652976
02:20:39 3387 8192000000 9999963680 10366337024 1020384000 11.076 100 77 155 0 1119866512 84832304 14650208
02:21:45 857 8192000000 10010545376 10376572928 1040812624 11.272 100 95 181 1 1121035488 62974256 17248608
02:22:47 1707 8192000000 10017967760 10392924160 1057192224 11.430 100 93 174 1 1147905744 72772240 17941280
02:23:53 3027 8192000000 10030745904 10411012096 1058779104 11.445 100 83 152 2 1150036848 76332048 14925696
02:24:58 2232 8192000000 10046410624 10419638272 1067775584 11.531 100 90 175 3 1160942640 76832960 16334096
02:26:03 1312 8192000000 10038063232 10415689728 1071935072 11.571 100 97 190 1 1151069440 59850176 19284192
02:27:07 749 8192000000 10061921696 10443030528 1075309728 11.603 100 93 174 0 1178889088 86007776 17571584
02:28:10 2429 8192000000 10035715408 10421538816 1081319888 11.660 100 95 185 2 1168286208 68567296 18399024
02:29:14 2043 8192000000 10033495680 10417627136 1081461408 11.661 100 98 191 2 1154091584 53115312 19514864
02:30:20 2224 8192000000 10064396272 10456301568 1088941648 11.733 100 94 189 2 1186282736 78425984 18915104
02:31:23 2412 8192000000 10068130688 10456051712 1088329456 11.727 100 97 189 3 1186464704 79707088 18428160
02:32:26 2242 8192000000 10051396080 10440261632 1086155136 11.706 100 95 185 4 1176097104 70334352 19607616
02:33:30 2184 8192000000 10054054784 10444439552 1090854720 11.751 100 97 190 2 1175578624 64121632 20602272
02:34:33 2957 8192000000 10079421456 10464923648 1089133904 11.734 100 96 192 1 1198936608 89555440 20247264
02:35:38 2406 8192000000 10071437136 10459754496 1085597520 11.701 100 98 189 1 1191679248 85979248 20102480
02:36:41 2498 8192000000 10036943456 10418733056 1073252256 11.583 99 98 188 0 1158074912 66212880 18609776
02:37:51 26808 8192000000 10042156656 10439118848 1079048480 11.638 100 67 131 0 1157843184 65653760 13140944
02:39:25 1395 8192000000 10058308688 10443747328 1090085200 11.743 100 82 158 0 1180467040 74132336 16249504
02:40:31 16370 8192000000 10100897344 10493497344 1102790736 11.864 100 72 143 2 1224563968 107631792 14141440
02:41:51 9703 8192000000 10097014288 10491084800 1099647104 11.834 100 67 129 1 1207405408 94137776 13620528
02:43:07 2097 8192000000 10167811680 10559897600 1124134320 12.066 100 77 147 0 1286749088 146901792 15712976
02:44:12 19021 8192000000 10182404960 10580627456 1087151760 11.716 98 50 98 1 1284965072 186391120 11422192
02:45:34 5462 8192000000 10186564288 10592165888 1098142880 11.820 100 80 157 0 1306564320 190727968 17693472
02:46:45 2247 8192000000 10233622608 10622894080 1124442800 12.069 100 91 180 3 1341520896 197344000 19734096
02:47:53 2981 8192000000 10253237712 10654703616 1127389856 12.097 100 79 159 0 1369847264 223604080 18853328
02:48:57 1447 8192000000 10284282496 10678132736 1127497136 12.098 100 94 184 0 1394533120 245768112 21267872
02:50:02 1752 8192000000 10329302016 10717564928 1137711680 12.194 100 91 173 0 1449456640 291376208 20368752
02:51:07 1557 8192000000 10365875888 10769477632 1145241344 12.265 100 93 179 0 1472864848 307278912 20344592
02:52:10 1270 8192000000 10400838000 10794213376 1151697072 12.325 100 97 190 2 1512933632 339403520 21833040
02:53:16 3323 8192000000 10431290864 10826461184 1164368112 12.444 100 83 164 0 1546811888 363353264 19090512
02:54:24 1123 8192000000 10486895424 10885640192 1167955952 12.478 100 95 185 3 1595652176 405460000 22236224
02:55:28 1521 8192000000 10534429312 10951237632 1168701536 12.485 100 95 188 2 1635006384 444542528 21762320
02:56:33 1207 8192000000 10542176512 10970107904 1168502368 12.483 100 95 190 1 1645093504 453922736 22668400
02:57:36 1822 8192000000 10527254848 10973085696 1169456096 12.492 100 98 191 1 1631635504 438527824 23651584
02:58:41 1445 8192000000 10499572608 10969911296 1166929888 12.468 100 95 187 1 1601576336 412326656 22319792
02:59:46 1926 8192000000 10499654080 10933637120 1182748672 12.616 100 98 194 2 1601874288 396745200 22380416
03:00:54 2123 8192000000 10490587920 10926632960 1167862224 12.477 100 75 149 0 1598056672 412380384 17814064
03:02:02 1370 8192000000 10448227648 10929762304 1166552112 12.465 99 97 194 3 1553370432 363078496 23739824
03:03:05 6262 8192000000 10454082064 10928885760 1172742512 12.522 100 64 126 1 1561129104 372767248 15619344
03:04:22 2883 8192000000 10445030512 10900893696 1174989328 12.543 100 71 143 0 1550755616 358464672 17301616
03:05:34 11186 8192000000 10414758592 10910322688 1151344864 12.322 99 32 65 2 1540327312 380434320 8548128
03:06:47 20551 8192000000 10368608240 10827284480 1154989232 12.356 100 32 64 1 1471670592 308287632 8393728
03:08:38 21030 8192000000 10232985008 10684055552 1141267440 12.227 100 39 74 2 1335461296 185603488 8590368
03:10:02 2378 8192000000 10227425600 10712100864 1162230496 12.424 100 83 166 1 1332612448 152151504 18230448
03:11:07 878 8192000000 10262665712 10698379264 1171924144 12.515 100 72 144 0 1361547488 173526864 16096480
03:12:11 38496 8192000000 10259470480 10722164736 1158587136 12.390 98 32 64 2 1367178464 201280352 7310976
03:13:51 35590 8192000000 10241085344 10699902976 1164713472 12.447 99 28 55 0 1343395136 172391568 6290096
03:15:58 29792 8192000000 10191996112 10656837632 1151126272 12.320 99 35 69 0 1296043888 137115312 7802304
03:17:32 7749 8192000000 10218176592 10633269248 1147380000 12.285 99 58 115 0 1303261248 142917648 12963600
03:18:41 38666 8192000000 10160971040 10591629312 1142813200 12.242 99 43 74 1 1270725168 120312192 7599776
03:20:24 14198 8192000000 10133870864 10574659584 1154591696 12.353 100 52 101 4 1242404400 76656256 11156448
03:22:02 58744 8192000000 10136287712 10590597120 1146229088 12.274 99 32 61 2 1223973312 71426160 6318064
03:24:13 125795 8192000000 10106908240 10578530304 1069277504 11.545 93 18 37 0 1142799680 69082896 4439280
03:27:52 30201 8192000000 10083797712 10504888320 1132728288 12.147 100 46 88 2 1178611552 35743728 10139536
03:29:23 10683 8192000000 10105119408 10531741696 1130556736 12.127 100 42 83 1 1205895472 66862384 8476352
03:30:43 149617 8192000000 10080959072 10550116352 1100149600 11.839 97 21 42 1 1158194976 53389232 4656144
03:34:18 69970 8192000000 10103259920 10535698432 1131541216 12.136 99 35 70 0 1194159968 55446448 7172304
03:36:29 100799 8192000000 10083697584 10522148864 1128860368 12.111 99 19 38 0 1197170320 64405584 3904368
03:39:13 4906 8192000000 10116669584 10530865152 1143980400 12.253 100 78 153 1 1220398960 61140656 15277904
03:40:21 31001 8192000000 10131660288 10563100672 1153976272 12.347 100 57 104 1 1242428496 77352080 11100144
03:41:57 3057 8192000000 10170470432 10600878080 1146343664 12.275 100 74 141 2 1270770560 109720416 14706480
03:43:02 26061 8192000000 10111098144 10545405952 1113047808 11.961 97 25 44 2 1210866032 92873808 4944416
03:44:32 39080 8192000000 10097705760 10586599424 1137805808 12.195 100 22 42 1 1229733072 86446336 5480928
03:46:17 43404 8192000000 10078802784 10542985216 1115976800 11.989 98 9 18 0 1178655744 59912880 2766064
03:48:01 67300 8192000000 10053492912 10545954816 1134972800 12.168 100 30 60 2 1195757184 53774032 7010352
03:50:14 41083 8192000000 10128728448 10578534400 1148689472 12.297 100 44 87 1 1224284912 66077696 9517744
03:52:07 68210 8192000000 10144183600 10641899520 1156773040 12.373 100 15 28 0 1276349008 116668784 2907184
03:54:22 81829 8192000000 10057491904 10563842048 1148109040 12.292 100 15 28 0 1225007168 73727120 3171008
03:56:55 69030 8192000000 10064935680 10535157760 1104358016 11.879 97 11 22 0 1142238912 35455472 2425424
03:59:04 33061 8192000000 10050561488 10495782912 1129941712 12.121 99 20 40 1 1146861488 13141584 3778192
04:00:39 17545 8192000000 10108085520 10540998656 1131543056 12.136 100 51 102 3 1214787264 73301184 9943024
04:02:01 33154 8192000000 10068907136 10514010112 1121356432 12.040 100 31 62 1 1174554064 47129600 6068032
04:04:10 32336 8192000000 10113097136 10559238144 1124820656 12.073 99 32 64 1 1210730656 79611872 6298128
04:05:45 22991 8192000000 10097907056 10550091776 1108692768 11.920 98 28 54 1 1190776000 76210912 5872320
04:07:10 49907 8192000000 10088192688 10563158016 1121465840 12.041 99 23 46 2 1193499152 67228976 4804336
04:09:00 19160 8192000000 10146085792 10584395776 1139861856 12.214 100 42 84 0 1243992672 95682496 8448320
04:10:23 87777 8192000000 10062674416 10517250048 1110933264 11.941 97 16 32 2 1156826096 42823152 3069680
04:13:17 57151 8192000000 10076595984 10502336512 1138956992 12.206 100 52 100 1 1184374912 34653008 10764912
04:15:16 45124 8192000000 10048752272 10485170176 1084688864 11.692 95 19 38 0 1121361472 32995744 3676864
04:17:04 72793 8192000000 10105431936 10546114560 1119037888 12.018 98 19 38 2 1195872304 72652464 4181952
04:19:18 101154 8192000000 10131644848 10617200640 1106998304 11.904 97 10 20 0 1205766992 96586112 2182576
04:22:00 50883 8192000000 10066013840 10530590720 1115590128 11.985 98 17 34 1 1152748288 33950032 3208128
04:23:58 29247 8192000000 10089728576 10522451968 1127128816 12.094 99 29 56 0 1193598704 59510224 6959664
04:25:33 36865 8192000000 10130336528 10554249216 1115241568 11.982 97 33 66 0 1208151616 85866048 7044000
04:27:14 21580 8192000000 10191014000 10618630144 1139528304 12.211 100 49 98 0 1268373200 118885248 9959648
04:28:48 43926 8192000000 10180666656 10625679360 1122355968 12.049 99 34 67 1 1255399216 126046976 6996272
04:30:39 30303 8192000000 10180668096 10607824896 1141701120 12.232 100 52 101 0 1302131936 149203920 11226896
04:32:17 60110 8192000000 10093992512 10593869824 1129266192 12.114 99 16 32 1 1190023248 57705216 3051840
04:34:18 135751 8192000000 10066641680 10529734656 1132110160 12.141 99 26 52 1 1189092864 51665504 5317200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-03-2022 04:39:14 to 22-03-2022 04:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 126 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-03-2022 04:39:30 to 22-03-2022 04:39:32.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          195141
MON$OLDEST_ACTIVE               195142
MON$OLDEST_SNAPSHOT             195142
MON$NEXT_TRANSACTION            195142
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-30 07:30:08.6030 GMT
MON$PAGES                       2061024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             732
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2744 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2744 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2744 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2744 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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


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

22.03.22 04:48:19. There are 3 logs in /var/tmp/logs-oltp40 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
2022-03-22 04:48:21 No severe PSQL-related problems occured
22.03.22 04:48:23. End of report.