Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.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 15751668 kB
MemAvailable 31061704 kB
Buffers 0 kB
Cached 15940172 kB
SwapCached 2484 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-19 10:37:18
DTS_END                         2022-07-19 10:37:18.6990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59404
TRN_ID                          220504
ATT_ID                          665
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: 9008.45 24472 1081014
customer order: creation 169.45 469 20334
customer order: refuse 14.52 386 1742
order to supplier: creation 45.61 1331 5473
order to supplier: removal 3.57 165421 428
invoice (draft): creation 45.41 3157 5449
invoice (draft): removal 49.56 974 5947
invoice accept: apply 41.73 25132 5008
invoice accept: cancel 44.88 1298 5385
customer reserve: creation 3862.12 239 463454
customer reserve: removal 4443.42 106 533210
realization accept: apply 84.05 261 10086
realization accept: cancel 76.09 183 9131
payment from customer: creation 58.51 174 7021
payment from customer: removal 5.57 119 668
payment to supplier: creation 48.38 201 5806
payment to supplier: removal 3.28 144 394
service: total inventory turnovers 3.74 4252 449
service: total monetary turnovers 7.66 354 919
service: aggregate perf. data 0.88 15943 106
service: refresh index statistics 0.03 269290 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8408
TEST_TIME 2 5892
TEST_TIME 3 5749
TEST_TIME 4 6011
TEST_TIME 5 6610
TEST_TIME 6 6337
TEST_TIME 7 6157
TEST_TIME 8 6413
TEST_TIME 9 6860
TEST_TIME 10 6817
TEST_TIME 11 6898
TEST_TIME 12 6811
TEST_TIME 13 6736
TEST_TIME 14 6882
TEST_TIME 15 6891
TEST_TIME 16 6963
TEST_TIME 17 7311
TEST_TIME 18 7424
TEST_TIME 19 7474
TEST_TIME 20 7741
TEST_TIME 21 8022
TEST_TIME 22 8163
TEST_TIME 23 8290
TEST_TIME 24 8735
TEST_TIME 25 8672
TEST_TIME 26 8869
TEST_TIME 27 9071
TEST_TIME 28 9184
TEST_TIME 29 9228
TEST_TIME 30 9631
TEST_TIME 31 9846
TEST_TIME 32 10203
TEST_TIME 33 10705
TEST_TIME 34 10944
TEST_TIME 35 10978
TEST_TIME 36 10833
TEST_TIME 37 10715
TEST_TIME 38 10607
TEST_TIME 39 10376
TEST_TIME 40 10196
TEST_TIME 41 10332
TEST_TIME 42 10262
TEST_TIME 43 10182
TEST_TIME 44 10055
TEST_TIME 45 9990
TEST_TIME 46 9974
TEST_TIME 47 9871
TEST_TIME 48 9891
TEST_TIME 49 9764
TEST_TIME 50 9788
TEST_TIME 51 9806
TEST_TIME 52 9810
TEST_TIME 53 9702
TEST_TIME 54 9687
TEST_TIME 55 9706
TEST_TIME 56 9723
TEST_TIME 57 9651
TEST_TIME 58 9792
TEST_TIME 59 9680
TEST_TIME 60 9862
TEST_TIME 61 9900
TEST_TIME 62 9888
TEST_TIME 63 9869
TEST_TIME 64 9934
TEST_TIME 65 9903
TEST_TIME 66 9764
TEST_TIME 67 9658
TEST_TIME 68 9729
TEST_TIME 69 9730
TEST_TIME 70 9649
TEST_TIME 71 9619
TEST_TIME 72 9540
TEST_TIME 73 9457
TEST_TIME 74 9399
TEST_TIME 75 9363
TEST_TIME 76 9323
TEST_TIME 77 9313
TEST_TIME 78 9280
TEST_TIME 79 9292
TEST_TIME 80 9334
TEST_TIME 81 9301
TEST_TIME 82 9311
TEST_TIME 83 9286
TEST_TIME 84 9339
TEST_TIME 85 9351
TEST_TIME 86 9426
TEST_TIME 87 9428
TEST_TIME 88 9419
TEST_TIME 89 9376
TEST_TIME 90 9312
TEST_TIME 91 9359
TEST_TIME 92 9296
TEST_TIME 93 9250
TEST_TIME 94 9221
TEST_TIME 95 9196
TEST_TIME 96 9185
TEST_TIME 97 9151
TEST_TIME 98 9135
TEST_TIME 99 9149
TEST_TIME 100 9123
TEST_TIME 101 9116
TEST_TIME 102 9109
TEST_TIME 103 9189
TEST_TIME 104 9184
TEST_TIME 105 9221
TEST_TIME 106 9289
TEST_TIME 107 9455
TEST_TIME 108 9426
TEST_TIME 109 9345
TEST_TIME 110 9328
TEST_TIME 111 9301
TEST_TIME 112 9260
TEST_TIME 113 9221
TEST_TIME 114 9166
TEST_TIME 115 9107
TEST_TIME 116 9091
TEST_TIME 117 9063
TEST_TIME 118 9080
TEST_TIME 119 9072
TEST_TIME 120 9033

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-07-2022 10:38:37 to 19-07-2022 10:38:41.

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 1508096 1508096 0 0.00 0 36852 14 0 0
doc_list_biud 1508089 1508089 0 0.00 0 38234 43 0 0
sp_add_invoice_to_stock 5115 5008 107 2.09 35 278219 25133 0 107
sp_cancel_adding_invoice 5385 5385 0 0.00 6 70705 1299 0 0
sp_cancel_client_order 1742 1742 0 0.00 7 9319 386 0 0
sp_cancel_customer_reserve 533210 533210 0 0.00 1 38237 107 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 2740 119 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 4765 145 0 0
sp_cancel_supplier_invoice 5961 5947 14 0.23 3 15653 975 0 14
sp_cancel_supplier_order 428 428 0 0.00 10 1342873 165421 0 0
sp_cancel_write_off 9131 9131 0 0.00 2 12324 184 0 0
sp_client_order 20334 20334 0 0.00 5 45640 469 0 0
sp_customer_reserve 463454 463454 0 0.00 0 192295 240 0 0
sp_fill_shopping_cart 31246 31246 0 0.00 1 17116 81 0 0
sp_fill_shopping_cart_clo_res 463430 463430 0 0.00 0 18914 20 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 22994 301 0 0
sp_kill_cost_storno 15578 15578 0 0.00 0 35175 79 0 0
sp_kill_qstorno_ret_qs2qd 1081926 1081926 0 0.00 0 38231 47 0 0
sp_kill_qty_storno 570897 570897 0 0.00 0 38234 106 0 0
sp_lock_selected_doc 20812 20812 0 0.00 0 7008 7 0 0
sp_make_cost_storno 27882 27882 0 0.00 0 14022 75 0 0
sp_multiply_rows_for_pdistr 27881 27881 0 0.00 0 15231 41 0 0
sp_multiply_rows_for_qdistr 20334 20334 0 0.00 0 14946 195 0 0
sp_pay_from_customer 7021 7021 0 0.00 5 13629 174 0 0
sp_pay_to_supplier 5806 5806 0 0.00 7 18875 201 0 0
sp_payment_common 12827 12827 0 0.00 2 18822 46 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 8571 314 0 0
sp_qd_handle_on_invoice_upd_sts 10353 10353 0 0.00 0 18841 513 0 0
sp_qd_handle_on_reserve_upd_sts 10086 10086 0 0.00 0 13315 59 0 0
sp_reserve_write_off 10086 10086 0 0.00 8 13896 262 0 0
sp_supplier_invoice 5449 5449 0 0.00 9 119995 3157 0 0
sp_supplier_order 5473 5473 0 0.00 7 46002 1332 0 0
srv_aggregate_perf_data 106 106 0 0.00 2471 129627 15943 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 683 217785 35628 0 0
srv_find_qd_qs_mism 1032560 1032560 0 0.00 0 36848 11 0 0
srv_make_invnt_saldo 449 449 0 0.00 51 130663 4253 0 0
srv_make_money_saldo 919 919 0 0.00 2 11100 354 0 0
srv_recalc_idx_stat 4 4 0 0.00 73729 379556 269290 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 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 5014 5014 0 0.00 1 41384 922 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 20014 506 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 2668 25 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 621 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 1349 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 860 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1241 33 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 97904 6626 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 521 16 0 0
t$perf-norm:sp_client_order 20336 20336 0 0.00 0 5672 38 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 932 12 0 0
t$perf-norm:sp_pay_from_customer 7021 7021 0 0.00 0 3492 18 0 0
t$perf-norm:sp_pay_to_supplier 5806 5806 0 0.00 0 3118 18 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 5321 21 0 0
t$perf-norm:sp_supplier_invoice 5449 5449 0 0.00 0 5771 32 0 0
t$perf-norm:sp_supplier_order 5473 5473 0 0.00 0 4408 33 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 435 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 505 10 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 2685 19 0 0
t$perf-norm:srv_make_money_saldo 919 919 0 0.00 0 556 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 138 49 0 0
v_add_invoice_to_stock 10146 9932 214 2.10 0 5154 24 0 214
v_all_customers 36692 36692 0 0.00 0 74 4 0 0
v_all_suppliers 30311 30311 0 0.00 0 67 4 0 0
v_all_wares 40662 40662 0 0.00 0 189 4 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 989 15 0 0
v_cancel_client_order 3484 3484 0 0.00 0 5177 11 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2640 14 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 2876 13 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 1750 15 0 0
v_cancel_supplier_invoice 808 780 28 3.46 0 710 23 0 28
v_cancel_supplier_order 856 856 0 0.00 0 338 11 0 0
v_cancel_write_off 2006 2006 0 0.00 0 2903 28 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 4919 6 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 1211 11 0 0
v_max_id_ord_sup 5445 5445 0 0.00 0 12478 10 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 3748 10 0 0
v_max_non_paid_realizn 3786 3786 0 0.00 0 1789 9 0 0
v_min_id_clo_ord 5468 5468 0 0.00 0 3027 9 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 3900 19 0 0
v_min_id_ord_sup 5445 5445 0 0.00 0 6437 8 0 0
v_min_non_paid_invoice 5806 5806 0 0.00 0 18772 20 0 0
v_min_non_paid_realizn 7021 7021 0 0.00 0 4511 16 0 0
v_our_firm 10438 10438 0 0.00 0 67 5 0 0
v_reserve_write_off 20170 20170 0 0.00 0 5194 10 0 0
x_lock_depdocs_on_canc_invoice 10942 10942 0 0.00 0 12783 108 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 3349 305 0 0
x_make_qstorno_1000_1200 5473 5473 0 0.00 2 45976 1171 0 0
x_make_qstorno_1200_2000 5449 5449 0 0.00 4 114047 2933 0 0
x_make_qstorno_2100_3300 463452 463452 0 0.00 0 192291 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-07-2022 10:38:42 to 19-07-2022 10:38:46.

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:22 895 8192000000 8930007696 8988024832 27905872 0.339 6 4 8 0 28717520 243680 567968
08:08:24 17977 8192000000 9717127776 10014490624 798031296 8.876 100 93 185 0 827909968 7183200 22695472
08:09:51 28157 8192000000 9769492320 10081759232 843489840 9.335 100 84 168 0 874243520 10861104 19892576
08:11:25 11230 8192000000 9805324256 10122489856 869723536 9.597 100 91 182 0 912916576 22612256 20580784
08:12:41 10740 8192000000 9857573664 10190438400 911822592 10.015 100 81 161 1 968961440 39438064 17700784
08:13:56 5658 8192000000 9911272864 10256437248 953303792 10.423 100 85 160 1 1023267808 52878336 17085680
08:15:08 3043 8192000000 9942521552 10298322944 994927088 10.829 100 84 156 1 1054821120 43282640 16611392
08:16:14 758 8192000000 9994721488 10355421184 1016252704 11.036 100 97 186 1 1120218416 85614784 18350928
08:17:16 916 8192000000 10021243312 10392031232 1042132416 11.285 100 98 188 0 1136945984 76343744 18469824
08:18:19 952 8192000000 10041699424 10416275456 1059393472 11.451 100 99 191 3 1163444144 84876288 19174384
08:19:20 873 8192000000 10063587648 10442534912 1070246912 11.554 100 97 183 2 1181060288 92498544 18314832
08:20:22 6873 8192000000 10034382560 10420727808 1070704528 11.559 100 72 128 4 1154189344 69345248 14139568
08:21:31 1137 8192000000 10039573328 10427400192 1079009328 11.638 100 82 152 0 1161732944 66734880 15988736
08:22:34 1215 8192000000 10072956400 10458669056 1088817280 11.731 100 92 182 3 1190777616 84034352 17925984
08:23:36 1756 8192000000 10019254864 10410291200 1091221520 11.754 100 66 122 2 1142945376 39551520 12172336
08:24:38 3269 8192000000 10049028288 10440220672 1098571552 11.824 100 73 143 1 1174188960 60372544 15244864
08:25:42 2356 8192000000 10076504320 10463285248 1102041616 11.857 100 92 169 3 1195880896 78444272 15395008
08:26:45 1025 8192000000 10066028048 10459512832 1101032000 11.847 100 96 179 1 1188472096 67502272 19937824
08:27:50 1329 8192000000 10047092048 10436587520 1102154192 11.858 100 92 177 3 1171223376 50303280 18765904
08:28:53 2603 8192000000 10062319920 10453098496 1094162784 11.782 100 81 149 3 1182856000 73438848 15254368
08:29:57 977 8192000000 10036074240 10424041472 1084903328 11.694 100 97 187 3 1157817360 54543600 18370432
08:30:58 3532 8192000000 10028860192 10411270144 1071148928 11.563 98 75 135 2 1150967296 66250000 13568368
08:32:02 893 8192000000 10045136176 10430443520 1070099600 11.553 100 89 164 1 1168064784 81331008 16634176
08:33:04 992 8192000000 10007631680 10386710528 1059001904 11.447 100 91 173 5 1132674384 55626016 18046464
08:34:06 1140 8192000000 9959628736 10334896128 1035564208 11.222 99 75 135 2 1082000576 33161856 13274512
08:35:09 6409 8192000000 9983116832 10361999360 1027011040 11.140 99 62 119 0 1098790880 59259456 12520384
08:36:17 1132 8192000000 10001111200 10382135296 1053619552 11.395 100 93 169 1 1129684256 56996496 19068208
08:37:19 1252 8192000000 10015804816 10397077504 1068765344 11.540 100 90 162 2 1139315680 54640128 15910208
08:38:29 2669 8192000000 10022345888 10403753984 1076880992 11.618 100 78 138 1 1144354320 52926336 14546992
08:39:37 21194 8192000000 10056740784 10450358272 1079434464 11.642 100 58 114 0 1174055152 83353808 11266880
08:41:07 1636 8192000000 10082437376 10469642240 1103082864 11.867 100 73 135 0 1199097952 81387104 14627984
08:42:11 2050 8192000000 10106564608 10511151104 1113744848 11.968 100 81 160 0 1233542880 101612544 18185488
08:43:18 3084 8192000000 10126139312 10521321472 1124961408 12.074 100 83 155 2 1246082688 105043472 16077808
08:44:24 1131 8192000000 10165014624 10562744320 1137109536 12.188 100 85 157 0 1281654672 128121504 16423632
08:45:29 1014 8192000000 10228677664 10626895872 1150064176 12.310 100 93 174 0 1337634256 169181600 18388480
08:46:35 2821 8192000000 10211456912 10617716736 1153567888 12.343 100 68 127 2 1321692384 153178624 14945872
08:47:42 5086 8192000000 10221056352 10625331200 1159100608 12.395 100 70 129 0 1331893792 159318912 13474272
08:48:53 3363 8192000000 10243521264 10641334272 1154396192 12.351 99 86 160 1 1355229808 183575760 17257856
08:50:00 3157 8192000000 10283785600 10692206592 1169530640 12.492 100 80 145 0 1399452416 213420784 16500992
08:51:06 2433 8192000000 10335946368 10753286144 1184478208 12.632 100 84 163 1 1447238192 244423136 18336848
08:52:12 943 8192000000 10389275552 10795499520 1188927968 12.673 100 97 193 2 1498612288 287407648 22276672
08:53:17 742 8192000000 10406575632 10831831040 1191307568 12.696 100 91 179 1 1515702880 303832960 20562352
08:54:21 777 8192000000 10447043824 10883772416 1190291568 12.686 100 96 189 1 1554810624 342717472 21801584
08:55:25 1139 8192000000 10438510608 10850672640 1172300768 12.518 99 94 185 0 1539162688 345813168 21048752
08:56:31 919 8192000000 10445732592 10882076672 1182258720 12.611 100 94 186 0 1552776000 348184048 22333232
08:57:35 1799 8192000000 10448135488 10884947968 1178036384 12.572 100 90 171 1 1555820176 357751712 20032080
08:58:43 882 8192000000 10426247872 10856824832 1179799328 12.588 100 92 181 1 1536213248 334321472 22092448
08:59:47 670 8192000000 10447135488 10878185472 1174467248 12.539 99 94 184 1 1552180304 355639200 22073856
09:00:50 1435 8192000000 10425338624 10880708608 1178313136 12.574 100 95 179 0 1527556384 326103632 23139616
09:01:53 2832 8192000000 10417822848 10867859456 1183882128 12.626 100 75 142 1 1518053520 317056768 17114624
09:03:00 2521 8192000000 10458423040 10907897856 1190006336 12.683 100 74 146 0 1563646352 356036112 17603904
09:04:06 9164 8192000000 10448956496 10921250816 1190232464 12.686 100 54 104 1 1546750720 342186240 14332016
09:05:18 13548 8192000000 10434978608 10883141632 1180960480 12.599 100 39 78 0 1545522032 354150240 10411312
09:06:46 7973 8192000000 10423322192 10883510272 1174459392 12.538 100 48 96 0 1527357344 340096656 12801296
09:08:01 25404 8192000000 10322559536 10847166464 1152758368 12.335 99 19 38 0 1443862528 286059648 5044512
09:09:28 24495 8192000000 10209191008 10711220224 1132224288 12.142 99 19 36 0 1340296640 203903856 4168496
09:11:21 75177 8192000000 10067573824 10581536768 1121109904 12.037 98 12 25 0 1186524400 63244528 2169968
09:13:38 45639 8192000000 10120791024 10556403712 1137160880 12.189 99 30 55 0 1198502336 55006880 6334576
09:15:49 127079 8192000000 10168522592 10628976640 1125041088 12.075 98 36 72 1 1264432640 131646080 7745472
09:19:11 37623 8192000000 10078138416 10544631808 1117860288 12.007 99 35 70 0 1152532528 27641920 7030320
09:20:49 27507 8192000000 10078251952 10509672448 1142664512 12.241 100 36 66 0 1173886160 24412448 6809200
09:22:17 7681 8192000000 10118179632 10540642304 1142274640 12.237 100 85 154 3 1210848000 52414352 16159008
09:23:26 217784 8192000000 10158409408 10613772288 1108902864 11.922 96 27 55 0 1190859808 76034800 5922144
09:28:07 47961 8192000000 10105277280 10610540544 1135055664 12.169 99 20 40 0 1225030848 85355360 4619824
09:30:06 64451 8192000000 10108762112 10576392192 1121591120 12.042 98 12 24 0 1185437728 61265808 2580800
09:32:11 49697 8192000000 10082437856 10576637952 1130890592 12.130 99 15 30 0 1199214704 64889120 3434992
09:34:06 69385 8192000000 10105682976 10613919744 1097098352 11.810 96 22 44 0 1191582944 89641232 4843360
09:36:17 67064 8192000000 10146031088 10639409152 1106468784 11.899 98 22 42 0 1250396208 139063920 4863504
09:38:35 67618 8192000000 10054548192 10511548416 1067237568 11.526 95 22 42 0 1104399712 33097872 4064272
09:40:44 61609 8192000000 10086043120 10566762496 1107969200 11.913 98 12 24 1 1176498352 66156880 2372272
09:42:49 116809 8192000000 10159203584 10606497792 1143401152 12.248 100 35 70 1 1251789424 100565520 7822752
09:47:03 69387 8192000000 10051933920 10502057984 1110516944 11.937 99 17 32 1 1150826096 36908224 3400928
09:49:17 34269 8192000000 10052213872 10528436224 1119483552 12.022 100 8 16 0 1165651760 44436944 1731264
09:50:58 94758 8192000000 10056967008 10520154112 1117162352 12.000 99 22 44 0 1152099360 30191952 4745056
09:53:41 42910 8192000000 10065372416 10527465472 1124458480 12.069 99 17 35 0 1169435920 41394976 3582464
09:55:28 49469 8192000000 10050851664 10494054400 1067822304 11.531 95 25 48 0 1120495632 47323088 5350240
09:57:21 29131 8192000000 10058441808 10499080192 1105967488 11.894 99 44 82 3 1166145744 52237168 7941088
09:59:04 16853 8192000000 10151658032 10581209088 1129019536 12.112 100 55 109 0 1224913408 85082912 10810960
10:00:22 40823 8192000000 10189755328 10627325952 1144422368 12.257 100 46 90 1 1260913488 107077792 9413328
10:02:16 68435 8192000000 10113564496 10555838464 1096722384 11.807 97 16 30 0 1225157792 125091120 3344288
10:04:26 14850 8192000000 10083910192 10511851520 1120371008 12.030 100 38 74 0 1161801072 33920256 7509808
10:05:43 95237 8192000000 10090376896 10566606848 1120026560 12.027 99 40 78 1 1206180176 78249888 7903728
10:08:20 67898 8192000000 10055848992 10540728320 1110960752 11.942 98 12 23 0 1158352496 45000608 2391136
10:10:28 35162 8192000000 10061005840 10564153344 1099355792 11.832 97 3 6 0 1135919808 36288432 275584
10:12:09 18391 8192000000 10101340624 10542985216 1130672128 12.128 99 39 76 0 1183269920 43904656 8693136
10:13:32 43762 8192000000 10114224640 10555326464 1132615632 12.146 99 32 58 1 1211202384 72874640 5712112
10:15:16 17323 8192000000 10182361696 10616950784 1146389168 12.276 100 51 96 0 1271911008 114618192 10903648
10:16:42 28643 8192000000 10202059648 10644480000 1159325120 12.397 100 37 70 1 1289300672 121745328 8230224
10:18:12 19267 8192000000 10239878704 10682183680 1146184128 12.274 99 54 108 0 1329187360 170619056 12384176
10:19:36 23773 8192000000 10258960096 10708008960 1155742992 12.363 100 37 74 0 1356009616 191118288 9148336
10:21:04 34731 8192000000 10230111248 10680496128 1149420736 12.304 99 40 80 0 1330878064 172050960 9406368
10:22:49 90158 8192000000 10175998432 10770632704 1154239136 12.349 100 14 28 0 1305278304 148029936 3009232
10:25:20 71340 8192000000 10088996816 10523729920 1133123568 12.151 99 27 55 0 1170450400 31906512 5420320
10:28:21 37725 8192000000 10067693952 10542047232 1120327696 12.030 98 24 45 0 1164826672 40069616 4429360
10:30:05 53118 8192000000 10078927872 10552188928 1139202688 12.208 100 8 16 0 1172265984 31708816 1354480
10:31:58 83610 8192000000 10076446976 10549583872 1120152240 12.028 98 16 33 0 1150658560 27565952 2940368
10:34:25 85708 8192000000 10088740544 10586791936 1107537104 11.909 97 18 35 0 1182700976 71133648 4030224
10:36:51 33346 8192000000 9120825168 9392246784 276364256 3.263 24 5 8 2 288205296 10883072 957968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-07-2022 10:38:48 to 19-07-2022 10:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 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 19-07-2022 10:39:05 to 19-07-2022 10:39:07.

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          222087
MON$OLDEST_ACTIVE               222088
MON$OLDEST_SNAPSHOT             222088
MON$NEXT_TRANSACTION            222088
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                       2070880
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

19.07.22 10:39:08. 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-19 10:39:10 No severe PSQL-related problems occured
19.07.22 10:39:11. End of report.