Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.09.2021 10:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15609740 kB
MemAvailable 30837460 kB
Buffers 0 kB
Cached 16047532 kB
SwapCached 380 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.1
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 2021-09-10 10:37:09
DTS_END                         2021-09-10 10:37:09.5000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48632
TRN_ID                          229828
ATT_ID                          611
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: 8968.50 31697 1076220
customer order: creation 161.30 409 19356
customer order: refuse 13.88 420 1665
order to supplier: creation 44.18 1502 5301
order to supplier: removal 3.54 137621 425
invoice (draft): creation 44.25 4143 5310
invoice (draft): removal 51.50 1193 6180
invoice accept: apply 40.61 19691 4873
invoice accept: cancel 46.68 1085 5602
customer reserve: creation 3749.16 190 449899
customer reserve: removal 4531.81 81 543817
realization accept: apply 79.82 206 9578
realization accept: cancel 77.03 151 9243
payment from customer: creation 58.03 128 6963
payment from customer: removal 5.35 93 642
payment to supplier: creation 47.32 138 5678
payment to supplier: removal 3.33 128 400
service: total inventory turnovers 3.28 5118 393
service: total monetary turnovers 6.56 673 787
service: aggregate perf. data 0.86 18675 103
service: refresh index statistics 0.04 442290 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-09-2021 10:38:18 to 10-09-2021 10:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5186
TEST_TIME 2 4877
TEST_TIME 3 4582
TEST_TIME 4 4373
TEST_TIME 5 5034
TEST_TIME 6 5050
TEST_TIME 7 5127
TEST_TIME 8 5176
TEST_TIME 9 5668
TEST_TIME 10 5867
TEST_TIME 11 5832
TEST_TIME 12 6051
TEST_TIME 13 6059
TEST_TIME 14 6619
TEST_TIME 15 6790
TEST_TIME 16 6762
TEST_TIME 17 7135
TEST_TIME 18 7134
TEST_TIME 19 7386
TEST_TIME 20 7478
TEST_TIME 21 7494
TEST_TIME 22 7533
TEST_TIME 23 7388
TEST_TIME 24 7674
TEST_TIME 25 8363
TEST_TIME 26 8509
TEST_TIME 27 8634
TEST_TIME 28 8638
TEST_TIME 29 9144
TEST_TIME 30 9626
TEST_TIME 31 10355
TEST_TIME 32 10942
TEST_TIME 33 10777
TEST_TIME 34 10800
TEST_TIME 35 10851
TEST_TIME 36 10632
TEST_TIME 37 10537
TEST_TIME 38 10427
TEST_TIME 39 10379
TEST_TIME 40 10220
TEST_TIME 41 10170
TEST_TIME 42 10121
TEST_TIME 43 10092
TEST_TIME 44 10214
TEST_TIME 45 10177
TEST_TIME 46 10094
TEST_TIME 47 10026
TEST_TIME 48 10123
TEST_TIME 49 10151
TEST_TIME 50 10140
TEST_TIME 51 9975
TEST_TIME 52 9881
TEST_TIME 53 9748
TEST_TIME 54 9640
TEST_TIME 55 9542
TEST_TIME 56 9539
TEST_TIME 57 9462
TEST_TIME 58 9480
TEST_TIME 59 9544
TEST_TIME 60 9465
TEST_TIME 61 9486
TEST_TIME 62 9439
TEST_TIME 63 9524
TEST_TIME 64 9466
TEST_TIME 65 9364
TEST_TIME 66 9302
TEST_TIME 67 9269
TEST_TIME 68 9265
TEST_TIME 69 9179
TEST_TIME 70 9147
TEST_TIME 71 9064
TEST_TIME 72 9103
TEST_TIME 73 9065
TEST_TIME 74 9041
TEST_TIME 75 9123
TEST_TIME 76 9183
TEST_TIME 77 9188
TEST_TIME 78 9220
TEST_TIME 79 9185
TEST_TIME 80 9258
TEST_TIME 81 9291
TEST_TIME 82 9289
TEST_TIME 83 9321
TEST_TIME 84 9223
TEST_TIME 85 9160
TEST_TIME 86 9172
TEST_TIME 87 9133
TEST_TIME 88 9110
TEST_TIME 89 9068
TEST_TIME 90 9066
TEST_TIME 91 9034
TEST_TIME 92 9041
TEST_TIME 93 9015
TEST_TIME 94 9048
TEST_TIME 95 9160
TEST_TIME 96 9134
TEST_TIME 97 9177
TEST_TIME 98 9240
TEST_TIME 99 9283
TEST_TIME 100 9277
TEST_TIME 101 9263
TEST_TIME 102 9237
TEST_TIME 103 9171
TEST_TIME 104 9145
TEST_TIME 105 9162
TEST_TIME 106 9165
TEST_TIME 107 9162
TEST_TIME 108 9136
TEST_TIME 109 9102
TEST_TIME 110 9090
TEST_TIME 111 9069
TEST_TIME 112 9031
TEST_TIME 113 9006
TEST_TIME 114 9014
TEST_TIME 115 8998
TEST_TIME 116 9030
TEST_TIME 117 9062
TEST_TIME 118 9058
TEST_TIME 119 9034
TEST_TIME 120 8970

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-09-2021 10:38:22 to 10-09-2021 10:38:25.

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 1491654 1491654 0 0.00 0 17955 7 0 0
doc_list_biud 1491652 1491652 0 0.00 0 29302 38 0 0
sp_add_invoice_to_stock 5030 4873 157 3.12 31 187973 19691 0 157
sp_cancel_adding_invoice 5602 5602 0 0.00 5 73202 1085 0 0
sp_cancel_client_order 1665 1665 0 0.00 5 10747 421 0 0
sp_cancel_customer_reserve 543817 543817 0 0.00 1 29311 81 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 6987 93 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 11194 128 0 0
sp_cancel_supplier_invoice 6193 6180 13 0.20 2 28106 1194 0 13
sp_cancel_supplier_order 425 425 0 0.00 9 1218625 137622 0 0
sp_cancel_write_off 9243 9243 0 0.00 1 17956 152 0 0
sp_client_order 19356 19356 0 0.00 4 59122 409 0 0
sp_customer_reserve 449899 449899 0 0.00 0 41717 190 0 0
sp_fill_shopping_cart 29964 29964 0 0.00 1 19051 53 0 0
sp_fill_shopping_cart_clo_res 449898 449898 0 0.00 0 17531 17 0 0
sp_get_clo_for_invoice 4872 4872 0 0.00 0 34405 422 0 0
sp_kill_cost_storno 15887 15887 0 0.00 0 17948 82 0 0
sp_kill_qstorno_ret_qs2qd 1103482 1103482 0 0.00 0 29292 44 0 0
sp_kill_qty_storno 581381 581381 0 0.00 0 29299 96 0 0
sp_lock_selected_doc 20056 20056 0 0.00 0 8000 6 0 0
sp_make_cost_storno 27091 27091 0 0.00 0 13502 64 0 0
sp_multiply_rows_for_pdistr 27090 27090 0 0.00 0 14894 38 0 0
sp_multiply_rows_for_qdistr 19356 19356 0 0.00 0 59103 203 0 0
sp_pay_from_customer 6963 6963 0 0.00 4 16693 129 0 0
sp_pay_to_supplier 5678 5678 0 0.00 5 14409 139 0 0
sp_payment_common 12640 12640 0 0.00 1 12908 22 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 0 10737 375 0 0
sp_qd_handle_on_invoice_upd_sts 10473 10473 0 0.00 0 20670 501 0 0
sp_qd_handle_on_reserve_upd_sts 9578 9578 0 0.00 0 14130 67 0 0
sp_reserve_write_off 9578 9578 0 0.00 7 14153 206 0 0
sp_supplier_invoice 5310 5310 0 0.00 9 96628 4143 0 0
sp_supplier_order 5301 5301 0 0.00 5 48014 1503 0 0
srv_aggregate_perf_data 103 103 0 0.00 2627 182736 18675 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 699 138530 40637 0 0
srv_find_qd_qs_mism 1029677 1029677 0 0.00 0 13134 5 0 0
srv_make_invnt_saldo 393 393 0 0.00 53 118592 5119 0 0
srv_make_money_saldo 787 787 0 0.00 1 15666 673 0 0
srv_recalc_idx_stat 5 5 0 0.00 340365 642159 442290 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4874 4874 0 0.00 0 12761 233 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 4715 121 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 3849 15 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 4471 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 3754 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 289 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 357 357 0 0.00 0 4434 38 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 30771 2037 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 3406 13 0 0
t$perf-norm:sp_client_order 19356 19356 0 0.00 0 15790 28 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 421 5 0 0
t$perf-norm:sp_pay_from_customer 6963 6963 0 0.00 0 13985 18 0 0
t$perf-norm:sp_pay_to_supplier 5678 5678 0 0.00 0 5501 13 0 0
t$perf-norm:sp_reserve_write_off 9578 9578 0 0.00 0 9966 16 0 0
t$perf-norm:sp_supplier_invoice 5310 5310 0 0.00 0 20891 30 0 0
t$perf-norm:sp_supplier_order 5301 5301 0 0.00 0 5398 28 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 915 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 863 19 0 0
t$perf-norm:srv_make_invnt_saldo 393 393 0 0.00 0 1644 24 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 616 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 36 15 0 0
v_add_invoice_to_stock 10056 9742 314 3.12 0 7408 22 0 314
v_all_customers 35672 35672 0 0.00 0 46 1 0 0
v_all_suppliers 29520 29520 0 0.00 0 26 1 0 0
v_all_wares 38712 38712 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 901 8 0 0
v_cancel_client_order 3330 3330 0 0.00 0 4631 9 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 3021 7 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 2310 7 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2940 12 0 0
v_cancel_supplier_invoice 740 714 26 3.51 0 1284 18 0 26
v_cancel_supplier_order 850 850 0 0.00 0 1224 8 0 0
v_cancel_write_off 2052 2052 0 0.00 0 4992 20 0 0
v_max_id_clo_ord 5301 5301 0 0.00 0 377 2 0 0
v_max_id_clo_res 956 956 0 0.00 0 2558 11 0 0
v_max_id_ord_sup 5307 5307 0 0.00 0 4549 5 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 391 3 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 2155 3 0 0
v_min_id_clo_ord 5301 5301 0 0.00 0 6967 10 0 0
v_min_id_clo_res 956 956 0 0.00 0 5191 13 0 0
v_min_id_ord_sup 5307 5307 0 0.00 0 14812 11 0 0
v_min_non_paid_invoice 5677 5677 0 0.00 0 6438 14 0 0
v_min_non_paid_realizn 6963 6963 0 0.00 0 6877 15 0 0
v_our_firm 9872 9872 0 0.00 0 26 1 0 0
v_reserve_write_off 19156 19156 0 0.00 0 5051 8 0 0
x_lock_depdocs_on_canc_invoice 11425 11425 0 0.00 0 12134 159 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 17841 556 0 0
x_make_qstorno_1000_1200 5301 5301 0 0.00 2 47997 1401 0 0
x_make_qstorno_1200_2000 5310 5310 0 0.00 4 96616 3949 0 0
x_make_qstorno_2100_3300 449899 449899 0 0.00 0 40249 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-09-2021 10:38:26 to 10-09-2021 10:38:30.

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
08:07:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 30826 8192000000 9716641776 10012209152 793745952 8.833 100 82 164 0 821144944 7456816 19942176
08:09:49 19505 8192000000 9750334064 10056105984 828977328 9.189 100 86 172 0 860608528 11049296 20581904
08:11:13 20337 8192000000 9795778816 10109509632 859464720 9.495 100 88 176 0 896964624 16938256 20561648
08:12:36 14341 8192000000 9836176480 10166558720 903748432 9.935 100 72 145 0 950669424 31673920 15247072
08:13:53 8204 8192000000 9912735376 10251706368 947591296 10.367 100 83 165 2 1025709040 60775504 17342240
08:15:08 4627 8192000000 9962067424 10310283264 978347024 10.668 100 87 174 1 1076481856 79851168 18283664
08:16:14 3694 8192000000 9985754704 10341572608 1009907248 10.974 100 91 182 1 1109941696 81789680 18244768
08:17:20 2128 8192000000 10000566080 10363801600 1031568592 11.184 100 91 181 0 1115774432 66076896 18128944
08:18:23 1827 8192000000 10023721440 10392494080 1048236208 11.344 100 84 166 0 1133175280 67279904 17659168
08:19:27 26875 8192000000 10005654672 10387877888 1058733248 11.444 100 62 124 3 1129634016 58051120 12849648
08:20:54 5241 8192000000 10010397424 10401251328 1072258176 11.574 100 54 109 0 1127539936 43899024 11382736
08:22:02 8393 8192000000 9986982896 10392666112 1078061504 11.629 100 30 60 0 1099930944 15846912 6022528
08:23:12 54299 8192000000 9998135920 10390806528 1084935152 11.694 100 56 113 0 1110240368 12441328 12863888
08:25:07 4377 8192000000 10006952512 10405601280 1088878608 11.732 100 45 91 3 1129694704 31013824 9802272
08:26:13 8973 8192000000 10027902832 10431393792 1093326832 11.774 100 30 60 0 1117979472 18147008 6505632
08:27:25 5828 8192000000 9982690624 10377842688 1058235696 11.440 98 23 45 3 1084983456 22096064 4651696
08:28:32 46797 8192000000 9980822576 10374164480 1053599280 11.395 99 40 79 2 1073565840 11477360 8489200
08:30:21 8751 8192000000 9956857904 10342158336 1025213984 11.122 99 30 62 0 1055015008 23443728 6357296
08:31:31 7598 8192000000 9947229072 10329907200 1011163904 10.987 98 36 72 0 1056104768 37065872 7874992
08:32:42 8168 8192000000 9938228416 10322849792 977493904 10.660 95 35 70 0 995945712 9415136 9036672
08:33:54 37835 8192000000 9968648560 10349506560 1038549744 11.251 100 48 97 1 1064623616 16612256 9461616
08:35:33 5189 8192000000 9948172176 10340790272 1044196912 11.305 100 48 95 2 1063652912 9764112 9691888
08:36:42 5192 8192000000 9978203520 10375118848 1058949280 11.446 100 43 87 1 1094829920 26751744 9128896
08:37:54 55332 8192000000 10007412064 10410385408 1075160976 11.601 100 38 76 1 1116962048 32976832 8824240
08:39:52 45010 8192000000 10053963664 10458243072 1094694768 11.787 100 54 105 2 1157195920 51974320 10526832
08:41:46 23672 8192000000 10134872480 10541326336 1103403648 11.870 100 63 126 0 1246168992 129937056 12828288
08:43:26 35649 8192000000 10122197072 10526425088 1118766608 12.015 100 44 88 0 1226556496 98481056 9308832
08:45:05 14069 8192000000 10167415792 10574913536 1133129408 12.151 100 54 106 2 1280382544 135655552 11597584
08:46:24 11091 8192000000 10177393344 10592632832 1131334000 12.134 100 45 87 0 1288299264 146823856 10141408
08:47:43 15433 8192000000 10196558816 10610786304 1147710672 12.288 100 46 92 0 1302702080 145374400 9617008
08:49:03 1781 8192000000 10238856704 10643107840 1174114736 12.535 100 73 144 3 1348499504 158774784 15609984
08:50:12 4896 8192000000 10290978032 10708389888 1163987344 12.441 100 57 110 1 1387794784 210535264 13272176
08:51:22 4874 8192000000 10289045040 10735484928 1161950448 12.422 100 79 157 0 1414800960 234888768 17961744
08:52:33 3938 8192000000 10339055872 10752225280 1182421696 12.613 100 69 136 1 1447404992 249702896 15280400
08:53:41 881 8192000000 10329823008 10754117632 1173685248 12.531 99 80 158 1 1420078368 229098704 17294416
08:54:48 2587 8192000000 10376422528 10778800128 1190158512 12.685 100 91 180 1 1472878528 262079408 20640608
08:55:59 915 8192000000 10393349280 10793742336 1174986128 12.543 100 88 173 0 1490839472 296011168 19842176
08:57:04 3026 8192000000 10394226624 10796900352 1169884608 12.496 99 89 174 2 1491777152 301428128 20464416
08:58:13 698 8192000000 10494211168 10891689984 1188306688 12.668 100 95 189 1 1588495024 378380384 21807952
08:59:20 2502 8192000000 10542043648 10946330624 1174602080 12.540 100 92 179 1 1630131360 434179776 21349504
09:00:28 1497 8192000000 10588646016 10994249728 1168544768 12.483 100 92 181 1 1678918112 488129728 22243616
09:01:34 2692 8192000000 10602454112 11021418496 1174282912 12.537 100 94 188 1 1703060896 505944656 22833328
09:02:39 16759 8192000000 10537669616 11010772992 1173257920 12.527 100 45 90 0 1663298992 477975744 12065328
09:04:02 2915 8192000000 10525035168 10950656000 1172517808 12.520 100 62 124 2 1619959728 432959888 14482032
09:05:11 3270 8192000000 10537163792 10953949184 1156246896 12.368 100 78 155 2 1628868976 453950624 18671456
09:06:16 9788 8192000000 10524250784 11000102912 1153513664 12.342 100 34 68 0 1626715792 463755648 9446480
09:07:30 9793 8192000000 10432173200 10918940672 1153768080 12.345 100 32 64 0 1536910880 374748624 8394176
09:08:42 16261 8192000000 10276145136 10821496832 1136224064 12.180 100 25 50 1 1447785232 304865728 6695440
09:10:31 43194 8192000000 10107436464 10590437376 1131553456 12.136 100 16 32 0 1254068320 119059744 3455120
09:12:15 27227 8192000000 10057313952 10528976896 1084546096 11.691 96 17 34 0 1132547040 44476672 3524272
09:13:43 41413 8192000000 10054576224 10509385728 1118732640 12.015 100 28 55 1 1144219184 19753376 5733168
09:15:26 72604 8192000000 10061846784 10520203264 1108857312 11.922 98 16 32 2 1154479648 42098576 3523760
09:17:39 99917 8192000000 10108022976 10555793408 1092047680 11.762 96 16 32 1 1159752480 64105920 3598880
09:20:23 76935 8192000000 10093821296 10593730560 1120403760 12.031 99 11 22 1 1213410736 90902848 2104128
09:22:42 49916 8192000000 10054570800 10477256704 1101123648 11.848 97 14 28 1 1128372624 24087056 3161920
09:24:33 131824 8192000000 10072755424 10560495616 1094652528 11.787 96 16 32 0 1163239232 65011776 3574928
09:27:50 138527 8192000000 10066798736 10525544448 1123933792 12.064 98 19 38 1 1154073232 26207488 3931952
09:31:09 12297 8192000000 10096698160 10527899648 1136747712 12.185 99 31 62 1 1180263344 36586160 6929472
09:32:26 15400 8192000000 10121456912 10547032064 1141442320 12.229 99 40 74 1 1213608416 64898640 7267456
09:33:42 53770 8192000000 10194030576 10632003584 1144176400 12.255 98 26 52 1 1255855856 105630496 6048960
09:36:01 64041 8192000000 10152043664 10641055744 1138583952 12.202 99 32 64 3 1265978000 120137216 7256832
09:38:12 63126 8192000000 10127849392 10610954240 1134967456 12.168 99 13 26 0 1225883008 88366704 2548848
09:40:17 34401 8192000000 10070906384 10561028096 1133892448 12.158 100 12 24 0 1174745968 38272944 2580576
09:41:53 71927 8192000000 10059078464 10496233472 1108373408 11.917 97 26 52 0 1140998240 27466656 5158176
09:44:10 44178 8192000000 10096511024 10565607424 1121864240 12.045 98 18 36 0 1189270432 63608576 3797616
09:46:11 48023 8192000000 10070670400 10559942656 1136576848 12.183 100 6 12 0 1192524176 54737200 1210128
09:48:35 24790 8192000000 10092210272 10533793792 1144183952 12.255 100 41 82 1 1192957488 40189216 8584320
09:50:05 40856 8192000000 10061512544 10497777664 1104365920 11.879 97 13 26 0 1131649616 24439520 2844176
09:51:52 59783 8192000000 10120066976 10598375424 1124106480 12.066 98 23 46 0 1231268992 102516400 4646112
09:54:24 51907 8192000000 10106110352 10575159296 1109519744 11.928 97 33 66 0 1160103184 44119664 6463776
09:56:17 103895 8192000000 10075857104 10557636608 1068161760 11.535 93 16 32 0 1128551440 57177232 3212448
09:59:15 37914 8192000000 10096905904 10562158592 1133203376 12.152 100 19 38 0 1183821248 46948400 3669472
10:00:53 121487 8192000000 10072510672 10525151232 1097390624 11.813 97 18 36 0 1138984944 37814080 3780240
10:03:57 38223 8192000000 10033705680 10515542016 1096839760 11.808 98 3 6 0 1127255600 29983392 432448
10:05:36 42542 8192000000 10032391488 10476662784 1101753600 11.854 98 20 40 0 1133896560 27662560 4480400
10:07:19 47857 8192000000 10052822912 10501582848 1112293744 11.954 98 18 36 2 1142445936 26627088 3525104
10:09:10 41738 8192000000 10100368256 10554814464 1122729680 12.053 98 21 42 1 1183252816 56102688 4420448
10:10:54 90072 8192000000 10126217776 10657546240 1121094496 12.037 98 15 31 0 1225108512 100743552 3270464
10:13:26 92357 8192000000 10097522128 10596753408 1075215216 11.602 94 13 24 2 1158546976 80650416 2681344
10:15:58 82716 8192000000 10078993152 10558791680 1090500576 11.747 96 5 10 0 1146851664 55348688 1002400
10:18:28 29238 8192000000 10156022192 10613432320 1143148208 12.245 100 37 74 0 1239597648 88920656 7528784
10:20:01 64992 8192000000 10128012096 10565349376 1134919664 12.168 99 44 87 2 1227292336 82421232 9951440
10:22:34 34630 8192000000 10098087696 10571309056 1140056528 12.216 100 21 42 1 1195437600 50743456 4637616
10:24:18 72256 8192000000 10072299856 10551037952 1131026368 12.131 99 11 22 0 1192754608 59322640 2405600
10:26:37 57477 8192000000 10096844336 10551930880 1123667360 12.062 98 15 30 0 1179311504 52715312 2928832
10:28:35 36867 8192000000 10068834256 10565156864 1128023632 12.103 99 12 23 0 1162290192 31917744 2348816
10:30:12 50198 8192000000 10110802976 10594091008 1113135744 11.962 97 15 30 0 1207200144 90884960 3179440
10:32:20 62945 8192000000 10071267328 10563694592 1111496416 11.947 98 13 26 1 1164353456 50247408 2609632
10:34:23 41683 8192000000 10064442544 10539474944 1110578976 11.938 98 33 66 1 1173676720 56437968 6659776
10:36:24 18008 8192000000 10107267344 10547900416 1140756224 12.223 100 47 91 1 1200720928 50687776 9276928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-09-2021 10:38:32 to 10-09-2021 10:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-09-2021 10:38:48 to 10-09-2021 10:38:50.

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          229137
MON$OLDEST_ACTIVE               231896
MON$OLDEST_SNAPSHOT             231896
MON$NEXT_TRANSACTION            231896
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                       2093504
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2587 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2587 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2587 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2587 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Fri Sep 10 10:37:13 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Sep 10 10:37:13 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Sep 10 10:37:14 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Sep 10 10:37:17 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

10.09.21 10:38:51. 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
2021-09-10 10:38:53 No severe PSQL-related problems occured
10.09.21 10:38:54. End of report.