Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.07.2022 13:39
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 15972496 kB
MemAvailable 31172048 kB
Buffers 0 kB
Cached 15766212 kB
SwapCached 2548 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-06 13:37:19
DTS_END                         2022-07-06 13:37:19.7570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33386
TRN_ID                          210360
ATT_ID                          627
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: 8850.53 25036 1062064
customer order: creation 161.01 524 19321
customer order: refuse 13.78 458 1654
order to supplier: creation 42.93 1445 5152
order to supplier: removal 3.51 196437 421
invoice (draft): creation 42.98 3096 5157
invoice (draft): removal 51.33 1181 6159
invoice accept: apply 38.57 30124 4628
invoice accept: cancel 46.38 1432 5566
customer reserve: creation 3635.96 283 436315
customer reserve: removal 4531.38 119 543766
realization accept: apply 76.13 300 9136
realization accept: cancel 86.44 189 10373
payment from customer: creation 55.29 186 6635
payment from customer: removal 5.11 154 613
payment to supplier: creation 44.37 212 5324
payment to supplier: removal 3.19 129 383
service: total inventory turnovers 3.69 4917 443
service: total monetary turnovers 7.50 363 900
service: aggregate perf. data 0.95 16040 114
service: refresh index statistics 0.03 243122 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-07-2022 13:40:07 to 06-07-2022 13:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6578
TEST_TIME 2 5532
TEST_TIME 3 5322
TEST_TIME 4 5520
TEST_TIME 5 5111
TEST_TIME 6 5055
TEST_TIME 7 5265
TEST_TIME 8 4993
TEST_TIME 9 5206
TEST_TIME 10 5214
TEST_TIME 11 5382
TEST_TIME 12 5490
TEST_TIME 13 6032
TEST_TIME 14 6149
TEST_TIME 15 6424
TEST_TIME 16 6698
TEST_TIME 17 6722
TEST_TIME 18 6715
TEST_TIME 19 6794
TEST_TIME 20 6844
TEST_TIME 21 6988
TEST_TIME 22 6939
TEST_TIME 23 7059
TEST_TIME 24 7238
TEST_TIME 25 7436
TEST_TIME 26 7370
TEST_TIME 27 7271
TEST_TIME 28 7495
TEST_TIME 29 7880
TEST_TIME 30 8259
TEST_TIME 31 8660
TEST_TIME 32 9054
TEST_TIME 33 9571
TEST_TIME 34 10064
TEST_TIME 35 10260
TEST_TIME 36 10727
TEST_TIME 37 10533
TEST_TIME 38 10380
TEST_TIME 39 10287
TEST_TIME 40 10131
TEST_TIME 41 10134
TEST_TIME 42 10197
TEST_TIME 43 10095
TEST_TIME 44 10060
TEST_TIME 45 9890
TEST_TIME 46 9790
TEST_TIME 47 9776
TEST_TIME 48 9702
TEST_TIME 49 9725
TEST_TIME 50 9679
TEST_TIME 51 9645
TEST_TIME 52 9586
TEST_TIME 53 9610
TEST_TIME 54 9505
TEST_TIME 55 9554
TEST_TIME 56 9681
TEST_TIME 57 9678
TEST_TIME 58 9652
TEST_TIME 59 9631
TEST_TIME 60 9580
TEST_TIME 61 9635
TEST_TIME 62 9737
TEST_TIME 63 9703
TEST_TIME 64 9626
TEST_TIME 65 9672
TEST_TIME 66 9681
TEST_TIME 67 9697
TEST_TIME 68 9695
TEST_TIME 69 9751
TEST_TIME 70 9720
TEST_TIME 71 9597
TEST_TIME 72 9584
TEST_TIME 73 9495
TEST_TIME 74 9469
TEST_TIME 75 9462
TEST_TIME 76 9444
TEST_TIME 77 9401
TEST_TIME 78 9344
TEST_TIME 79 9310
TEST_TIME 80 9264
TEST_TIME 81 9226
TEST_TIME 82 9262
TEST_TIME 83 9245
TEST_TIME 84 9249
TEST_TIME 85 9192
TEST_TIME 86 9158
TEST_TIME 87 9113
TEST_TIME 88 9116
TEST_TIME 89 9106
TEST_TIME 90 9117
TEST_TIME 91 9213
TEST_TIME 92 9168
TEST_TIME 93 9273
TEST_TIME 94 9334
TEST_TIME 95 9268
TEST_TIME 96 9187
TEST_TIME 97 9170
TEST_TIME 98 9212
TEST_TIME 99 9146
TEST_TIME 100 9108
TEST_TIME 101 9038
TEST_TIME 102 9025
TEST_TIME 103 8991
TEST_TIME 104 8959
TEST_TIME 105 8949
TEST_TIME 106 8965
TEST_TIME 107 8949
TEST_TIME 108 8971
TEST_TIME 109 8960
TEST_TIME 110 8972
TEST_TIME 111 8963
TEST_TIME 112 8976
TEST_TIME 113 8953
TEST_TIME 114 8944
TEST_TIME 115 8939
TEST_TIME 116 9020
TEST_TIME 117 9037
TEST_TIME 118 8979
TEST_TIME 119 8931
TEST_TIME 120 8875

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-07-2022 13:40:11 to 06-07-2022 13:40:14.

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 1465465 1465465 0 0.00 0 16538 15 0 0
doc_list_biud 1465453 1465453 0 0.00 0 79695 50 0 0
sp_add_invoice_to_stock 4720 4628 92 1.94 65 209181 30125 0 92
sp_cancel_adding_invoice 5566 5566 0 0.00 5 85919 1433 0 0
sp_cancel_client_order 1654 1654 0 0.00 5 12001 459 0 0
sp_cancel_customer_reserve 543766 543766 0 0.00 1 33002 119 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 4 10918 154 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 4 7383 130 0 0
sp_cancel_supplier_invoice 6168 6159 9 0.14 2 79703 1181 0 9
sp_cancel_supplier_order 421 421 0 0.00 11 1780698 196438 0 0
sp_cancel_write_off 10373 10373 0 0.00 2 16871 190 0 0
sp_client_order 19321 19321 0 0.00 5 29210 524 0 0
sp_customer_reserve 436315 436315 0 0.00 0 153616 284 0 0
sp_fill_shopping_cart 29619 29619 0 0.00 1 18335 87 0 0
sp_fill_shopping_cart_clo_res 436287 436287 0 0.00 0 16868 23 0 0
sp_get_clo_for_invoice 4582 4582 0 0.00 0 26336 366 0 0
sp_kill_cost_storno 16935 16935 0 0.00 0 9969 82 0 0
sp_kill_qstorno_ret_qs2qd 1104485 1104485 0 0.00 0 79693 52 0 0
sp_kill_qty_storno 581654 581654 0 0.00 0 79695 118 0 0
sp_lock_selected_doc 19189 19189 0 0.00 0 8740 8 0 0
sp_make_cost_storno 25676 25676 0 0.00 0 13264 93 0 0
sp_multiply_rows_for_pdistr 25675 25675 0 0.00 0 9253 40 0 0
sp_multiply_rows_for_qdistr 19321 19321 0 0.00 0 29163 200 0 0
sp_pay_from_customer 6635 6635 0 0.00 5 14974 187 0 0
sp_pay_to_supplier 5324 5324 0 0.00 7 18512 212 0 0
sp_payment_common 11959 11959 0 0.00 2 18490 49 0 0
sp_qd_handle_on_cancel_clo 1654 1654 0 0.00 0 11950 387 0 0
sp_qd_handle_on_invoice_upd_sts 10145 10145 0 0.00 0 18136 595 0 0
sp_qd_handle_on_reserve_upd_sts 9136 9136 0 0.00 0 14571 70 0 0
sp_reserve_write_off 9136 9136 0 0.00 8 18497 300 0 0
sp_supplier_invoice 5157 5157 0 0.00 9 89058 3097 0 0
sp_supplier_order 5152 5152 0 0.00 7 48106 1446 0 0
srv_aggregate_perf_data 114 114 0 0.00 2505 160033 16040 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 739 116498 32309 0 0
srv_find_qd_qs_mism 1017090 1017090 0 0.00 0 16531 12 0 0
srv_make_invnt_saldo 443 443 0 0.00 42 142564 4917 0 0
srv_make_money_saldo 900 900 0 0.00 1 8585 363 0 0
srv_recalc_idx_stat 4 4 0 0.00 51057 383617 243123 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4634 4634 0 0.00 0 15922 1030 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 6922 517 0 0
t$perf-norm:sp_cancel_client_order 1654 1654 0 0.00 0 12064 35 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 9915 51 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 1490 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 3173 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 1469 32 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 98021 8227 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 4696 25 0 0
t$perf-norm:sp_client_order 19322 19322 0 0.00 0 12041 44 0 0
t$perf-norm:sp_customer_reserve 905 905 0 0.00 0 1519 15 0 0
t$perf-norm:sp_pay_from_customer 6635 6635 0 0.00 0 11819 25 0 0
t$perf-norm:sp_pay_to_supplier 5324 5324 0 0.00 0 11946 24 0 0
t$perf-norm:sp_reserve_write_off 9136 9136 0 0.00 0 15004 28 0 0
t$perf-norm:sp_supplier_invoice 5157 5157 0 0.00 0 15515 37 0 0
t$perf-norm:sp_supplier_order 5152 5152 0 0.00 0 15523 39 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 233 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 98 5 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 409 13 0 0
t$perf-norm:srv_make_money_saldo 900 900 0 0.00 0 1271 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 223 61 0 0
v_add_invoice_to_stock 9334 9150 184 1.97 0 7648 26 0 184
v_all_customers 35652 35652 0 0.00 0 140 4 0 0
v_all_suppliers 28646 28646 0 0.00 0 68 5 0 0
v_all_wares 38636 38636 0 0.00 0 137 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 6679 24 0 0
v_cancel_client_order 3308 3308 0 0.00 0 909 8 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 5006 15 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 1 3048 13 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 7344 20 0 0
v_cancel_supplier_invoice 752 734 18 2.39 0 2152 28 0 18
v_cancel_supplier_order 842 842 0 0.00 0 7332 26 0 0
v_cancel_write_off 1944 1944 0 0.00 0 16818 39 0 0
v_max_id_clo_ord 5150 5150 0 0.00 0 5161 7 0 0
v_max_id_clo_res 905 905 0 0.00 0 2626 16 0 0
v_max_id_ord_sup 5151 5151 0 0.00 0 8363 8 0 0
v_max_non_paid_invoice 1301 1301 0 0.00 0 67 8 0 0
v_max_non_paid_realizn 3317 3317 0 0.00 0 285 7 0 0
v_min_id_clo_ord 5150 5150 0 0.00 0 4683 12 0 0
v_min_id_clo_res 905 905 0 0.00 0 4988 26 0 0
v_min_id_ord_sup 5151 5151 0 0.00 0 5956 15 0 0
v_min_non_paid_invoice 5324 5324 0 0.00 0 18466 21 0 0
v_min_non_paid_realizn 6635 6635 0 0.00 0 6974 20 0 0
v_our_firm 9620 9620 0 0.00 0 71 5 0 0
v_reserve_write_off 18272 18272 0 0.00 0 7226 10 0 0
x_lock_depdocs_on_canc_invoice 11358 11358 0 0.00 0 16046 128 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 7638 377 0 0
x_make_qstorno_1000_1200 5152 5152 0 0.00 2 48078 1266 0 0
x_make_qstorno_1200_2000 5157 5157 0 0.00 4 89022 2853 0 0
x_make_qstorno_2100_3300 436310 436310 0 0.00 0 153542 247 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-07-2022 13:40:15 to 06-07-2022 13:40:19.

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:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:24 24704 8192000000 9725135328 10025132032 801801152 8.915 100 88 176 0 830699984 7698448 21200384
11:10:00 23661 8192000000 9769967200 10078404608 842474880 9.325 100 93 185 0 878214896 13771264 21968752
11:11:38 12947 8192000000 9818928400 10137935872 872930400 9.629 100 82 164 0 917674624 25636416 19107808
11:12:53 9060 8192000000 9867529216 10199871488 913430912 10.031 100 85 168 0 979648336 47384432 18832992
11:14:06 2459 8192000000 9926394544 10269302784 957504464 10.465 100 92 182 0 1048471216 72122608 18844144
11:15:14 2528 8192000000 9971984816 10323193856 993231280 10.813 100 90 173 1 1092789504 81759520 17798704
11:16:19 1093 8192000000 9997860000 10360233984 1015532320 11.029 100 93 179 0 1118582416 84673216 18376880
11:17:23 610 8192000000 10006087424 10373640192 1038088528 11.246 100 96 179 0 1125733456 69232160 18412768
11:18:24 965 8192000000 10035025984 10411343872 1054658224 11.405 100 97 196 0 1154102912 80663472 18781216
11:19:26 938 8192000000 10064068176 10442268672 1068360224 11.536 100 96 183 1 1183571584 95807472 19403888
11:20:27 10268 8192000000 10047621616 10427334656 1076116784 11.610 100 86 149 5 1166354816 76266352 13971680
11:21:38 996 8192000000 10042090144 10426642432 1086273024 11.707 100 97 182 0 1163339312 58219264 18847024
11:22:41 1139 8192000000 10074686224 10461339648 1088000368 11.724 100 98 196 0 1195048816 86592672 20455776
11:23:43 1790 8192000000 10062120624 10452623360 1098382688 11.822 100 97 181 1 1183270768 65432240 19455840
11:24:46 951 8192000000 10051100608 10441383936 1096203488 11.802 100 93 179 0 1173508208 58948832 18355888
11:25:49 862 8192000000 10066012928 10457210880 1100291760 11.840 100 90 177 0 1187915872 69663920 17960192
11:26:52 1315 8192000000 10058892288 10449670144 1107123072 11.905 100 96 185 2 1182753072 56687824 18942176
11:27:54 1069 8192000000 10073823328 10468286464 1108453536 11.918 100 95 180 2 1198628016 72089520 18084960
11:28:56 991 8192000000 10061977968 10453413888 1100141712 11.839 100 100 199 0 1181702288 61703296 19857280
11:29:59 1189 8192000000 10049166368 10436108288 1087367856 11.718 100 97 190 0 1167954912 61438640 19148416
11:31:01 1016 8192000000 10054166032 10441609216 1082491536 11.671 100 89 168 0 1174011952 72639360 18881056
11:32:04 1202 8192000000 10008413504 10385731584 1042297568 11.287 98 89 163 2 1128022432 67590368 18134496
11:33:06 905 8192000000 10022179104 10396897280 1048687696 11.348 99 92 179 2 1144957872 77765488 18504688
11:34:08 1148 8192000000 10004133360 10375335936 1043684640 11.300 100 95 182 5 1128769552 67336608 17748304
11:35:11 3274 8192000000 9982228128 10350981120 1015339760 11.027 99 84 162 3 1094492128 63695264 15457104
11:36:16 2433 8192000000 10003952128 10384699392 1022812208 11.099 100 79 152 1 1130755888 91221360 16722320
11:37:20 1003 8192000000 9977551328 10353483776 1033132016 11.199 99 78 148 0 1097698032 50672448 13893568
11:38:28 19869 8192000000 10032248704 10414342144 1042932048 11.293 99 69 135 2 1150823824 94636144 13255632
11:39:57 3374 8192000000 10038871200 10422071296 1074998352 11.600 100 82 155 3 1161086448 69244240 16843856
11:41:08 10294 8192000000 10075027536 10463592448 1078905392 11.637 100 69 136 0 1198876304 105794192 14176720
11:42:25 18167 8192000000 10111522864 10504814592 1095401168 11.794 100 61 120 1 1216130352 108608992 12120192
11:43:54 3440 8192000000 10141150816 10527621120 1104784864 11.883 100 75 146 0 1256810864 136379792 15646208
11:45:05 12094 8192000000 10174159440 10566725632 1123091744 12.056 100 76 141 1 1285340352 147992704 14255904
11:46:21 1507 8192000000 10254437360 10642706432 1138348064 12.200 100 96 186 0 1367387056 209139328 19899664
11:47:26 1562 8192000000 10283990528 10680274944 1148765824 12.298 100 90 170 0 1402931136 233286912 20878400
11:48:32 2870 8192000000 10306247296 10715705344 1153851008 12.346 100 79 147 1 1426668080 256200496 16616576
11:49:39 4013 8192000000 10328332960 10760282112 1163520512 12.436 100 85 170 0 1439674240 256867952 19285776
11:50:45 2422 8192000000 10361723888 10778181632 1175701856 12.550 100 94 180 0 1469314304 272587104 21025344
11:51:53 1575 8192000000 10395928112 10808057856 1176826144 12.561 100 91 175 0 1496629680 299289376 20514160
11:52:58 1093 8192000000 10412911472 10827644928 1185950592 12.646 100 93 188 1 1522861824 315505760 21405472
11:54:03 1049 8192000000 10423271776 10833932288 1191024304 12.693 100 92 177 0 1534830064 321889888 21915872
11:55:07 1545 8192000000 10452984592 10860756992 1182980192 12.618 100 86 170 0 1566936064 362999984 20955888
11:56:14 1076 8192000000 10511208992 10919747584 1192898272 12.710 100 92 177 1 1616458480 402832608 20727600
11:57:18 731 8192000000 10523451648 10950459392 1201676624 12.792 100 96 189 0 1629391104 405338080 22376400
11:58:23 2963 8192000000 10550843952 10975686656 1189171408 12.676 100 92 180 0 1657403920 445642096 22590416
11:59:32 1708 8192000000 10593582048 11004710912 1199688000 12.773 100 99 192 2 1698363408 475095616 23579792
12:00:38 920 8192000000 10619026480 11076247552 1198574352 12.763 100 95 186 0 1721900528 500782800 22543376
12:01:43 988 8192000000 10622105440 11054673920 1193270640 12.714 100 96 188 1 1726591984 508123056 25198288
12:02:53 853 8192000000 10664303680 11081142272 1194904960 12.729 100 88 175 0 1768468656 551709584 21854112
12:04:00 2453 8192000000 10704135952 11152834560 1195015056 12.730 100 74 145 1 1806670352 593348528 18306768
12:05:08 1617 8192000000 10697271344 11176067072 1180311200 12.593 99 89 175 0 1800545376 598121824 22112352
12:06:14 1103 8192000000 10643662304 11159408640 1175011200 12.544 99 80 157 0 1750429440 555110992 20307248
12:07:22 7373 8192000000 10661914624 11118583808 1189190976 12.676 100 53 107 0 1771720352 568250816 14278560
12:08:32 10662 8192000000 10520392592 11027771392 1171151872 12.508 100 41 77 0 1630720544 448771424 10797248
12:10:00 12152 8192000000 10344283264 10882654208 1150688336 12.316 99 39 78 0 1444991552 284334848 9968368
12:11:16 10710 8192000000 10229511792 10713001984 1136167104 12.179 99 39 74 2 1355548704 210467648 8913952
12:12:27 58298 8192000000 10097180000 10574675968 1146595168 12.278 100 14 26 0 1280361488 130488912 3277408
12:14:26 17093 8192000000 10095919088 10536017920 1138883664 12.205 99 42 81 0 1184857264 37953280 8020320
12:15:51 93803 8192000000 10069932128 10513371136 1112964512 11.960 99 32 64 1 1176985552 56728112 7292928
12:18:33 116498 8192000000 10073969488 10532433920 1126273312 12.086 99 20 37 0 1204674528 74440336 3960880
12:21:35 105281 8192000000 10112262096 10534780928 1122744672 12.053 97 93 179 1 1200606304 58492528 19369104
12:24:23 6416 8192000000 10185935840 10621542400 1153348448 12.341 100 49 92 0 1284007376 120246528 10412400
12:25:36 2035 8192000000 10176916576 10606129152 1158432048 12.389 100 65 120 1 1271529104 99968944 13128112
12:26:40 52524 8192000000 10199998368 10652413952 1149027760 12.300 98 25 50 0 1314474272 159380560 6065952
12:28:33 48276 8192000000 10234569232 10722496512 1166103136 12.460 100 25 48 0 1325183920 153442880 5637904
12:31:11 36402 8192000000 10193597120 10663690240 1160375232 12.407 100 20 40 0 1309147296 143575376 5196688
12:33:01 52795 8192000000 10114795472 10597556224 1138869568 12.205 99 18 36 0 1234642256 91516496 4256192
12:34:58 84575 8192000000 10072897376 10533900288 1118162256 12.010 97 23 46 0 1175874032 53577504 4134272
12:37:29 68295 8192000000 10134310704 10607398912 1141533632 12.230 99 28 56 0 1222175552 74292224 6349696
12:40:25 56273 8192000000 10101271840 10537455616 1138160032 12.198 99 26 51 1 1185940480 42822480 4957968
12:42:25 35678 8192000000 10127893760 10561753088 1139235120 12.208 99 42 84 0 1212308256 64914352 8158784
12:44:08 43678 8192000000 10076351280 10534891520 1090746272 11.750 95 22 42 0 1124865088 30140768 3978048
12:45:57 50510 8192000000 10159344144 10616750080 1127837824 12.101 98 33 65 0 1205832560 71442672 6552064
12:47:53 63532 8192000000 10085057712 10523377664 1135995536 12.178 100 27 54 0 1169442960 27396576 6050848
12:49:57 60764 8192000000 10077211664 10504835072 1116899616 11.998 97 43 77 0 1178089680 53259184 7930880
12:51:59 96655 8192000000 10094341200 10532429824 1093974704 11.780 96 23 46 0 1152308960 52796736 5537520
12:54:44 68156 8192000000 10082826656 10548506624 1101821040 11.855 97 17 32 1 1169227504 63498560 3907904
12:56:53 43150 8192000000 10092204272 10567938048 1141125920 12.226 100 26 47 2 1214685200 68079504 5479776
12:59:09 89465 8192000000 10096609392 10626572288 1145633888 12.268 100 12 24 0 1229630608 81007856 2988864
13:01:40 53113 8192000000 10087195680 10544197632 1103738432 11.873 96 18 34 0 1136938544 29878048 3322064
13:03:36 16576 8192000000 10151241520 10596642816 1150725824 12.316 100 34 68 0 1250069088 91583280 7759984
13:04:55 13921 8192000000 10207521904 10640338944 1167689360 12.475 100 44 87 0 1306337408 129082752 9565296
13:06:31 40296 8192000000 10198366896 10659917824 1156559552 12.371 100 27 52 0 1288563040 126209920 5793568
13:08:11 37132 8192000000 10143553264 10611548160 1132909936 12.149 99 36 71 0 1257204944 116642128 7652880
13:09:49 40543 8192000000 10140834032 10652684288 1117371376 12.002 97 25 47 2 1223420928 100408224 5641328
13:11:30 45509 8192000000 10115863632 10539757568 1129576432 12.117 98 36 72 0 1185803424 49138160 7088832
13:13:16 95796 8192000000 10121786704 10593603584 1140583968 12.221 100 42 82 0 1247211376 97612320 9015088
13:15:56 76111 8192000000 10049165664 10506457088 1109005616 11.923 98 22 44 0 1132530528 18839232 4685680
13:19:11 30109 8192000000 10061074528 10516000768 1122165856 12.047 99 19 38 0 1170148112 43393376 4588880
13:20:42 87908 8192000000 10070880160 10528243712 1103190032 11.868 98 10 18 0 1141615888 36597456 1828400
13:23:10 42634 8192000000 10063159440 10509832192 1105986848 11.894 98 14 28 0 1145002240 36271968 2743424
13:24:54 35310 8192000000 10104130800 10550878208 1132831920 12.148 100 28 56 0 1180407248 41772384 5802944
13:26:33 26905 8192000000 10094491520 10532548608 1129221872 12.114 99 36 69 1 1186938400 50664144 7052384
13:28:03 51721 8192000000 10089486576 10530701312 1111429968 11.946 98 44 80 1 1182426816 62940560 8056288
13:29:55 37122 8192000000 10089992896 10532253696 1116922032 11.998 98 32 64 0 1190485280 66996224 6567024
13:31:35 20564 8192000000 10140578128 10565885952 1140148320 12.217 100 39 78 0 1231314160 83002160 8163680
13:32:56 68527 8192000000 10139171584 10618232832 1115079392 11.980 98 40 80 1 1242029904 119016320 7934192
13:36:21 14804 8192000000 10067027840 10501337088 1134474048 12.164 100 46 90 3 1162618544 18740160 9404336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-07-2022 13:40:21 to 06-07-2022 13:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-07-2022 13:40:38 to 06-07-2022 13:40:40.

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          208738
MON$OLDEST_ACTIVE               210965
MON$OLDEST_SNAPSHOT             210965
MON$NEXT_TRANSACTION            210965
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                       2056288
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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.07.22 13:40:41. 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-06 13:40:44 No severe PSQL-related problems occured
06.07.22 13:40:45. End of report.