Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.04.2022 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16600892 kB
MemAvailable 31592740 kB
Buffers 0 kB
Cached 15137052 kB
SwapCached 2260 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-04-06 13:37:13
DTS_END                         2022-04-06 13:37:13.8420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56226
TRN_ID                          221464
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: 8720.53 29580 1046463
customer order: creation 165.17 492 19820
customer order: refuse 14.59 374 1751
order to supplier: creation 44.04 1461 5285
order to supplier: removal 3.22 167045 386
invoice (draft): creation 43.97 3336 5276
invoice (draft): removal 46.93 1040 5632
invoice accept: apply 41.22 27611 4946
invoice accept: cancel 42.63 1339 5115
customer reserve: creation 3848.68 263 461841
customer reserve: removal 4193.85 100 503262
realization accept: apply 78.64 274 9437
realization accept: cancel 72.53 169 8703
payment from customer: creation 55.96 193 6715
payment from customer: removal 5.34 118 641
payment to supplier: creation 47.85 203 5742
payment to supplier: removal 3.41 108 409
service: total inventory turnovers 3.92 4180 470
service: total monetary turnovers 7.56 359 907
service: aggregate perf. data 0.99 14384 119
service: refresh index statistics 0.05 368555 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-04-2022 13:39:02 to 06-04-2022 13:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8481
TEST_TIME 2 6993
TEST_TIME 3 6378
TEST_TIME 4 7235
TEST_TIME 5 6747
TEST_TIME 6 6878
TEST_TIME 7 6557
TEST_TIME 8 6539
TEST_TIME 9 6822
TEST_TIME 10 6723
TEST_TIME 11 6954
TEST_TIME 12 7013
TEST_TIME 13 7055
TEST_TIME 14 7238
TEST_TIME 15 7184
TEST_TIME 16 7271
TEST_TIME 17 7400
TEST_TIME 18 7408
TEST_TIME 19 7447
TEST_TIME 20 7573
TEST_TIME 21 7486
TEST_TIME 22 7475
TEST_TIME 23 7733
TEST_TIME 24 8429
TEST_TIME 25 8948
TEST_TIME 26 9233
TEST_TIME 27 9383
TEST_TIME 28 9668
TEST_TIME 29 10078
TEST_TIME 30 10229
TEST_TIME 31 10454
TEST_TIME 32 10717
TEST_TIME 33 10774
TEST_TIME 34 10462
TEST_TIME 35 10250
TEST_TIME 36 10230
TEST_TIME 37 10609
TEST_TIME 38 10383
TEST_TIME 39 10333
TEST_TIME 40 10175
TEST_TIME 41 10048
TEST_TIME 42 10004
TEST_TIME 43 9962
TEST_TIME 44 9948
TEST_TIME 45 9835
TEST_TIME 46 9752
TEST_TIME 47 9680
TEST_TIME 48 9542
TEST_TIME 49 9480
TEST_TIME 50 9423
TEST_TIME 51 9391
TEST_TIME 52 9404
TEST_TIME 53 9369
TEST_TIME 54 9270
TEST_TIME 55 9119
TEST_TIME 56 9124
TEST_TIME 57 9042
TEST_TIME 58 9082
TEST_TIME 59 8942
TEST_TIME 60 8968
TEST_TIME 61 8924
TEST_TIME 62 8873
TEST_TIME 63 8916
TEST_TIME 64 8903
TEST_TIME 65 8909
TEST_TIME 66 8867
TEST_TIME 67 8915
TEST_TIME 68 8916
TEST_TIME 69 8968
TEST_TIME 70 8968
TEST_TIME 71 9108
TEST_TIME 72 9101
TEST_TIME 73 9110
TEST_TIME 74 9115
TEST_TIME 75 9099
TEST_TIME 76 8997
TEST_TIME 77 9021
TEST_TIME 78 8932
TEST_TIME 79 8879
TEST_TIME 80 8925
TEST_TIME 81 8864
TEST_TIME 82 8822
TEST_TIME 83 8803
TEST_TIME 84 8787
TEST_TIME 85 8740
TEST_TIME 86 8817
TEST_TIME 87 8782
TEST_TIME 88 8793
TEST_TIME 89 8830
TEST_TIME 90 8815
TEST_TIME 91 8800
TEST_TIME 92 8837
TEST_TIME 93 8849
TEST_TIME 94 8942
TEST_TIME 95 8914
TEST_TIME 96 8925
TEST_TIME 97 8887
TEST_TIME 98 8853
TEST_TIME 99 8847
TEST_TIME 100 8782
TEST_TIME 101 8737
TEST_TIME 102 8680
TEST_TIME 103 8659
TEST_TIME 104 8659
TEST_TIME 105 8625
TEST_TIME 106 8672
TEST_TIME 107 8670
TEST_TIME 108 8636
TEST_TIME 109 8696
TEST_TIME 110 8680
TEST_TIME 111 8703
TEST_TIME 112 8730
TEST_TIME 113 8740
TEST_TIME 114 8779
TEST_TIME 115 8752
TEST_TIME 116 8810
TEST_TIME 117 8830
TEST_TIME 118 8853
TEST_TIME 119 8809
TEST_TIME 120 8736

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-04-2022 13:39:06 to 06-04-2022 13:39:09.

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 1469841 1469841 0 0.00 0 19988 15 0 0
doc_list_biud 1469820 1469820 0 0.00 0 21383 40 0 0
sp_add_invoice_to_stock 5086 4946 140 2.75 46 206085 27612 0 140
sp_cancel_adding_invoice 5115 5115 0 0.00 6 79029 1339 0 0
sp_cancel_client_order 1751 1751 0 0.00 6 17926 374 0 0
sp_cancel_customer_reserve 503262 503262 0 0.00 1 19425 101 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 4 5070 119 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 4 1805 108 0 0
sp_cancel_supplier_invoice 5646 5632 14 0.24 2 17469 1041 0 14
sp_cancel_supplier_order 386 386 0 0.00 12 1300874 167046 0 0
sp_cancel_write_off 8703 8703 0 0.00 2 17590 170 0 0
sp_client_order 19820 19820 0 0.00 6 25888 493 0 0
sp_customer_reserve 461841 461841 0 0.00 1 39422 264 0 0
sp_fill_shopping_cart 30364 30364 0 0.00 2 28109 87 0 0
sp_fill_shopping_cart_clo_res 461808 461808 0 0.00 0 16765 20 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 26723 404 0 0
sp_kill_cost_storno 14868 14868 0 0.00 0 18023 75 0 0
sp_kill_qstorno_ret_qs2qd 1021245 1021245 0 0.00 0 18856 44 0 0
sp_kill_qty_storno 539195 539195 0 0.00 0 21383 101 0 0
sp_lock_selected_doc 19948 19948 0 0.00 0 6873 8 0 0
sp_make_cost_storno 26806 26806 0 0.00 0 16805 87 0 0
sp_multiply_rows_for_pdistr 26803 26803 0 0.00 0 8175 38 0 0
sp_multiply_rows_for_qdistr 19820 19820 0 0.00 0 12281 179 0 0
sp_pay_from_customer 6715 6715 0 0.00 6 15199 193 0 0
sp_pay_to_supplier 5742 5742 0 0.00 7 14981 204 0 0
sp_payment_common 12457 12457 0 0.00 2 13312 53 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 1 17914 299 0 0
sp_qd_handle_on_invoice_upd_sts 10024 10024 0 0.00 0 21382 517 0 0
sp_qd_handle_on_reserve_upd_sts 9437 9437 0 0.00 0 14124 61 0 0
sp_reserve_write_off 9437 9437 0 0.00 8 14988 274 0 0
sp_supplier_invoice 5276 5276 0 0.00 11 72383 3336 0 0
sp_supplier_order 5285 5285 0 0.00 8 62196 1461 0 0
srv_aggregate_perf_data 120 119 1 0.83 2074 142277 14384 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 762 117206 35384 0 0
srv_find_qd_qs_mism 997772 997772 0 0.00 0 19986 12 0 0
srv_make_invnt_saldo 470 470 0 0.00 71 126308 4180 0 0
srv_make_money_saldo 907 907 0 0.00 1 12285 359 0 0
srv_recalc_idx_stat 6 6 0 0.00 207223 451277 368556 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4950 4950 0 0.00 0 16718 839 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 6862 462 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 3149 25 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 1813 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 6041 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 762 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 753 34 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 75488 6934 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 1078 21 0 0
t$perf-norm:sp_client_order 19820 19820 0 0.00 0 6316 32 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 2408 16 0 0
t$perf-norm:sp_pay_from_customer 6715 6715 0 0.00 0 3547 18 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 5760 18 0 0
t$perf-norm:sp_reserve_write_off 9437 9437 0 0.00 0 5483 22 0 0
t$perf-norm:sp_supplier_invoice 5276 5276 0 0.00 0 5297 29 0 0
t$perf-norm:sp_supplier_order 5285 5285 0 0.00 0 4139 28 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 816 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 322 7 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 735 11 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 213 3 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 331 122 0 0
v_add_invoice_to_stock 10098 9818 280 2.77 0 15604 25 0 280
v_all_customers 36512 36512 0 0.00 0 57 3 0 0
v_all_suppliers 29590 29590 0 0.00 0 156 4 0 0
v_all_wares 39638 39638 0 0.00 0 284 3 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 1130 14 0 0
v_cancel_client_order 3502 3502 0 0.00 0 2685 12 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 723 11 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 87 9 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 461 10 0 0
v_cancel_supplier_invoice 798 770 28 3.50 0 2262 26 0 28
v_cancel_supplier_order 772 772 0 0.00 0 3274 20 0 0
v_cancel_write_off 1926 1926 0 0.00 0 2525 22 0 0
v_max_id_clo_ord 5280 5280 0 0.00 0 276 4 0 0
v_max_id_clo_res 965 965 0 0.00 0 1175 12 0 0
v_max_id_ord_sup 5265 5265 0 0.00 0 4251 8 0 0
v_max_non_paid_invoice 1492 1492 0 0.00 0 180 7 0 0
v_max_non_paid_realizn 3395 3395 0 0.00 0 13300 15 0 0
v_min_id_clo_ord 5280 5280 0 0.00 0 8720 10 0 0
v_min_id_clo_res 965 965 0 0.00 0 3809 15 0 0
v_min_id_ord_sup 5265 5265 0 0.00 0 6284 12 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 6453 19 0 0
v_min_non_paid_realizn 6715 6715 0 0.00 0 10572 27 0 0
v_our_firm 9766 9766 0 0.00 0 54 4 0 0
v_reserve_write_off 18874 18874 0 0.00 0 5972 11 0 0
x_lock_depdocs_on_canc_invoice 10362 10362 0 0.00 0 18897 126 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 2678 339 0 0
x_make_qstorno_1000_1200 5285 5285 0 0.00 2 42316 1285 0 0
x_make_qstorno_1200_2000 5276 5276 0 0.00 4 72360 3082 0 0
x_make_qstorno_2100_3300 461841 461841 0 0.00 0 39416 232 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-04-2022 13:39:10 to 06-04-2022 13:39:15.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:18 19170 8192000000 9721634240 10023575552 800509968 8.901 100 77 154 0 826516880 6960128 19046784
11:09:41 16263 8192000000 9772841024 10082471936 849266944 9.393 100 91 182 0 882355024 11621488 21466592
11:10:59 18626 8192000000 9803730928 10120007680 872723120 9.627 100 91 182 1 910904048 17342128 20838800
11:12:29 9724 8192000000 9859623616 10193526784 917623952 10.073 100 80 160 0 972415728 37776864 17014912
11:13:42 7582 8192000000 9912141648 10254983168 954646112 10.437 100 85 169 1 1023787888 51924880 17216896
11:14:53 2260 8192000000 9977789664 10328973312 990093648 10.782 100 88 172 0 1090774720 83388448 17292624
11:15:58 1220 8192000000 9997524944 10358956032 1022499936 11.096 100 96 186 3 1118817504 78469200 17848368
11:17:01 936 8192000000 10003595200 10369466368 1044062304 11.304 100 96 187 0 1122591600 60053904 18475392
11:18:03 996 8192000000 10032542528 10412785664 1062229632 11.478 100 94 188 2 1156669568 75649968 18789968
11:19:05 1085 8192000000 10030429632 10408329216 1061601552 11.472 100 97 185 2 1149009504 67768112 19639840
11:20:07 1603 8192000000 10039542064 10416418816 1070302784 11.555 100 92 179 0 1155532880 66868656 18361440
11:21:09 7792 8192000000 10029477936 10412122112 1080144192 11.649 100 72 139 2 1148802064 54070496 14587376
11:22:17 14985 8192000000 10045712416 10443927552 1090513744 11.748 100 55 100 3 1168795808 68685712 9596352
11:23:33 1122 8192000000 10048664976 10439761920 1097038272 11.810 100 79 152 1 1166588096 53211152 16338672
11:24:35 1092 8192000000 10070773424 10462150656 1098418400 11.823 100 92 177 0 1188405376 71779136 18207840
11:25:37 1087 8192000000 10055395792 10447175680 1104535472 11.881 100 90 176 3 1177619616 53725392 19358752
11:26:39 987 8192000000 10081274624 10473369600 1106221808 11.897 100 93 180 2 1200864128 75977808 18664512
11:27:41 965 8192000000 10044444384 10433703936 1096771280 11.807 100 80 151 0 1168551920 55993712 15786928
11:28:43 1067 8192000000 10050776768 10438164480 1081030160 11.657 100 84 159 1 1171911792 74071248 16810384
11:29:44 1221 8192000000 10042987728 10426331136 1077820400 11.627 99 95 179 3 1161217472 64850560 18546512
11:30:46 4896 8192000000 10033396656 10425700352 1076520544 11.614 100 68 130 2 1155162512 64720656 13921312
11:31:53 8160 8192000000 10002075488 10390528000 1053657328 11.396 99 60 113 2 1124843552 59782368 11403856
11:33:03 836 8192000000 9986053712 10355322880 1028672240 11.156 100 92 173 2 1110125040 63446576 18006224
11:34:05 6157 8192000000 9974964848 10356936704 1026250704 11.132 98 69 126 2 1100117184 59825776 14040704
11:35:12 1550 8192000000 9994448496 10381062144 1034490480 11.212 99 63 126 0 1115149392 66352608 14306304
11:36:15 6338 8192000000 9989307360 10370891776 1024474144 11.115 98 65 126 0 1107432960 70121680 12837136
11:37:23 9632 8192000000 9967209968 10352906240 1044334912 11.306 100 73 146 0 1089269920 29973584 14961424
11:38:57 6728 8192000000 10033309008 10410983424 1057713680 11.435 100 80 144 0 1155949904 83116656 15119568
11:40:11 5742 8192000000 10041837040 10430185472 1074366560 11.594 100 59 118 0 1161080272 74355440 12358272
11:41:22 1496 8192000000 10073620272 10458095616 1086322064 11.708 100 96 183 3 1194267536 88651216 19294256
11:42:27 7847 8192000000 10102564320 10495148032 1102423552 11.861 100 69 126 1 1214529520 99052240 13053728
11:43:43 1014 8192000000 10147672256 10541015040 1116192976 11.991 100 94 180 4 1263002416 127879168 18930272
11:44:50 3851 8192000000 10189565184 10580529152 1120658752 12.033 100 87 170 2 1302513104 164203952 17650400
11:45:57 1096 8192000000 10188473584 10594103296 1132688368 12.147 100 94 186 1 1299668160 144854304 22125488
11:47:02 884 8192000000 10196146432 10590752768 1140625328 12.221 100 86 161 1 1310069280 151685824 17758128
11:48:15 1771 8192000000 10243550240 10652205056 1162303312 12.425 100 81 157 1 1350619984 170978944 17337728
11:49:20 1069 8192000000 10284264656 10688745472 1177680128 12.569 100 97 192 2 1395257568 196496800 21080640
11:50:24 1213 8192000000 10293952752 10709999616 1178979024 12.581 100 93 181 1 1401160496 202800848 19380624
11:51:28 1944 8192000000 10326080032 10726330368 1189929904 12.683 100 87 174 0 1433936368 223562240 20444224
11:52:33 1089 8192000000 10383783872 10788376576 1206642704 12.838 100 91 174 1 1492849776 266257472 19949600
11:53:37 1266 8192000000 10413030704 10846191616 1207188912 12.843 100 93 186 0 1521241712 292131440 21921360
11:54:42 1428 8192000000 10473891680 10879868928 1212753584 12.895 100 97 190 0 1579777632 343899056 23124992
11:55:46 1290 8192000000 10477619488 10895716352 1203269648 12.807 100 89 174 0 1588778016 363610336 21898032
11:56:51 903 8192000000 10528131136 10963034112 1206455952 12.836 100 98 191 2 1630646656 401415440 22775264
11:57:55 754 8192000000 10556316272 10967044096 1199474992 12.771 100 82 161 0 1662641232 443804992 19361248
11:59:00 977 8192000000 10583401696 11000254464 1179751680 12.588 100 83 163 0 1689189744 489462048 19976016
12:00:04 1595 8192000000 10586021632 11042992128 1180276736 12.593 100 77 152 1 1692408608 491992080 20139792
12:01:12 1474 8192000000 10505545760 11017248768 1179893664 12.589 100 79 152 2 1609564320 408481712 21188944
12:02:15 5119 8192000000 10481829936 10952171520 1175585728 12.549 100 60 120 0 1586341952 395739360 15016864
12:03:31 23620 8192000000 10405675520 10855923712 1142106720 12.235 98 30 60 0 1481911664 331812512 7992432
12:04:55 29871 8192000000 10371712992 10887512064 1145344784 12.266 100 23 46 0 1504746688 353318848 6083056
12:06:50 17297 8192000000 10240728480 10677620736 1145201552 12.264 100 34 65 1 1349918880 196734704 7982624
12:08:08 40827 8192000000 10215288512 10705678336 1107617296 11.910 97 32 64 0 1290348304 174853776 7877232
12:09:50 22907 8192000000 10162315264 10625400832 1128365872 12.106 100 26 50 1 1265258592 130919584 5973136
12:11:15 38990 8192000000 10175459008 10616033280 1137791920 12.195 100 53 100 2 1274191984 125856864 10543200
12:13:18 72495 8192000000 10146197632 10646708224 1114127856 11.971 99 19 38 0 1281041408 162512512 4401040
12:15:30 37039 8192000000 10078935072 10527944704 1125422720 12.078 100 46 93 1 1192465024 56322000 10720304
12:17:17 110289 8192000000 10140916192 10594467840 1131157296 12.132 99 26 45 2 1196339104 60403584 4778224
12:20:10 95009 8192000000 10086645504 10581434368 1138813408 12.204 99 23 46 0 1218639408 75057696 4768304
12:22:48 23068 8192000000 10079639520 10530734080 1136902096 12.186 99 49 96 0 1182732048 36028688 9801264
12:24:16 60409 8192000000 10065557120 10512187392 1107439056 11.908 97 15 30 0 1136727424 25968528 3319840
12:26:18 10466 8192000000 10095999056 10531323904 1147575984 12.287 100 63 127 1 1206856112 44096688 15183440
12:27:36 19689 8192000000 10153417136 10604720128 1153344864 12.341 100 58 105 4 1264766512 99618160 11803488
12:29:15 41747 8192000000 10098937120 10602991616 1131163056 12.132 99 13 26 0 1216028528 81903376 2962096
12:30:57 61709 8192000000 10070960944 10552238080 1131485152 12.135 99 19 37 0 1169565120 34403152 3676816
12:32:59 79608 8192000000 10105862352 10580844544 1140185216 12.217 99 36 72 0 1221412848 73616784 7610848
12:35:20 117204 8192000000 10082368832 10607906816 1134221584 12.161 99 5 10 0 1205306160 70136096 948480
12:38:17 69125 8192000000 10075042720 10523353088 1129354864 12.115 99 13 26 1 1164399040 31890000 3154176
12:40:36 39280 8192000000 10094690304 10545692672 1142885984 12.243 100 32 64 0 1205707040 56398416 6422640
12:42:15 37415 8192000000 10101808640 10549063680 1109931184 11.932 97 46 90 0 1186618432 65840560 10846688
12:43:53 53874 8192000000 10114571664 10547679232 1107706736 11.911 97 27 54 1 1215825152 101693728 6424688
12:45:47 48684 8192000000 10144280304 10593435648 1153903344 12.346 100 21 42 0 1263856032 105555616 4397072
12:47:42 79071 8192000000 10085056304 10551169024 1073030368 11.581 94 27 54 0 1160468464 82198080 5240016
12:50:05 50971 8192000000 10119942240 10591518720 1121575072 12.042 98 36 72 1 1213548784 83884112 8089600
12:51:57 70509 8192000000 10104191728 10551394304 1126469472 12.088 98 32 64 0 1219308032 86142288 6696272
12:54:23 70917 8192000000 10064112800 10511888384 1134542544 12.164 99 30 60 1 1174670048 34151776 5975728
12:56:34 30334 8192000000 10111659024 10557743104 1130656928 12.128 100 46 92 1 1215705952 76298816 8750208
12:58:08 30047 8192000000 10055546976 10547281920 1119808640 12.025 99 7 14 1 1163323888 42257504 1257744
12:59:38 43611 8192000000 10061439760 10512699392 1117732720 12.006 98 11 22 0 1151438208 31638080 2067408
13:01:23 23915 8192000000 10074658368 10509643776 1129852832 12.120 99 30 58 2 1173026992 37277568 5896592
13:02:54 47265 8192000000 10090373376 10524160000 1116332128 11.992 99 27 54 0 1184801296 62700016 5769152
13:04:44 13957 8192000000 10071529088 10495565824 1115356912 11.983 100 52 105 3 1155581568 29631088 10593568
13:06:10 62569 8192000000 10055584624 10506891264 1086271456 11.707 97 29 56 0 1147087408 54648592 6167360
13:08:38 42381 8192000000 10126699888 10576994304 1124025872 12.065 100 52 104 1 1207428512 72833440 10569200
13:10:21 46294 8192000000 10134276352 10608078848 1131743840 12.138 100 29 58 1 1235374400 97905024 5725536
13:12:08 66220 8192000000 10135383216 10566877184 1133004592 12.150 99 18 36 2 1173836016 37102976 3728448
13:14:15 102878 8192000000 10084314672 10565664768 1142963296 12.243 100 23 46 1 1193798704 45938320 4897088
13:16:59 46204 8192000000 10060850768 10529931264 1138215616 12.199 100 9 18 1 1173442288 33537328 1689344
13:18:46 79400 8192000000 10110237600 10575859712 1149664976 12.306 100 11 22 0 1187060528 34851712 2543840
13:21:06 70415 8192000000 10099959072 10565992448 1127083760 12.094 98 12 24 0 1186068032 56394112 2590160
13:23:35 35403 8192000000 10094499808 10540478464 1112215248 11.953 97 19 38 0 1170110752 53858848 4036656
13:25:19 35388 8192000000 10140263376 10579230720 1119202064 12.019 97 27 52 1 1200252384 76066208 4984112
13:27:09 29067 8192000000 10117794880 10549428224 1137834816 12.195 99 34 65 1 1198210832 53740080 6635936
13:28:39 95834 8192000000 10107932384 10546532352 1109877696 11.931 96 32 64 1 1157207664 40814928 6515040
13:31:17 38584 8192000000 10085873680 10553950208 1083059984 11.677 95 40 79 2 1164551680 73841696 7650000
13:32:59 15119 8192000000 10140541024 10578440192 1128065984 12.103 100 47 94 0 1231992416 94542192 9384240
13:34:41 42184 8192000000 10098439600 10530189312 1091568864 11.758 96 29 58 1 1161534064 63747840 6217360
13:36:30 67970 8192000000 8985717952 9199616000 150930784 1.809 14 6 11 0 159311040 7264784 1115472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 06-04-2022 13:39:16 to 06-04-2022 13:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 140 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 06-04-2022 13:39:34 to 06-04-2022 13:39:36.

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          221746
MON$OLDEST_ACTIVE               221747
MON$OLDEST_SNAPSHOT             221747
MON$NEXT_TRANSACTION            221747
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                       2052640
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

06.04.22 13:39:37. 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-04-06 13:39:39 No severe PSQL-related problems occured
06.04.22 13:39:40. End of report.