Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.05.2022 07: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 16128492 kB
MemAvailable 31483464 kB
Buffers 0 kB
Cached 15540620 kB
SwapCached 2488 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-05-05 07:37:14
DTS_END                         2022-05-05 07:37:14.6020
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/42852
TRN_ID                          215716
ATT_ID                          628
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: 8986.50 27018 1078380
customer order: creation 163.41 464 19609
customer order: refuse 13.78 400 1653
order to supplier: creation 43.94 1359 5273
order to supplier: removal 3.28 165765 393
invoice (draft): creation 43.51 3122 5221
invoice (draft): removal 49.37 930 5924
invoice accept: apply 40.11 26101 4813
invoice accept: cancel 45.43 1154 5451
customer reserve: creation 3771.60 244 452592
customer reserve: removal 4528.91 96 543469
realization accept: apply 79.15 284 9498
realization accept: cancel 79.03 158 9483
payment from customer: creation 56.90 166 6828
payment from customer: removal 5.65 108 678
payment to supplier: creation 46.63 180 5595
payment to supplier: removal 3.54 127 425
service: total inventory turnovers 4.05 3797 486
service: total monetary turnovers 7.21 368 865
service: aggregate perf. data 0.99 14046 119
service: refresh index statistics 0.04 321487 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-05-2022 07:38:26 to 05-05-2022 07:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9163
TEST_TIME 2 7547
TEST_TIME 3 7032
TEST_TIME 4 7076
TEST_TIME 5 6591
TEST_TIME 6 6616
TEST_TIME 7 6555
TEST_TIME 8 6596
TEST_TIME 9 6578
TEST_TIME 10 6844
TEST_TIME 11 7063
TEST_TIME 12 7213
TEST_TIME 13 7358
TEST_TIME 14 7362
TEST_TIME 15 7563
TEST_TIME 16 8175
TEST_TIME 17 8558
TEST_TIME 18 8652
TEST_TIME 19 8675
TEST_TIME 20 8757
TEST_TIME 21 8849
TEST_TIME 22 8733
TEST_TIME 23 9034
TEST_TIME 24 9165
TEST_TIME 25 9026
TEST_TIME 26 8989
TEST_TIME 27 9396
TEST_TIME 28 9476
TEST_TIME 29 9796
TEST_TIME 30 10667
TEST_TIME 31 10998
TEST_TIME 32 11144
TEST_TIME 33 11362
TEST_TIME 34 11094
TEST_TIME 35 10926
TEST_TIME 36 10803
TEST_TIME 37 10711
TEST_TIME 38 10792
TEST_TIME 39 10828
TEST_TIME 40 10708
TEST_TIME 41 10599
TEST_TIME 42 10510
TEST_TIME 43 10410
TEST_TIME 44 10247
TEST_TIME 45 10161
TEST_TIME 46 10280
TEST_TIME 47 10396
TEST_TIME 48 10422
TEST_TIME 49 10336
TEST_TIME 50 10283
TEST_TIME 51 10273
TEST_TIME 52 10296
TEST_TIME 53 10293
TEST_TIME 54 10170
TEST_TIME 55 10001
TEST_TIME 56 9920
TEST_TIME 57 9838
TEST_TIME 58 9797
TEST_TIME 59 9679
TEST_TIME 60 9694
TEST_TIME 61 9619
TEST_TIME 62 9603
TEST_TIME 63 9647
TEST_TIME 64 9668
TEST_TIME 65 9613
TEST_TIME 66 9581
TEST_TIME 67 9578
TEST_TIME 68 9558
TEST_TIME 69 9543
TEST_TIME 70 9504
TEST_TIME 71 9530
TEST_TIME 72 9470
TEST_TIME 73 9553
TEST_TIME 74 9546
TEST_TIME 75 9454
TEST_TIME 76 9553
TEST_TIME 77 9636
TEST_TIME 78 9653
TEST_TIME 79 9740
TEST_TIME 80 9679
TEST_TIME 81 9639
TEST_TIME 82 9546
TEST_TIME 83 9501
TEST_TIME 84 9488
TEST_TIME 85 9412
TEST_TIME 86 9364
TEST_TIME 87 9331
TEST_TIME 88 9365
TEST_TIME 89 9373
TEST_TIME 90 9322
TEST_TIME 91 9277
TEST_TIME 92 9243
TEST_TIME 93 9197
TEST_TIME 94 9260
TEST_TIME 95 9223
TEST_TIME 96 9184
TEST_TIME 97 9245
TEST_TIME 98 9274
TEST_TIME 99 9241
TEST_TIME 100 9247
TEST_TIME 101 9223
TEST_TIME 102 9274
TEST_TIME 103 9345
TEST_TIME 104 9328
TEST_TIME 105 9334
TEST_TIME 106 9311
TEST_TIME 107 9301
TEST_TIME 108 9242
TEST_TIME 109 9210
TEST_TIME 110 9172
TEST_TIME 111 9200
TEST_TIME 112 9171
TEST_TIME 113 9138
TEST_TIME 114 9096
TEST_TIME 115 9070
TEST_TIME 116 9052
TEST_TIME 117 9030
TEST_TIME 118 9065
TEST_TIME 119 9085
TEST_TIME 120 9019

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-05-2022 07:38:30 to 05-05-2022 07:38:34.

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 1495598 1495598 0 0.00 0 26468 12 0 0
doc_list_biud 1495592 1495592 0 0.00 0 41754 40 0 0
sp_add_invoice_to_stock 4928 4813 115 2.33 59 218633 26102 0 115
sp_cancel_adding_invoice 5451 5451 0 0.00 7 109668 1155 0 0
sp_cancel_client_order 1653 1653 0 0.00 6 9858 401 0 0
sp_cancel_customer_reserve 543469 543469 0 0.00 1 28993 96 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 4 2597 108 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 7137 127 0 0
sp_cancel_supplier_invoice 5940 5924 16 0.26 2 18889 931 0 16
sp_cancel_supplier_order 393 393 0 0.00 13 1366676 165765 0 0
sp_cancel_write_off 9483 9483 0 0.00 2 8258 159 0 0
sp_client_order 19609 19609 0 0.00 6 15940 465 0 0
sp_customer_reserve 452592 452592 0 0.00 1 107110 245 0 0
sp_fill_shopping_cart 30095 30095 0 0.00 1 14804 85 0 0
sp_fill_shopping_cart_clo_res 452569 452569 0 0.00 0 14043 19 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 16699 312 0 0
sp_kill_cost_storno 16037 16037 0 0.00 0 9366 68 0 0
sp_kill_qstorno_ret_qs2qd 1102738 1102738 0 0.00 0 28916 42 0 0
sp_kill_qty_storno 580649 580649 0 0.00 0 41754 97 0 0
sp_lock_selected_doc 19830 19830 0 0.00 0 8363 7 0 0
sp_make_cost_storno 26699 26699 0 0.00 0 26450 76 0 0
sp_multiply_rows_for_pdistr 26698 26698 0 0.00 0 6976 36 0 0
sp_multiply_rows_for_qdistr 19609 19609 0 0.00 0 15249 172 0 0
sp_pay_from_customer 6828 6828 0 0.00 6 9918 166 0 0
sp_pay_to_supplier 5595 5595 0 0.00 6 10982 181 0 0
sp_payment_common 12422 12422 0 0.00 2 9871 40 0 0
sp_qd_handle_on_cancel_clo 1653 1653 0 0.00 0 9791 316 0 0
sp_qd_handle_on_invoice_upd_sts 10229 10229 0 0.00 0 41753 504 0 0
sp_qd_handle_on_reserve_upd_sts 9498 9498 0 0.00 0 22580 75 0 0
sp_reserve_write_off 9498 9498 0 0.00 8 26576 284 0 0
sp_supplier_invoice 5221 5221 0 0.00 10 74632 3122 0 0
sp_supplier_order 5273 5273 0 0.00 8 55135 1359 0 0
srv_aggregate_perf_data 119 119 0 0.00 2627 131317 14046 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 768 190325 43173 0 0
srv_find_qd_qs_mism 1031367 1031367 0 0.00 0 19129 9 0 0
srv_make_invnt_saldo 486 486 0 0.00 76 51388 3797 0 0
srv_make_money_saldo 865 865 0 0.00 1 11166 369 0 0
srv_recalc_idx_stat 5 5 0 0.00 92290 579211 321487 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 33110 988 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6052 502 0 0
t$perf-norm:sp_cancel_client_order 1653 1653 0 0.00 0 1940 27 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 1789 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 13476 39 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 365 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 692 32 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 108838 6572 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 626 16 0 0
t$perf-norm:sp_client_order 19609 19609 0 0.00 0 20016 36 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 20360 37 0 0
t$perf-norm:sp_pay_from_customer 6828 6828 0 0.00 0 12029 20 0 0
t$perf-norm:sp_pay_to_supplier 5595 5595 0 0.00 0 4710 20 0 0
t$perf-norm:sp_reserve_write_off 9498 9498 0 0.00 0 11708 27 0 0
t$perf-norm:sp_supplier_invoice 5222 5222 0 0.00 0 9986 32 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 9748 32 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 520 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 205 10 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 1126 15 0 0
t$perf-norm:srv_make_money_saldo 865 865 0 0.00 0 1875 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 221 63 0 0
v_add_invoice_to_stock 9778 9548 230 2.35 0 5297 27 0 230
v_all_customers 36164 36164 0 0.00 0 127 4 0 0
v_all_suppliers 29292 29292 0 0.00 0 114 4 0 0
v_all_wares 39214 39214 0 0.00 0 124 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2675 17 0 0
v_cancel_client_order 3306 3306 0 0.00 0 2941 16 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2559 14 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 3909 14 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 568 11 0 0
v_cancel_supplier_invoice 794 762 32 4.03 0 1948 29 0 32
v_cancel_supplier_order 786 786 0 0.00 0 440 10 0 0
v_cancel_write_off 1946 1946 0 0.00 0 1685 22 0 0
v_max_id_clo_ord 5270 5270 0 0.00 0 410 5 0 0
v_max_id_clo_res 964 964 0 0.00 0 1057 10 0 0
v_max_id_ord_sup 5216 5216 0 0.00 0 7346 9 0 0
v_max_non_paid_invoice 1434 1434 0 0.00 0 204 7 0 0
v_max_non_paid_realizn 3447 3447 0 0.00 0 9857 9 0 0
v_min_id_clo_ord 5270 5270 0 0.00 0 1391 7 0 0
v_min_id_clo_res 964 964 0 0.00 0 1412 9 0 0
v_min_id_ord_sup 5216 5216 0 0.00 0 6306 13 0 0
v_min_non_paid_invoice 5593 5593 0 0.00 0 2614 11 0 0
v_min_non_paid_realizn 6828 6828 0 0.00 0 4174 12 0 0
v_our_firm 9810 9810 0 0.00 0 126 4 0 0
v_reserve_write_off 18994 18994 0 0.00 0 4940 11 0 0
x_lock_depdocs_on_canc_invoice 10994 10994 0 0.00 0 13134 129 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 4168 351 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 3 55116 1179 0 0
x_make_qstorno_1200_2000 5221 5221 0 0.00 4 70674 2899 0 0
x_make_qstorno_2100_3300 452592 452592 0 0.00 0 107106 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-05-2022 07:38:35 to 05-05-2022 07:38:39.

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
05:07:18 35 8192000000 8808686224 8823693312 9845616 0.120 4 2 2 0 9956000 74608 35776
05:08:19 38203 8192000000 9729222976 10029002752 804082032 8.938 100 91 182 0 834108576 8286192 21740352
05:10:00 16386 8192000000 9765086304 10073939968 841176912 9.312 100 89 178 0 875030016 12814400 21038704
05:11:24 13861 8192000000 9802478240 10125139968 875305056 9.653 100 80 160 1 912341488 18869520 18166912
05:12:42 8521 8192000000 9872767152 10210791424 922235808 10.118 100 81 162 1 981750000 41684976 17829216
05:14:00 4769 8192000000 9939630896 10285604864 970663600 10.593 100 89 178 0 1053708032 64463744 18580688
05:15:08 2305 8192000000 9977352752 10333802496 999860816 10.877 100 86 168 0 1087234656 70116992 17256848
05:16:15 1285 8192000000 9988956128 10351165440 1026045088 11.130 100 89 174 0 1110664000 66907216 17711696
05:17:18 961 8192000000 10031356112 10403143680 1041089680 11.275 100 96 187 0 1151737104 92049440 18597984
05:18:21 749 8192000000 10026935136 10406907904 1052239744 11.382 100 95 180 3 1150381024 79820960 18320320
05:19:23 1344 8192000000 10009611264 10389778432 1061823328 11.474 100 90 167 1 1131219072 52829424 16566320
05:20:26 881 8192000000 10028868064 10411323392 1074532736 11.595 100 86 167 1 1150145904 58519424 17093744
05:21:28 1185 8192000000 10050331136 10433576960 1082561296 11.672 100 94 189 0 1166338752 63515584 20261872
05:22:30 9393 8192000000 10045928224 10448740352 1083625936 11.682 100 61 120 0 1173521744 77641968 12253840
05:23:43 1015 8192000000 10036614240 10420826112 1092481216 11.766 100 96 182 4 1159645344 47819920 19344208
05:24:44 2487 8192000000 10065127344 10450649088 1096198128 11.802 100 81 159 0 1189924000 77302016 16423856
05:25:47 1168 8192000000 10072364064 10464743424 1093476880 11.776 100 94 174 2 1194191504 82612576 18102048
05:26:49 1296 8192000000 10076407616 10463424512 1098665568 11.825 100 98 181 1 1193859456 76483184 18710704
05:27:52 6545 8192000000 10030174832 10425331712 1074920272 11.599 99 66 128 1 1140323152 51527552 13875328
05:29:00 3920 8192000000 10047498448 10439958528 1087932528 11.723 100 73 131 3 1169188896 69900112 11356256
05:30:05 4094 8192000000 10026454288 10417422336 1076822896 11.617 100 70 136 1 1153218048 62406576 13988576
05:31:10 1526 8192000000 10029661312 10417958912 1078940560 11.637 100 83 165 1 1149624832 52651936 18032336
05:32:12 998 8192000000 10005713328 10380632064 1052222544 11.382 100 96 185 1 1125038992 54386416 18430032
05:33:15 1073 8192000000 9996029936 10373001216 1040630736 11.271 99 96 185 1 1118687472 58263216 19793520
05:34:17 995 8192000000 9971863232 10343763968 1030309008 11.171 100 84 162 0 1093228288 46128704 16790576
05:35:19 6890 8192000000 9971711440 10350321664 1023800144 11.109 100 58 104 1 1112709696 78147296 10762256
05:36:27 1007 8192000000 9976568112 10357338112 1034958608 11.216 100 84 162 0 1099119456 47883312 16277536
05:37:30 1268 8192000000 10027187680 10407706624 1070292560 11.555 100 92 172 3 1148850064 59419072 19138432
05:38:38 1272 8192000000 10052453328 10430578688 1077299664 11.622 100 92 172 3 1170258496 75985584 16973248
05:39:44 3196 8192000000 10028322944 10403983360 1080553904 11.653 100 85 162 1 1146881552 49171712 17155936
05:40:53 1194 8192000000 10091047248 10472992768 1087627568 11.720 99 82 158 3 1209239952 104618016 16994368
05:41:57 1101 8192000000 10103620320 10488139776 1111624208 11.948 100 91 164 3 1214625984 86408208 16593568
05:43:02 861 8192000000 10145267104 10534125568 1119874112 12.026 100 95 177 1 1260372144 120633408 19864624
05:44:10 1991 8192000000 10180433216 10575986688 1133347488 12.153 100 87 169 0 1290001088 138003072 18650528
05:45:15 913 8192000000 10225105392 10619871232 1142755952 12.241 100 94 182 1 1335145248 172446400 19942896
05:46:19 1907 8192000000 10249118992 10640752640 1145371856 12.266 100 89 172 1 1364633376 200002960 19258560
05:47:25 1097 8192000000 10273624544 10677100544 1157592704 12.381 100 93 181 0 1380305424 202380912 20331808
05:48:29 930 8192000000 10311654352 10709237760 1166419696 12.463 100 94 183 1 1421422368 235190192 19812480
05:49:33 2158 8192000000 10336948176 10738040832 1175854176 12.552 100 92 179 1 1452387040 254782112 21750752
05:50:38 958 8192000000 10379886112 10784755712 1181040688 12.600 100 95 185 0 1491706592 289362512 21303392
05:51:42 1286 8192000000 10405349488 10819559424 1187051568 12.656 100 96 187 0 1517374464 309282192 21040704
05:52:47 1392 8192000000 10386846816 10826522624 1183416912 12.622 100 88 172 0 1494441232 290878000 20146320
05:53:50 758 8192000000 10362036272 10796793856 1180880176 12.598 100 96 189 1 1469998320 267290080 21828064
05:54:54 1334 8192000000 10344745280 10756280320 1176597968 12.558 100 92 178 1 1452352224 255697200 20057056
05:55:58 1111 8192000000 10401882784 10813210624 1186393920 12.650 100 95 188 1 1509545152 301731744 21419488
05:57:01 988 8192000000 10384330112 10821615616 1167995264 12.478 99 97 190 0 1487986512 297221840 22769408
05:58:06 891 8192000000 10444960656 10872082432 1196379824 12.743 100 98 189 0 1548565968 329309504 22876640
05:59:08 12216 8192000000 10479213776 10907975680 1188590544 12.670 99 48 96 0 1569226656 367990384 12645728
06:00:24 7048 8192000000 10491929872 10936516608 1180781920 12.597 99 55 109 0 1590990592 395611968 14596704
06:01:36 25044 8192000000 10500599216 10975375360 1192925456 12.711 100 41 80 1 1614030800 409842752 11262592
06:03:11 10729 8192000000 10567932496 11029499904 1190321744 12.686 100 42 84 0 1671761840 470516992 10923104
06:04:24 22445 8192000000 10526141776 11013320704 1168061680 12.479 99 34 68 1 1651992432 474512944 9417808
06:06:21 40197 8192000000 10448014928 10946121728 1127348992 12.096 98 27 54 0 1559541408 424879312 7313104
06:08:06 18067 8192000000 10192811536 10704392192 1125681872 12.081 100 34 65 3 1315821856 182790912 7349072
06:09:27 3771 8192000000 10110636320 10602770432 1110703328 11.939 98 66 130 0 1208208000 82741648 14763024
06:10:32 40697 8192000000 10162238912 10595409920 1137146112 12.189 99 51 88 0 1261070416 114508192 9416112
06:12:21 12089 8192000000 10188749488 10622767104 1149632336 12.306 100 49 98 1 1279428608 118538336 11257936
06:13:51 90996 8192000000 10138489872 10647818240 1124849440 12.073 98 20 40 1 1245111856 115560016 4702400
06:16:34 49705 8192000000 10066112336 10518138880 1113820368 11.969 98 22 45 1 1158071408 39727200 4523840
06:18:26 38784 8192000000 10098837760 10543939584 1123682800 12.062 99 22 44 0 1185962032 57347184 4932048
06:20:08 91676 8192000000 10140812288 10574131200 1134251328 12.161 99 28 51 2 1215231904 75379600 5600976
06:22:42 29331 8192000000 10198799424 10648375296 1139941136 12.215 100 29 58 2 1283286704 136841248 6504320
06:24:37 94735 8192000000 10074102624 10587828224 1122190992 12.048 98 12 22 1 1196202704 71351760 2659952
06:27:13 106929 8192000000 10111190080 10589765632 1119150384 12.019 98 18 36 3 1199004432 76207760 3646288
06:30:08 141321 8192000000 10077086704 10564870144 1114153504 11.972 98 13 26 1 1196174192 79171008 2849680
06:33:33 124313 8192000000 10151995648 10627469312 1117258176 12.001 98 31 60 1 1189822032 66325792 6238064
06:37:26 26611 8192000000 10163142416 10595950592 1132596224 12.146 99 44 87 2 1240912912 99575344 8741344
06:39:02 8656 8192000000 10168370128 10590294016 1132429056 12.144 100 63 127 0 1267898128 122128288 13340784
06:40:14 190324 8192000000 10108947056 10624909312 1134334144 12.162 99 17 33 1 1274116528 135714960 4067424
06:44:38 60940 8192000000 10068854880 10562613248 1135556080 12.174 99 11 22 0 1187063200 49086064 2421056
06:46:42 27555 8192000000 10094105008 10538225664 1142562192 12.240 99 25 50 1 1177630192 30304464 4763536
06:48:13 104661 8192000000 10149711744 10616135680 1156020896 12.366 100 24 48 2 1252942080 91513056 5408128
06:50:59 138710 8192000000 10226014672 10677669888 1117506176 12.003 96 24 47 1 1227027120 103928784 5592160
06:54:57 82070 8192000000 10085970416 10545733632 1110491232 11.937 97 19 37 1 1167895424 53182416 4221776
06:58:36 79235 8192000000 10075619088 10500345856 1096669520 11.806 97 45 90 1 1142901728 37522832 8709376
07:01:02 72535 8192000000 10090717216 10525364224 1113275120 11.963 98 26 52 0 1175825712 57500816 5049776
07:03:18 35358 8192000000 10154541120 10602381312 1129231552 12.114 100 45 86 0 1236226560 95223904 11771104
07:05:11 106555 8192000000 10082767552 10566115328 1113698784 11.967 98 18 36 0 1203386624 85714624 3973216
07:09:16 86503 8192000000 10092303248 10533310464 1107207024 11.906 97 23 46 2 1165516240 53094480 5214736
07:11:42 46161 8192000000 10073914480 10506760192 1112818624 11.959 97 31 61 2 1147894384 28934336 6141424
07:13:31 51040 8192000000 10142258992 10596831232 1126500432 12.088 98 37 72 2 1210990832 76730400 7760000
07:15:28 47436 8192000000 10073586048 10503749632 1092851072 11.770 97 24 48 2 1144929392 47278640 4799680
07:17:22 16730 8192000000 10093182160 10520428544 1111979424 11.951 99 54 108 0 1182080320 59722544 10378352
07:18:48 51565 8192000000 10175809312 10646937600 1136942192 12.187 100 34 68 0 1280703216 136545456 7215568
07:21:06 57484 8192000000 10085153168 10517835776 1109387088 11.927 97 37 70 0 1153367072 37394480 6585504
07:23:05 11016 8192000000 10098235200 10513428480 1138393376 12.200 100 78 144 3 1186752560 34470448 13888736
07:24:17 160009 8192000000 10119643120 10581712896 1138173008 12.198 99 26 50 2 1201089136 57720192 5195936
07:27:59 66409 8192000000 10082955088 10574954496 1151661328 12.325 100 14 28 0 1207701216 53191584 2848304
07:30:53 60998 8192000000 10098455968 10550026240 1128801712 12.110 98 14 26 1 1192643024 61005968 2835344
07:32:54 119848 8192000000 10129305328 10587074560 1150322256 12.313 100 19 37 0 1239753344 85362304 4068784
07:35:56 67181 8192000000 10069692096 10511503360 1125595552 12.080 98 28 56 1 1159746800 28399248 5752000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-05-2022 07:38:41 to 05-05-2022 07:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-05-2022 07:38:57 to 05-05-2022 07:38:59.

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          213038
MON$OLDEST_ACTIVE               215879
MON$OLDEST_SNAPSHOT             215879
MON$NEXT_TRANSACTION            215879
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                       2085984
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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 ---
:162: 
:163: mscs-test-161.budzdorov.ru    Thu May  5 07:37:19 2022
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:165: 
--- end of diff output ---

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

05.05.22 07:39:00. 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-05-05 07:39:02 No severe PSQL-related problems occured
05.05.22 07:39:04. End of report.