Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.07.2022 10: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 15363848 kB
MemAvailable 31023320 kB
Buffers 0 kB
Cached 16314364 kB
SwapCached 2552 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-24 10:37:19
DTS_END                         2022-07-24 10:37:19.7820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51648
TRN_ID                          223348
ATT_ID                          660
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: 8656.67 23592 1038800
customer order: creation 177.79 448 21335
customer order: refuse 14.69 461 1763
order to supplier: creation 47.98 1358 5757
order to supplier: removal 3.33 173345 399
invoice (draft): creation 46.39 3469 5567
invoice (draft): removal 45.40 1173 5448
invoice accept: apply 43.18 26974 5181
invoice accept: cancel 40.52 1472 4862
customer reserve: creation 3960.83 256 475300
customer reserve: removal 3984.83 113 478179
realization accept: apply 84.84 296 10181
realization accept: cancel 74.50 199 8940
payment from customer: creation 60.29 208 7235
payment from customer: removal 6.08 147 730
payment to supplier: creation 49.67 229 5960
payment to supplier: removal 3.14 156 377
service: total inventory turnovers 3.84 4753 461
service: total monetary turnovers 8.46 370 1015
service: aggregate perf. data 0.88 16791 106
service: refresh index statistics 0.03 239614 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-07-2022 10:38:36 to 24-07-2022 10:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9660
TEST_TIME 2 6883
TEST_TIME 3 5916
TEST_TIME 4 5466
TEST_TIME 5 5579
TEST_TIME 6 5080
TEST_TIME 7 5050
TEST_TIME 8 5386
TEST_TIME 9 5789
TEST_TIME 10 6165
TEST_TIME 11 6356
TEST_TIME 12 6473
TEST_TIME 13 6733
TEST_TIME 14 6917
TEST_TIME 15 6936
TEST_TIME 16 7098
TEST_TIME 17 7298
TEST_TIME 18 7504
TEST_TIME 19 7534
TEST_TIME 20 7714
TEST_TIME 21 8107
TEST_TIME 22 8101
TEST_TIME 23 8154
TEST_TIME 24 8460
TEST_TIME 25 8504
TEST_TIME 26 8634
TEST_TIME 27 8573
TEST_TIME 28 8564
TEST_TIME 29 8657
TEST_TIME 30 8718
TEST_TIME 31 8920
TEST_TIME 32 9071
TEST_TIME 33 9212
TEST_TIME 34 9576
TEST_TIME 35 9557
TEST_TIME 36 9758
TEST_TIME 37 9757
TEST_TIME 38 10031
TEST_TIME 39 9892
TEST_TIME 40 10046
TEST_TIME 41 10280
TEST_TIME 42 10250
TEST_TIME 43 10453
TEST_TIME 44 10325
TEST_TIME 45 10221
TEST_TIME 46 10110
TEST_TIME 47 10102
TEST_TIME 48 9971
TEST_TIME 49 9936
TEST_TIME 50 9984
TEST_TIME 51 9889
TEST_TIME 52 9849
TEST_TIME 53 9821
TEST_TIME 54 9780
TEST_TIME 55 9631
TEST_TIME 56 9597
TEST_TIME 57 9576
TEST_TIME 58 9508
TEST_TIME 59 9411
TEST_TIME 60 9344
TEST_TIME 61 9335
TEST_TIME 62 9308
TEST_TIME 63 9314
TEST_TIME 64 9400
TEST_TIME 65 9331
TEST_TIME 66 9293
TEST_TIME 67 9279
TEST_TIME 68 9271
TEST_TIME 69 9224
TEST_TIME 70 9107
TEST_TIME 71 9082
TEST_TIME 72 9124
TEST_TIME 73 9041
TEST_TIME 74 9048
TEST_TIME 75 8982
TEST_TIME 76 8894
TEST_TIME 77 8870
TEST_TIME 78 8897
TEST_TIME 79 8943
TEST_TIME 80 8938
TEST_TIME 81 8953
TEST_TIME 82 8996
TEST_TIME 83 8991
TEST_TIME 84 9045
TEST_TIME 85 9068
TEST_TIME 86 9044
TEST_TIME 87 9072
TEST_TIME 88 9175
TEST_TIME 89 9226
TEST_TIME 90 9244
TEST_TIME 91 9160
TEST_TIME 92 9157
TEST_TIME 93 9120
TEST_TIME 94 9074
TEST_TIME 95 9053
TEST_TIME 96 8984
TEST_TIME 97 8964
TEST_TIME 98 8902
TEST_TIME 99 8887
TEST_TIME 100 8869
TEST_TIME 101 8869
TEST_TIME 102 8853
TEST_TIME 103 8868
TEST_TIME 104 8850
TEST_TIME 105 8824
TEST_TIME 106 8830
TEST_TIME 107 8843
TEST_TIME 108 8924
TEST_TIME 109 8900
TEST_TIME 110 8970
TEST_TIME 111 8936
TEST_TIME 112 8883
TEST_TIME 113 8891
TEST_TIME 114 8841
TEST_TIME 115 8845
TEST_TIME 116 8837
TEST_TIME 117 8793
TEST_TIME 118 8771
TEST_TIME 119 8748
TEST_TIME 120 8693

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-07-2022 10:38:39 to 24-07-2022 10:38:42.

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 1477004 1477004 0 0.00 0 16786 15 0 0
doc_list_biud 1476992 1476992 0 0.00 0 105792 43 0 0
sp_add_invoice_to_stock 5297 5181 116 2.18 41 206994 26974 0 116
sp_cancel_adding_invoice 4862 4862 0 0.00 7 89351 1473 0 0
sp_cancel_client_order 1763 1763 0 0.00 5 7748 461 0 0
sp_cancel_customer_reserve 478179 478179 0 0.00 1 24058 113 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 4 5696 147 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 4 5622 157 0 0
sp_cancel_supplier_invoice 5462 5448 14 0.25 3 22527 1173 0 14
sp_cancel_supplier_order 399 399 0 0.00 9 1415792 173346 0 0
sp_cancel_write_off 8940 8940 0 0.00 2 105837 199 0 0
sp_client_order 21335 21335 0 0.00 5 30304 449 0 0
sp_customer_reserve 475300 475300 0 0.00 0 117538 256 0 0
sp_fill_shopping_cart 32649 32649 0 0.00 1 15646 95 0 0
sp_fill_shopping_cart_clo_res 475243 475243 0 0.00 0 18693 19 0 0
sp_get_clo_for_invoice 5123 5123 0 0.00 0 29662 293 0 0
sp_kill_cost_storno 14909 14909 0 0.00 0 9004 89 0 0
sp_kill_qstorno_ret_qs2qd 971145 971145 0 0.00 0 105789 49 0 0
sp_kill_qty_storno 514894 514894 0 0.00 0 105790 113 0 0
sp_lock_selected_doc 21150 21150 0 0.00 0 7174 7 0 0
sp_make_cost_storno 28499 28499 0 0.00 0 13873 88 0 0
sp_multiply_rows_for_pdistr 28498 28498 0 0.00 0 8119 41 0 0
sp_multiply_rows_for_qdistr 21335 21335 0 0.00 0 11557 145 0 0
sp_pay_from_customer 7235 7235 0 0.00 5 12205 208 0 0
sp_pay_to_supplier 5960 5960 0 0.00 6 19244 230 0 0
sp_payment_common 13195 13195 0 0.00 2 9410 50 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 7732 368 0 0
sp_qd_handle_on_invoice_upd_sts 9984 9984 0 0.00 0 23968 626 0 0
sp_qd_handle_on_reserve_upd_sts 10181 10181 0 0.00 0 11555 63 0 0
sp_reserve_write_off 10181 10181 0 0.00 8 11755 297 0 0
sp_supplier_invoice 5567 5567 0 0.00 9 95616 3470 0 0
sp_supplier_order 5757 5757 0 0.00 9 28982 1359 0 0
srv_aggregate_perf_data 106 106 0 0.00 2028 157458 16792 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 945 112700 30376 0 0
srv_find_qd_qs_mism 988808 988808 0 0.00 0 16785 11 0 0
srv_make_invnt_saldo 461 461 0 0.00 36 91706 4754 0 0
srv_make_money_saldo 1015 1015 0 0.00 1 15776 370 0 0
srv_recalc_idx_stat 4 4 0 0.00 30909 342898 239614 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5187 5187 0 0.00 0 15384 1084 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 2 6771 563 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 1273 28 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 1644 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 1231 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 1133 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 6869 50 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 103758 8828 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 1253 23 0 0
t$perf-norm:sp_client_order 21336 21336 0 0.00 0 21367 39 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 21375 41 0 0
t$perf-norm:sp_pay_from_customer 7235 7235 0 0.00 0 1718 17 0 0
t$perf-norm:sp_pay_to_supplier 5961 5961 0 0.00 0 4366 19 0 0
t$perf-norm:sp_reserve_write_off 10181 10181 0 0.00 0 7418 26 0 0
t$perf-norm:sp_supplier_invoice 5568 5568 0 0.00 0 23468 40 0 0
t$perf-norm:sp_supplier_order 5757 5757 0 0.00 0 2985 36 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 410 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 279 10 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 2415 27 0 0
t$perf-norm:srv_make_money_saldo 1015 1015 0 0.00 0 2083 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 211 79 0 0
v_add_invoice_to_stock 10476 10244 232 2.21 0 11041 29 0 232
v_all_customers 39086 39086 0 0.00 0 68 4 0 0
v_all_suppliers 31066 31066 0 0.00 0 81 5 0 0
v_all_wares 42670 42670 0 0.00 0 65 5 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 1325 18 0 0
v_cancel_client_order 3526 3526 0 0.00 0 5698 11 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 2110 17 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 801 12 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 5518 29 0 0
v_cancel_supplier_invoice 912 884 28 3.07 0 3160 35 0 28
v_cancel_supplier_order 798 798 0 0.00 0 2100 14 0 0
v_cancel_write_off 2038 2038 0 0.00 0 4672 31 0 0
v_max_id_clo_ord 5754 5754 0 0.00 0 1230 6 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 2052 18 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 3116 8 0 0
v_max_non_paid_invoice 1741 1741 0 0.00 0 1628 10 0 0
v_max_non_paid_realizn 3753 3753 0 0.00 0 1628 9 0 0
v_min_id_clo_ord 5754 5754 0 0.00 0 12107 15 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 2330 15 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 7132 11 0 0
v_min_non_paid_invoice 5960 5960 0 0.00 0 4837 16 0 0
v_min_non_paid_realizn 7235 7235 0 0.00 0 9392 22 0 0
v_our_firm 10548 10548 0 0.00 0 70 5 0 0
v_reserve_write_off 20360 20360 0 0.00 0 5300 13 0 0
x_lock_depdocs_on_canc_invoice 9868 9868 0 0.00 0 17186 144 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 8451 378 0 0
x_make_qstorno_1000_1200 5757 5757 0 0.00 2 25381 1159 0 0
x_make_qstorno_1200_2000 5567 5567 0 0.00 4 95091 3220 0 0
x_make_qstorno_2100_3300 475300 475300 0 0.00 0 117530 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-07-2022 10:38:43 to 24-07-2022 10:38:47.

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:23 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:24 29983 8192000000 9719224352 10017775616 792358256 8.819 100 87 172 0 821097936 7088816 21650864
08:10:00 16867 8192000000 9745672816 10048540672 822301552 9.122 100 90 181 0 856338048 12166544 21869952
08:11:21 11460 8192000000 9784311296 10095796224 848288144 9.383 100 92 184 0 895862864 25450224 22124496
08:12:38 12575 8192000000 9859432912 10189873152 907496928 9.973 100 78 156 0 962930512 38035664 17397920
08:13:55 6101 8192000000 9905983392 10244386816 949745856 10.389 100 87 172 0 1016600064 48476752 18377456
08:15:07 3089 8192000000 9958597552 10310000640 985099408 10.734 100 89 175 0 1074271120 71429280 17742432
08:16:12 1289 8192000000 9985622464 10348171264 1018027584 11.053 100 96 183 1 1107505568 70971776 18506208
08:17:15 1189 8192000000 10021221264 10384334848 1036012592 11.226 100 89 173 0 1138774336 85812848 16948896
08:18:18 898 8192000000 10039153808 10417451008 1058478640 11.442 100 93 178 2 1163787424 86347328 18961456
08:19:21 1052 8192000000 10044368064 10420785152 1066002656 11.514 100 97 191 1 1166382144 81009600 19369888
08:20:24 1092 8192000000 10033576576 10416316416 1067728704 11.530 100 82 162 1 1156101600 71557264 16815632
08:21:27 1168 8192000000 10042597760 10422583296 1084046720 11.686 100 96 185 1 1166352368 63255504 19050144
08:22:29 2701 8192000000 10045541600 10439184384 1083765296 11.683 100 77 150 1 1174437792 75637248 15035248
08:23:33 1091 8192000000 10059515280 10444492800 1090606624 11.748 100 92 175 1 1189355936 81339760 17409552
08:24:36 2078 8192000000 10057260496 10443579392 1092657072 11.768 100 93 180 4 1175969360 64862192 18450096
08:25:40 1056 8192000000 10025012656 10419716096 1097305584 11.812 100 75 141 4 1150304208 38620224 14378400
08:26:41 3435 8192000000 10042480816 10429747200 1088611840 11.729 99 68 128 5 1162573216 62054288 11907088
08:27:47 961 8192000000 10074150160 10461839360 1098773056 11.826 100 97 188 0 1191654752 73661200 19220496
08:28:49 882 8192000000 10056979728 10442358784 1082171872 11.668 100 92 175 0 1175452928 76142064 17138992
08:29:51 1170 8192000000 10044891552 10431119360 1093605920 11.777 100 99 187 3 1170228112 56466272 20155920
08:30:53 1134 8192000000 10029755824 10409562112 1081852624 11.665 100 95 185 1 1149964128 49992416 18119088
08:31:54 5234 8192000000 10022188080 10398375936 1051011664 11.370 99 83 152 1 1135113696 70574976 13527056
08:33:01 1194 8192000000 10006678720 10383785984 1046166320 11.324 100 89 164 4 1125405488 62852208 16386960
08:34:03 1025 8192000000 10015751024 10385936384 1039255408 11.258 99 94 172 2 1134774528 77953648 17565472
08:35:05 1113 8192000000 10012581392 10390421504 1047007552 11.332 100 85 165 3 1132676448 68847024 16821872
08:36:07 1059 8192000000 10012851856 10390671360 1046031328 11.323 100 79 157 1 1134352928 72033344 16288256
08:37:09 1031 8192000000 10024893088 10400235520 1053278400 11.392 100 95 181 1 1145003104 72598304 19126400
08:38:17 1911 8192000000 10026756480 10401931264 1062469472 11.480 100 81 153 1 1144570848 66419920 15681456
08:39:25 2282 8192000000 10032973088 10411511808 1078601664 11.634 100 88 163 1 1152731104 58344160 15785280
08:40:36 25639 8192000000 10058605968 10441895936 1087828496 11.722 100 65 127 1 1174050992 73781856 12440640
08:42:06 12552 8192000000 10103350560 10497556480 1102500448 11.861 100 73 138 0 1218104448 101577120 14026880
08:43:29 22008 8192000000 10174402544 10568826880 1108066128 11.914 100 68 134 1 1273556944 151249968 14240848
08:44:57 4846 8192000000 10196303152 10597318656 1118591600 12.014 100 78 149 1 1307474192 172167376 16715216
08:46:09 1772 8192000000 10206864480 10609385472 1128143664 12.104 100 87 162 3 1321204304 175726304 17334336
08:47:16 2575 8192000000 10221044832 10625597440 1144089392 12.254 100 81 155 0 1332173056 170912400 17171264
08:48:23 1313 8192000000 10260640992 10660765696 1156409600 12.370 100 97 190 2 1378742352 199513952 22818800
08:49:27 1531 8192000000 10260881392 10676486144 1164248256 12.443 100 93 180 1 1372399760 187569872 20581632
08:50:32 1512 8192000000 10290688544 10697142272 1158919936 12.393 100 78 156 1 1403109456 226196608 17992912
08:51:37 1737 8192000000 10309315936 10727501824 1167434080 12.473 100 92 183 0 1426103472 236413744 22255648
08:52:44 1486 8192000000 10348905536 10753732608 1167687488 12.475 100 98 192 1 1454787040 265053520 22046032
08:53:50 1045 8192000000 10364761648 10798329856 1172449856 12.520 100 96 190 0 1477070464 282838416 21782192
08:54:56 974 8192000000 10366478496 10791051264 1170747552 12.504 100 90 175 1 1471893536 280630528 20515456
08:56:00 1140 8192000000 10363891408 10765742080 1158215968 12.387 100 98 194 0 1471893664 291901328 21776368
08:57:03 935 8192000000 10369752944 10796425216 1151776640 12.326 100 97 191 0 1475961776 301854064 22331072
08:58:07 1383 8192000000 10377281952 10814676992 1155785920 12.364 100 96 192 0 1483730784 306585072 21359792
08:59:14 1664 8192000000 10324690768 10751049728 1158497248 12.389 100 74 137 1 1434840560 261594304 14749008
09:00:20 1874 8192000000 10352412848 10793816064 1165134288 12.451 100 85 166 1 1457267664 272715168 19418208
09:01:25 1074 8192000000 10370511936 10796625920 1174092320 12.535 100 90 177 0 1475516416 279574352 21849744
09:02:30 2949 8192000000 10368640576 10813476864 1183392032 12.622 100 73 143 0 1478887168 278252768 17242368
09:03:38 1186 8192000000 10404274032 10812227584 1174906400 12.543 100 95 185 0 1505701712 308920688 21874624
09:04:43 7483 8192000000 10431750832 10865143808 1160744160 12.410 100 66 127 0 1546656176 369971008 15941008
09:05:54 2009 8192000000 10472284496 10914271232 1172972128 12.525 100 94 183 0 1573455920 377654192 22829600
09:06:58 1264 8192000000 10481949312 10926419968 1179606480 12.587 100 66 127 1 1591093472 395853568 15633424
09:08:02 2574 8192000000 10446937696 10929643520 1190677888 12.690 100 96 185 1 1547824000 333988688 23157424
09:09:06 1656 8192000000 10466062448 10905612288 1185537648 12.642 100 84 168 0 1564106048 358367104 20201296
09:10:11 5004 8192000000 10439754432 10889752576 1162628800 12.428 100 57 114 0 1534039472 356933952 14476720
09:11:26 15448 8192000000 10407401952 10891120640 1173536288 12.530 100 35 70 1 1529920656 347231392 9152976
09:12:48 17121 8192000000 10412195760 10870435840 1176304320 12.556 100 33 66 1 1521781184 337035440 8441424
09:14:13 24575 8192000000 10369028816 10825220096 1147327648 12.284 98 28 56 0 1453716736 299175984 7213104
09:15:44 43499 8192000000 10279995696 10746675200 1152702848 12.335 100 63 118 0 1399811328 232699440 14409040
09:17:29 14052 8192000000 10255947280 10728816640 1157661376 12.381 100 27 52 1 1376702192 212986064 6054752
09:18:50 98066 8192000000 10099812704 10645348352 1131830784 12.139 99 12 25 0 1263120880 128527952 2762144
09:21:30 16598 8192000000 10070882864 10506989568 1139284832 12.209 100 44 82 0 1167036544 18797520 8954192
09:23:01 58237 8192000000 10101950272 10547081216 1135167024 12.170 99 31 60 1 1183922144 42443424 6311696
09:25:29 33688 8192000000 10084305152 10529406976 1142917728 12.243 100 37 65 3 1179469696 29660656 6891312
09:27:08 70044 8192000000 10073847072 10529624064 1118708976 12.015 98 8 16 0 1166307744 45359936 2238832
09:29:20 109425 8192000000 10076708784 10564014080 1096116992 11.801 96 11 22 0 1143204704 44744816 2342896
09:32:15 112700 8192000000 10098174128 10578116608 1101940816 11.856 97 26 52 1 1137617488 30318656 5358016
09:35:08 36539 8192000000 10066601360 10496413696 1091668928 11.759 97 55 102 0 1122572544 20433264 10470352
09:36:48 68512 8192000000 10089176688 10510753792 1115898064 11.988 98 72 116 2 1164344896 37902608 10544224
09:38:58 24810 8192000000 10120938896 10558156800 1122976528 12.055 99 35 67 0 1222364288 91969632 7418128
09:40:23 49386 8192000000 10119651680 10599972864 1116403728 11.993 99 22 42 0 1249914736 128945856 4565152
09:42:15 44482 8192000000 10077484448 10536734720 1129669920 12.118 100 12 24 0 1173283024 41291312 2321792
09:44:00 64374 8192000000 10065835312 10511937536 1074235568 11.593 94 15 30 0 1133438704 55721520 3481616
09:46:34 57913 8192000000 10061739728 10511671296 1130967824 12.130 100 15 28 0 1157447616 23605808 2873984
09:48:54 62221 8192000000 10066812208 10602938368 1121078864 12.037 99 5 10 0 1176928816 55006976 842976
09:50:57 12356 8192000000 10082929744 10515128320 1130007296 12.121 100 67 125 0 1185014896 40691952 14315648
09:52:11 78060 8192000000 10086992640 10575368192 1129763056 12.119 100 4 8 0 1195481888 64901808 817024
09:54:51 51216 8192000000 10085280960 10571829248 1118803312 12.016 99 24 46 0 1193952768 70308592 4840864
09:56:46 27510 8192000000 10070309456 10549350400 1131053280 12.131 100 21 42 1 1191854816 56385792 4415744
09:58:22 27439 8192000000 10146409680 10584256512 1125963072 12.083 98 49 92 0 1220839408 85412576 9463760
09:59:52 51360 8192000000 10147497248 10600587264 1130548288 12.127 99 37 72 1 1213911536 76075248 7288000
10:01:47 39403 8192000000 10068296704 10496110592 1120549984 12.032 98 34 68 0 1158037504 30464816 7022704
10:03:37 44856 8192000000 10166904576 10640916480 1113088000 11.962 98 39 76 0 1250479808 129510800 7881008
10:05:25 94012 8192000000 10125706240 10613346304 1096992288 11.809 96 11 22 0 1176230432 77227280 2010864
10:08:00 109460 8192000000 10097285952 10555052032 1138909968 12.205 100 28 55 2 1198680272 54103920 5666384
10:10:51 68685 8192000000 10074534000 10542313472 1129071552 12.113 99 5 10 0 1170686384 40817504 797328
10:12:59 52309 8192000000 10061678160 10517696512 1130773056 12.129 99 11 22 0 1159333488 25796000 2764432
10:14:57 62240 8192000000 10074208640 10552795136 1136553712 12.183 100 16 31 0 1182361440 42590944 3216784
10:17:02 38859 8192000000 10071915776 10537263104 1120019424 12.027 98 11 21 1 1147256656 25619440 1617792
10:18:41 14362 8192000000 10066457952 10504564736 1119263760 12.020 98 32 59 0 1153578448 28551488 5763200
10:19:58 29005 8192000000 10107819632 10560831488 1143677392 12.250 100 33 64 1 1205654016 55326432 6650192
10:21:37 31296 8192000000 10140814976 10578063360 1143032256 12.244 100 57 113 0 1218280144 63951216 11296672
10:23:16 17068 8192000000 10135401264 10591285248 1121105280 12.037 98 51 103 0 1220074160 88104544 10864336
10:24:34 32919 8192000000 10152894688 10652491776 1112731280 11.958 98 45 88 1 1237893488 116299248 8862960
10:26:08 23303 8192000000 10109771392 10555510784 1132922608 12.149 99 33 66 1 1187484656 47601392 6960656
10:27:51 39858 8192000000 10079912720 10514649088 1116048880 11.990 98 41 82 0 1154110304 29765888 8295536
10:29:33 83694 8192000000 10066985888 10539810816 1099807312 11.836 97 41 82 0 1198851728 90487040 8557376
10:32:00 7469 8192000000 10071834512 10508808192 1114783712 11.978 99 56 112 0 1174924288 48241808 11898768
10:33:09 44782 8192000000 10041124624 10524000256 1105170800 11.887 99 7 14 0 1157489600 50717456 1601344
10:34:57 47195 8192000000 10053394912 10511421440 1095436624 11.794 97 26 49 1 1140232080 39960016 4835440
10:37:14 12048 8192000000 8966280224 9191161856 133316112 1.601 12 7 14 0 167019264 31991104 1712048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 24-07-2022 10:38:49 to 24-07-2022 10:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

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          223634
MON$OLDEST_ACTIVE               223635
MON$OLDEST_SNAPSHOT             223635
MON$NEXT_TRANSACTION            223635
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                       2115904
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2807 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2807 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2807 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2807 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 ---
:47: 
:48: mscs-test-161.budzdorov.ru    Sun Jul 24 10:37:39 2022
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
--- end of diff output ---

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

24.07.22 10:39:10. 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-24 10:39:12 No severe PSQL-related problems occured
24.07.22 10:39:14. End of report.