Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.07.2022 13: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 15640148 kB
MemAvailable 31159536 kB
Buffers 0 kB
Cached 16088156 kB
SwapCached 2532 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-07-09 13:37:17
DTS_END                         2022-07-09 13:37:17.2920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51348
TRN_ID                          217512
ATT_ID                          656
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: 8736.95 24235 1048434
customer order: creation 171.18 503 20541
customer order: refuse 14.17 495 1700
order to supplier: creation 45.52 1416 5462
order to supplier: removal 3.38 200258 405
invoice (draft): creation 45.35 3451 5442
invoice (draft): removal 48.34 1209 5801
invoice accept: apply 40.07 27437 4808
invoice accept: cancel 43.34 1551 5201
customer reserve: creation 3728.83 256 447460
customer reserve: removal 4305.24 123 516629
realization accept: apply 83.10 325 9972
realization accept: cancel 79.47 190 9537
payment from customer: creation 59.29 198 7115
payment from customer: removal 5.70 152 684
payment to supplier: creation 48.38 223 5805
payment to supplier: removal 3.33 135 399
service: total inventory turnovers 3.68 4892 442
service: total monetary turnovers 7.56 450 907
service: aggregate perf. data 0.99 14289 119
service: refresh index statistics 0.04 227147 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-07-2022 13:38:50 to 09-07-2022 13:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7343
TEST_TIME 2 5897
TEST_TIME 3 6305
TEST_TIME 4 6039
TEST_TIME 5 6939
TEST_TIME 6 7218
TEST_TIME 7 6789
TEST_TIME 8 6957
TEST_TIME 9 7091
TEST_TIME 10 7144
TEST_TIME 11 7018
TEST_TIME 12 7077
TEST_TIME 13 7135
TEST_TIME 14 7328
TEST_TIME 15 7275
TEST_TIME 16 7212
TEST_TIME 17 7161
TEST_TIME 18 7232
TEST_TIME 19 7265
TEST_TIME 20 7276
TEST_TIME 21 7497
TEST_TIME 22 7450
TEST_TIME 23 7436
TEST_TIME 24 7444
TEST_TIME 25 7521
TEST_TIME 26 7622
TEST_TIME 27 7858
TEST_TIME 28 7848
TEST_TIME 29 7953
TEST_TIME 30 8146
TEST_TIME 31 8053
TEST_TIME 32 8057
TEST_TIME 33 8488
TEST_TIME 34 8733
TEST_TIME 35 9048
TEST_TIME 36 9316
TEST_TIME 37 9509
TEST_TIME 38 10177
TEST_TIME 39 10263
TEST_TIME 40 10391
TEST_TIME 41 10482
TEST_TIME 42 10554
TEST_TIME 43 10441
TEST_TIME 44 10309
TEST_TIME 45 10199
TEST_TIME 46 10097
TEST_TIME 47 10154
TEST_TIME 48 10020
TEST_TIME 49 10001
TEST_TIME 50 10134
TEST_TIME 51 10091
TEST_TIME 52 9993
TEST_TIME 53 9951
TEST_TIME 54 9873
TEST_TIME 55 9730
TEST_TIME 56 9674
TEST_TIME 57 9711
TEST_TIME 58 9657
TEST_TIME 59 9592
TEST_TIME 60 9529
TEST_TIME 61 9492
TEST_TIME 62 9432
TEST_TIME 63 9468
TEST_TIME 64 9420
TEST_TIME 65 9442
TEST_TIME 66 9424
TEST_TIME 67 9381
TEST_TIME 68 9503
TEST_TIME 69 9462
TEST_TIME 70 9410
TEST_TIME 71 9286
TEST_TIME 72 9248
TEST_TIME 73 9201
TEST_TIME 74 9200
TEST_TIME 75 9166
TEST_TIME 76 9138
TEST_TIME 77 9061
TEST_TIME 78 9027
TEST_TIME 79 8980
TEST_TIME 80 9014
TEST_TIME 81 9001
TEST_TIME 82 8985
TEST_TIME 83 9043
TEST_TIME 84 9072
TEST_TIME 85 9078
TEST_TIME 86 9161
TEST_TIME 87 9148
TEST_TIME 88 9115
TEST_TIME 89 9173
TEST_TIME 90 9211
TEST_TIME 91 9192
TEST_TIME 92 9140
TEST_TIME 93 9105
TEST_TIME 94 9089
TEST_TIME 95 9064
TEST_TIME 96 9016
TEST_TIME 97 8961
TEST_TIME 98 8911
TEST_TIME 99 8946
TEST_TIME 100 8908
TEST_TIME 101 8881
TEST_TIME 102 8948
TEST_TIME 103 8917
TEST_TIME 104 8922
TEST_TIME 105 8927
TEST_TIME 106 8940
TEST_TIME 107 9020
TEST_TIME 108 8982
TEST_TIME 109 9017
TEST_TIME 110 9011
TEST_TIME 111 8983
TEST_TIME 112 8992
TEST_TIME 113 8937
TEST_TIME 114 8929
TEST_TIME 115 8895
TEST_TIME 116 8846
TEST_TIME 117 8818
TEST_TIME 118 8801
TEST_TIME 119 8813
TEST_TIME 120 8755

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-07-2022 13:38:53 to 09-07-2022 13:38:57.

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 1462920 1462920 0 0.00 0 25154 14 0 0
doc_list_biud 1462907 1462907 0 0.00 0 36177 50 0 0
sp_add_invoice_to_stock 4938 4808 130 2.63 47 216181 27437 0 130
sp_cancel_adding_invoice 5201 5201 0 0.00 5 103925 1551 0 0
sp_cancel_client_order 1700 1700 0 0.00 6 12162 495 0 0
sp_cancel_customer_reserve 516629 516629 0 0.00 1 36179 124 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 8094 152 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 1909 135 0 0
sp_cancel_supplier_invoice 5808 5801 7 0.12 2 24845 1210 0 7
sp_cancel_supplier_order 405 405 0 0.00 6 1733287 200259 0 0
sp_cancel_write_off 9537 9537 0 0.00 2 36184 191 0 0
sp_client_order 20541 20541 0 0.00 6 28887 503 0 0
sp_customer_reserve 447460 447460 0 0.00 0 156635 257 0 0
sp_fill_shopping_cart 31433 31433 0 0.00 1 11309 90 0 0
sp_fill_shopping_cart_clo_res 447425 447425 0 0.00 0 16462 21 0 0
sp_get_clo_for_invoice 4767 4767 0 0.00 0 30512 276 0 0
sp_kill_cost_storno 15821 15821 0 0.00 0 11307 93 0 0
sp_kill_qstorno_ret_qs2qd 1049001 1049001 0 0.00 0 36176 53 0 0
sp_kill_qty_storno 554009 554009 0 0.00 0 36177 122 0 0
sp_lock_selected_doc 20354 20354 0 0.00 0 8434 9 0 0
sp_make_cost_storno 27657 27657 0 0.00 0 13075 95 0 0
sp_multiply_rows_for_pdistr 27656 27656 0 0.00 0 7977 43 0 0
sp_multiply_rows_for_qdistr 20540 20540 0 0.00 0 22400 198 0 0
sp_pay_from_customer 7115 7115 0 0.00 5 14218 198 0 0
sp_pay_to_supplier 5805 5805 0 0.00 6 17681 224 0 0
sp_payment_common 12920 12920 0 0.00 2 11463 52 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 12120 417 0 0
sp_qd_handle_on_invoice_upd_sts 9965 9965 0 0.00 0 25208 605 0 0
sp_qd_handle_on_reserve_upd_sts 9972 9972 0 0.00 0 23020 77 0 0
sp_reserve_write_off 9972 9972 0 0.00 8 23084 326 0 0
sp_supplier_invoice 5442 5442 0 0.00 10 73194 3452 0 0
sp_supplier_order 5462 5462 0 0.00 8 33670 1417 0 0
srv_aggregate_perf_data 119 119 0 0.00 1923 143212 14290 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 670 262672 36269 0 0
srv_find_qd_qs_mism 1001473 1001473 0 0.00 0 25151 10 0 0
srv_make_invnt_saldo 442 442 0 0.00 122 73711 4892 0 0
srv_make_money_saldo 907 907 0 0.00 1 24045 451 0 0
srv_recalc_idx_stat 5 5 0 0.00 51495 384961 227147 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4811 4811 0 0.00 1 13893 1053 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 6797 579 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 1011 28 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1713 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 236 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1149 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 1424 46 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 81488 7840 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1521 23 0 0
t$perf-norm:sp_client_order 20542 20542 0 0.00 0 23238 42 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 1363 15 0 0
t$perf-norm:sp_pay_from_customer 7115 7115 0 0.00 0 15759 23 0 0
t$perf-norm:sp_pay_to_supplier 5805 5805 0 0.00 0 2239 21 0 0
t$perf-norm:sp_reserve_write_off 9973 9973 0 0.00 0 7059 24 0 0
t$perf-norm:sp_supplier_invoice 5442 5442 0 0.00 0 5330 33 0 0
t$perf-norm:sp_supplier_order 5462 5462 0 0.00 0 2210 36 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 280 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 297 15 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 850 22 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 4712 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 174 114 0 0
v_add_invoice_to_stock 9778 9518 260 2.65 0 6433 29 0 260
v_all_customers 37584 37584 0 0.00 0 69 4 0 0
v_all_suppliers 30618 30618 0 0.00 0 89 5 0 0
v_all_wares 41079 41079 0 0.00 0 171 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 3183 19 0 0
v_cancel_client_order 3400 3400 0 0.00 0 3117 11 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 2163 14 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 5237 22 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1141 15 0 0
v_cancel_supplier_invoice 750 736 14 1.86 0 2651 28 0 14
v_cancel_supplier_order 810 810 0 0.00 0 426 12 0 0
v_cancel_write_off 1972 1972 0 0.00 0 1553 27 0 0
v_max_id_clo_ord 5460 5460 0 0.00 0 1066 6 0 0
v_max_id_clo_res 950 950 0 0.00 0 1407 18 0 0
v_max_id_ord_sup 5432 5432 0 0.00 0 4202 9 0 0
v_max_non_paid_invoice 1388 1388 0 0.00 0 87 9 0 0
v_max_non_paid_realizn 3709 3709 0 0.00 0 4601 9 0 0
v_min_id_clo_ord 5460 5460 0 0.00 0 10962 12 0 0
v_min_id_clo_res 950 950 0 0.00 0 7885 24 0 0
v_min_id_ord_sup 5432 5432 0 0.00 0 4542 9 0 0
v_min_non_paid_invoice 5805 5805 0 0.00 0 11432 19 0 0
v_min_non_paid_realizn 7115 7115 0 0.00 0 10622 20 0 0
v_our_firm 10304 10304 0 0.00 0 135 5 0 0
v_reserve_write_off 19942 19942 0 0.00 0 4671 13 0 0
x_lock_depdocs_on_canc_invoice 10634 10634 0 0.00 0 20449 146 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 15365 435 0 0
x_make_qstorno_1000_1200 5462 5462 0 0.00 2 33653 1224 0 0
x_make_qstorno_1200_2000 5442 5442 0 0.00 4 73157 3208 0 0
x_make_qstorno_2100_3300 447460 447460 0 0.00 0 156634 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-07-2022 13:38:58 to 09-07-2022 13:39:02.

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
11:07:21 9 8192000000 8801504688 8813535232 6509232 0.079 3 1 2 0 6610432 65424 35776
11:08:22 17043 8192000000 9722187264 10018897920 800531632 8.902 100 92 184 0 831724832 7643552 23549648
11:09:49 21445 8192000000 9764672608 10074681344 842478576 9.325 100 88 177 0 874720784 10361344 21880864
11:11:14 12921 8192000000 9796644160 10118766592 872990048 9.630 100 83 166 0 910060832 17213776 19857008
11:12:31 12417 8192000000 9851404032 10181718016 915362400 10.050 100 78 156 0 961926528 29473184 17090944
11:13:48 6752 8192000000 9927744960 10271952896 967769168 10.565 100 91 179 0 1037455024 51305840 18380016
11:14:58 2685 8192000000 9965295312 10320273408 994244080 10.823 100 89 171 0 1084159584 72545104 17370400
11:16:05 1546 8192000000 9991232976 10349871104 1016152096 11.035 100 94 184 0 1111012560 76452112 18408352
11:17:09 1227 8192000000 10012120688 10380341248 1036294688 11.229 100 94 184 0 1132172656 76556496 19321472
11:18:11 1328 8192000000 10031762400 10407407616 1060948848 11.466 100 96 183 0 1146127824 65557408 19621568
11:19:14 1151 8192000000 10046589904 10425176064 1066090096 11.515 100 91 178 0 1167225136 83117456 18017584
11:20:19 1280 8192000000 10066208752 10448482304 1069887008 11.551 100 98 186 1 1184216352 95729184 18600160
11:21:23 1121 8192000000 10036812272 10413891584 1075229072 11.602 100 94 184 2 1155681216 61910048 18542096
11:22:25 1123 8192000000 10050863616 10437038080 1079394512 11.642 100 98 187 2 1172616672 74761552 18460608
11:23:30 1091 8192000000 10050649936 10433228800 1087781680 11.722 100 95 186 0 1170071520 63730848 18558992
11:24:32 981 8192000000 10059148128 10446925824 1094047184 11.781 100 88 174 0 1184259360 72416304 17795872
11:25:41 1212 8192000000 10069585856 10457260032 1096320032 11.803 100 99 192 2 1193994656 77676576 19998048
11:26:43 3057 8192000000 10056011152 10443165696 1098065392 11.819 100 87 167 0 1178083600 65090736 14927472
11:27:47 1326 8192000000 10074550016 10467868672 1103168304 11.868 100 84 164 0 1197317264 77770160 16378800
11:28:49 1072 8192000000 10075989008 10467684352 1106750272 11.902 100 92 178 4 1202740464 77252336 18737856
11:29:52 2232 8192000000 10050496768 10444087296 1088711824 11.730 99 75 143 2 1174221536 71564368 13945344
11:30:56 2356 8192000000 10062591888 10455580672 1089232624 11.735 99 71 139 0 1174412560 70741344 14438592
11:32:00 1615 8192000000 10039172160 10427723776 1078524384 11.633 99 77 143 4 1156007520 63005776 14477360
11:33:03 1022 8192000000 10024423920 10404274176 1056021952 11.418 100 88 161 1 1146349456 73070016 17257488
11:34:05 1251 8192000000 9989743824 10366480384 1043233392 11.296 100 88 169 0 1112735488 51308896 18193200
11:35:07 1363 8192000000 10006336432 10378362880 1050201760 11.363 100 92 176 3 1127991248 59118000 18671488
11:36:10 1321 8192000000 9980331936 10347212800 1022231792 11.094 99 95 182 4 1100417968 60672704 17513472
11:37:12 1017 8192000000 9980191936 10358353920 1048142576 11.343 100 89 171 2 1101932384 36312176 17477632
11:38:19 4613 8192000000 10035729552 10421813248 1059613424 11.453 100 85 161 0 1151883584 76444384 15825776
11:39:31 1218 8192000000 10031625968 10412888064 1078502736 11.633 100 87 164 0 1153005776 57101376 17401664
11:40:43 1534 8192000000 10053628208 10438012928 1087257296 11.717 100 87 166 0 1170398080 66279328 16861456
11:41:47 1794 8192000000 10102103088 10481270784 1091830640 11.760 100 88 168 0 1206958032 97761760 17365632
11:42:51 1228 8192000000 10102049712 10491756544 1103630016 11.872 100 98 191 0 1223445568 99540304 20275248
11:43:57 3558 8192000000 10110254256 10509307904 1115462304 11.984 100 68 133 1 1232209712 102940096 13807312
11:45:06 2234 8192000000 10126146320 10524618752 1127515312 12.098 100 71 138 1 1244823072 102870288 14437472
11:46:14 3457 8192000000 10150017184 10552430592 1143333904 12.247 100 69 134 1 1268965696 110868464 14763328
11:47:25 1652 8192000000 10151831040 10550960128 1155132512 12.358 100 84 155 0 1266179568 94459792 16587264
11:48:31 3950 8192000000 10201518384 10601668608 1158426944 12.389 100 82 159 0 1316627968 140630128 17570896
11:49:42 2570 8192000000 10229860944 10640723968 1164512240 12.446 100 90 172 0 1348219344 163280752 20426352
11:50:50 1562 8192000000 10265396000 10678398976 1176892944 12.561 100 95 182 4 1374014416 176882128 20239344
11:51:56 2849 8192000000 10285627440 10688368640 1193120480 12.712 100 83 153 0 1402034736 192015088 16899168
11:53:06 1449 8192000000 10307609568 10717229056 1184938880 12.636 99 74 143 0 1414295968 212346320 17010768
11:54:16 1387 8192000000 10359915856 10768310272 1200502080 12.781 100 87 165 1 1470426576 250690320 19234176
11:55:22 1211 8192000000 10386039232 10804154368 1210537536 12.874 100 84 166 0 1507869072 277815280 19516256
11:56:30 1792 8192000000 10411694672 10832691200 1207550064 12.846 100 92 176 0 1521671168 292862816 21258288
11:57:35 746 8192000000 10413789344 10846175232 1196049184 12.740 100 99 191 1 1520454432 301652160 22753088
11:58:42 661 8192000000 10465202544 10889207808 1217887840 12.942 100 96 191 1 1571951904 331573360 22490704
11:59:47 776 8192000000 10487555568 10893873152 1205846448 12.831 100 97 190 3 1593903328 364542960 23513920
12:00:52 1119 8192000000 10523307808 10939686912 1204360928 12.817 100 96 184 0 1627302960 400941184 22000848
12:01:56 1268 8192000000 10554789072 10972868608 1193130000 12.712 100 98 185 2 1658559936 443685744 21744192
12:02:59 753 8192000000 10567434880 10985402368 1181924144 12.608 100 95 188 0 1672116384 467737856 22454384
12:04:03 1363 8192000000 10588129504 11042578432 1205035488 12.823 100 83 159 1 1693972368 467785184 21151696
12:05:07 3433 8192000000 10618149328 11032616960 1193107328 12.712 100 80 154 0 1714702752 502716704 18878720
12:06:15 5031 8192000000 10626025440 11062001664 1193993664 12.721 100 62 120 1 1728818640 519594752 15230224
12:07:26 5053 8192000000 10601948960 11058204672 1175571632 12.549 100 55 107 1 1704605264 515042224 13991408
12:08:42 9585 8192000000 10631847888 11076358144 1188981488 12.674 100 55 110 1 1748704208 546130832 13591888
12:09:59 3243 8192000000 10650742816 11129077760 1186802400 12.654 100 71 142 1 1748853408 544833904 17217104
12:11:12 6529 8192000000 10559268304 11034537984 1189098736 12.675 100 46 90 1 1671488464 470586896 11802832
12:12:28 30418 8192000000 10544288192 11008233472 1181387984 12.603 100 39 74 1 1623274448 432305744 9580720
12:14:07 21513 8192000000 10443799392 10905780224 1161118320 12.414 99 33 62 0 1559294752 389189040 8987392
12:15:37 18876 8192000000 10269047872 10721312768 1172628128 12.521 100 35 70 0 1399224224 218415616 8180480
12:16:56 33465 8192000000 10234508656 10683666432 1133416512 12.154 98 42 76 2 1327340752 183805040 10119200
12:18:43 56606 8192000000 10085770560 10607513600 1132241056 12.142 99 10 17 0 1228463024 94500192 1721776
12:20:43 72197 8192000000 10078947920 10510835712 1098603296 11.824 96 33 62 0 1126759280 21527664 6628320
12:22:57 60369 8192000000 10127947072 10574729216 1123478384 12.060 98 22 45 0 1197398976 69396336 4524256
12:25:02 262671 8192000000 10060935600 10520698880 1042671024 11.290 91 9 18 0 1089036576 44250736 2114816
12:30:30 146056 8192000000 10095273776 10564751360 1111723376 11.949 98 32 61 1 1158074400 39247312 7103712
12:34:21 30215 8192000000 10100465824 10531823616 1114288400 11.973 98 47 82 4 1169522416 46578304 8655712
12:35:54 113344 8192000000 10111575216 10535698432 1128280368 12.105 98 73 116 3 1183141600 41810112 13051120
12:38:50 4724 8192000000 10134618208 10565316608 1150226336 12.312 100 54 96 1 1230243808 70157296 9860176
12:39:57 1770 8192000000 10186168736 10622128128 1154242976 12.349 100 65 129 6 1274090352 105571904 14275472
12:41:05 6697 8192000000 10163505728 10601525248 1150736512 12.316 100 51 97 1 1254176400 92173296 11266592
12:42:14 186305 8192000000 10095797104 10608513024 1058866608 11.446 92 22 44 0 1172062624 108082560 5113456
12:46:30 57431 8192000000 10063066784 10505457664 1108008032 11.914 98 19 39 0 1134860448 23145680 3706736
12:48:29 85148 8192000000 10077872432 10557579264 1111286448 11.945 98 21 42 0 1173115488 56931664 4897376
12:51:13 47336 8192000000 10069898944 10548224000 1083045632 11.676 96 26 48 2 1145153008 56931904 5175472
12:53:04 68481 8192000000 10040558144 10511818752 1084681264 11.692 97 12 24 0 1129874656 42779568 2413824
12:55:18 70736 8192000000 10082331264 10537701376 1098410480 11.823 98 16 32 1 1159486672 57463856 3612336
12:57:29 36012 8192000000 10099171680 10569236480 1117676464 12.005 100 28 56 0 1209361552 85349984 6335104
12:59:29 12682 8192000000 10167520256 10603794432 1133429680 12.154 100 42 84 0 1251110816 108896176 8784960
13:00:44 45740 8192000000 10146363472 10599915520 1132401408 12.144 100 24 48 0 1245107328 106998528 5707392
13:02:39 32661 8192000000 10128162848 10569043968 1112540672 11.956 99 25 50 0 1215040528 96919024 5580832
13:04:19 14716 8192000000 10073585648 10491834368 1124078816 12.066 100 57 108 3 1173418672 37369344 11970512
13:05:36 37022 8192000000 10131725696 10597433344 1137829120 12.195 100 37 73 2 1221893856 76406448 7658288
13:07:15 53012 8192000000 10107781600 10555887616 1129505984 12.117 99 36 69 0 1185199824 49130816 6563024
13:09:11 118264 8192000000 10103997952 10575806464 1142964352 12.243 100 26 52 0 1195566576 47405184 5197040
13:12:11 96054 8192000000 10051832208 10493206528 1082258208 11.669 95 13 25 0 1102499184 17351056 2889920
13:14:50 66035 8192000000 10119073968 10584887296 1118774016 12.015 98 12 24 0 1172740080 51145056 2821008
13:17:34 47427 8192000000 10144887104 10589822976 1140841504 12.223 99 20 40 0 1229815744 84542960 4431280
13:19:26 42164 8192000000 10127377488 10585956352 1110527968 11.937 96 29 58 1 1178325696 60539456 7258272
13:21:20 84818 8192000000 10163961856 10606174208 1119502240 12.022 98 24 48 0 1238890512 113717792 5670480
13:23:56 72902 8192000000 10085615200 10553208832 1116558656 11.994 98 17 33 0 1185381168 65690880 3131632
13:26:11 36664 8192000000 10082480656 10536574976 1077966560 11.628 95 42 78 1 1149466080 63210592 8288928
13:27:49 19382 8192000000 10127974528 10555908096 1125120096 12.075 100 66 133 2 1220852224 82320368 13411760
13:29:08 87904 8192000000 10049320544 10519171072 1111464704 11.946 98 37 74 1 1162610784 43188016 7958064
13:31:37 54472 8192000000 10084490336 10555363328 1135514240 12.173 100 16 32 0 1202373152 63548272 3310640
13:33:39 50265 8192000000 10055089520 10515296256 1090397600 11.746 96 15 28 0 1127289248 33755312 3136336
13:35:30 78314 8192000000 10105084272 10600951808 1121013344 12.037 99 17 34 0 1167271728 42144816 4113568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-07-2022 13:39:04 to 09-07-2022 13:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-07-2022 13:39:20 to 09-07-2022 13:39:22.

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          216465
MON$OLDEST_ACTIVE               217905
MON$OLDEST_SNAPSHOT             217905
MON$NEXT_TRANSACTION            217905
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                       2096128
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

--- start of diff output ---
:163: 
:164: mscs-test-161.budzdorov.ru    Sat Jul  9 13:37:20 2022
:165:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:166: 
:167: 
:168: mscs-test-161.budzdorov.ru    Sat Jul  9 13:37:21 2022
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:170: 
:171: 
:172: mscs-test-161.budzdorov.ru    Sat Jul  9 13:37:25 2022
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Sat Jul  9 13:37:26 2022
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:178: 
--- end of diff output ---

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

09.07.22 13:39:23. 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-07-09 13:39:26 No severe PSQL-related problems occured
09.07.22 13:39:27. End of report.