Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.04.2022 07:37
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 16330396 kB
MemAvailable 31501276 kB
Buffers 0 kB
Cached 15339716 kB
SwapCached 2544 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-23 07:37:14
DTS_END                         2022-04-23 07:37:14.6320
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/57896
TRN_ID                          221999
ATT_ID                          593
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: 9198.09 26347 1103771
customer order: creation 170.04 474 20405
customer order: refuse 14.58 403 1750
order to supplier: creation 45.88 1341 5505
order to supplier: removal 3.58 175450 429
invoice (draft): creation 46.55 3181 5586
invoice (draft): removal 52.53 999 6303
invoice accept: apply 41.51 26574 4981
invoice accept: cancel 47.14 1209 5657
customer reserve: creation 3851.23 253 462147
customer reserve: removal 4629.10 107 555492
realization accept: apply 82.79 267 9935
realization accept: cancel 84.06 178 10087
payment from customer: creation 58.99 174 7079
payment from customer: removal 5.83 119 700
payment to supplier: creation 48.15 193 5778
payment to supplier: removal 3.43 114 411
service: total inventory turnovers 3.96 4226 475
service: total monetary turnovers 7.71 374 925
service: aggregate perf. data 1.01 15751 121
service: refresh index statistics 0.04 295558 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-04-2022 07:38:05 to 23-04-2022 07:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8683
TEST_TIME 2 6027
TEST_TIME 3 5676
TEST_TIME 4 6354
TEST_TIME 5 6723
TEST_TIME 6 7109
TEST_TIME 7 7532
TEST_TIME 8 7581
TEST_TIME 9 7414
TEST_TIME 10 7594
TEST_TIME 11 7588
TEST_TIME 12 7589
TEST_TIME 13 7390
TEST_TIME 14 7310
TEST_TIME 15 7643
TEST_TIME 16 7755
TEST_TIME 17 7970
TEST_TIME 18 7909
TEST_TIME 19 7944
TEST_TIME 20 7944
TEST_TIME 21 8126
TEST_TIME 22 7998
TEST_TIME 23 8091
TEST_TIME 24 8169
TEST_TIME 25 8231
TEST_TIME 26 8788
TEST_TIME 27 8706
TEST_TIME 28 8995
TEST_TIME 29 9170
TEST_TIME 30 9426
TEST_TIME 31 9912
TEST_TIME 32 10130
TEST_TIME 33 10590
TEST_TIME 34 11124
TEST_TIME 35 11049
TEST_TIME 36 10850
TEST_TIME 37 10690
TEST_TIME 38 10490
TEST_TIME 39 10418
TEST_TIME 40 10288
TEST_TIME 41 10217
TEST_TIME 42 10179
TEST_TIME 43 10016
TEST_TIME 44 10045
TEST_TIME 45 10057
TEST_TIME 46 10034
TEST_TIME 47 9900
TEST_TIME 48 9906
TEST_TIME 49 9859
TEST_TIME 50 9812
TEST_TIME 51 9788
TEST_TIME 52 9732
TEST_TIME 53 9650
TEST_TIME 54 9700
TEST_TIME 55 9684
TEST_TIME 56 9667
TEST_TIME 57 9693
TEST_TIME 58 9698
TEST_TIME 59 9795
TEST_TIME 60 9883
TEST_TIME 61 9895
TEST_TIME 62 9889
TEST_TIME 63 9760
TEST_TIME 64 9671
TEST_TIME 65 9606
TEST_TIME 66 9506
TEST_TIME 67 9534
TEST_TIME 68 9507
TEST_TIME 69 9431
TEST_TIME 70 9410
TEST_TIME 71 9383
TEST_TIME 72 9371
TEST_TIME 73 9365
TEST_TIME 74 9333
TEST_TIME 75 9352
TEST_TIME 76 9360
TEST_TIME 77 9379
TEST_TIME 78 9353
TEST_TIME 79 9536
TEST_TIME 80 9548
TEST_TIME 81 9583
TEST_TIME 82 9600
TEST_TIME 83 9569
TEST_TIME 84 9561
TEST_TIME 85 9485
TEST_TIME 86 9431
TEST_TIME 87 9422
TEST_TIME 88 9392
TEST_TIME 89 9373
TEST_TIME 90 9339
TEST_TIME 91 9309
TEST_TIME 92 9235
TEST_TIME 93 9242
TEST_TIME 94 9210
TEST_TIME 95 9178
TEST_TIME 96 9177
TEST_TIME 97 9155
TEST_TIME 98 9147
TEST_TIME 99 9141
TEST_TIME 100 9148
TEST_TIME 101 9116
TEST_TIME 102 9098
TEST_TIME 103 9114
TEST_TIME 104 9171
TEST_TIME 105 9193
TEST_TIME 106 9330
TEST_TIME 107 9343
TEST_TIME 108 9390
TEST_TIME 109 9328
TEST_TIME 110 9311
TEST_TIME 111 9298
TEST_TIME 112 9296
TEST_TIME 113 9295
TEST_TIME 114 9263
TEST_TIME 115 9271
TEST_TIME 116 9250
TEST_TIME 117 9223
TEST_TIME 118 9201
TEST_TIME 119 9247
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-04-2022 07:38:09 to 23-04-2022 07:38:12.

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 1530586 1530586 0 0.00 0 30648 14 0 0
doc_list_biud 1530577 1530577 0 0.00 0 39873 44 0 0
sp_add_invoice_to_stock 5088 4981 107 2.10 68 233160 26574 0 107
sp_cancel_adding_invoice 5657 5657 0 0.00 6 83872 1210 0 0
sp_cancel_client_order 1750 1750 0 0.00 6 10582 403 0 0
sp_cancel_customer_reserve 555492 555492 0 0.00 1 39876 107 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 2856 119 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 1245 114 0 0
sp_cancel_supplier_invoice 6313 6303 10 0.15 4 28892 1000 0 10
sp_cancel_supplier_order 429 429 0 0.00 11 1335177 175451 0 0
sp_cancel_write_off 10087 10087 0 0.00 2 10895 178 0 0
sp_client_order 20405 20405 0 0.00 6 38231 474 0 0
sp_customer_reserve 462147 462147 0 0.00 1 230163 253 0 0
sp_fill_shopping_cart 31479 31479 0 0.00 1 19799 85 0 0
sp_fill_shopping_cart_clo_res 462115 462115 0 0.00 0 14730 20 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 31057 291 0 0
sp_kill_cost_storno 16855 16855 0 0.00 0 21603 73 0 0
sp_kill_qstorno_ret_qs2qd 1127803 1127803 0 0.00 0 39871 47 0 0
sp_kill_qty_storno 594596 594596 0 0.00 0 39873 106 0 0
sp_lock_selected_doc 20641 20641 0 0.00 0 7735 7 0 0
sp_make_cost_storno 27737 27737 0 0.00 0 13544 79 0 0
sp_multiply_rows_for_pdistr 27734 27734 0 0.00 0 10381 37 0 0
sp_multiply_rows_for_qdistr 20403 20403 0 0.00 0 38148 186 0 0
sp_pay_from_customer 7079 7079 0 0.00 5 10421 174 0 0
sp_pay_to_supplier 5778 5778 0 0.00 6 14363 194 0 0
sp_payment_common 12855 12855 0 0.00 2 9011 44 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 10542 330 0 0
sp_qd_handle_on_invoice_upd_sts 10601 10601 0 0.00 0 30180 511 0 0
sp_qd_handle_on_reserve_upd_sts 9934 9934 0 0.00 0 18283 60 0 0
sp_reserve_write_off 9935 9935 0 0.00 8 18662 267 0 0
sp_supplier_invoice 5586 5586 0 0.00 10 101472 3182 0 0
sp_supplier_order 5505 5505 0 0.00 8 26945 1342 0 0
srv_aggregate_perf_data 121 121 0 0.00 2380 137774 15751 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 783 217784 35523 0 0
srv_find_qd_qs_mism 1055620 1055620 0 0.00 0 30639 11 0 0
srv_make_invnt_saldo 475 475 0 0.00 65 76399 4226 0 0
srv_make_money_saldo 925 925 0 0.00 1 18977 374 0 0
srv_recalc_idx_stat 5 5 0 0.00 87993 428674 295558 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4986 4986 0 0.00 1 13853 931 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 2 15010 469 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 1651 28 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 6137 47 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1664 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 399 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1585 30 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 111218 6661 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 5218 30 0 0
t$perf-norm:sp_client_order 20406 20406 0 0.00 0 6300 35 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 619 13 0 0
t$perf-norm:sp_pay_from_customer 7080 7080 0 0.00 0 6301 20 0 0
t$perf-norm:sp_pay_to_supplier 5778 5778 0 0.00 0 3983 22 0 0
t$perf-norm:sp_reserve_write_off 9935 9935 0 0.00 0 6887 24 0 0
t$perf-norm:sp_supplier_invoice 5586 5586 0 0.00 0 12620 32 0 0
t$perf-norm:sp_supplier_order 5505 5505 0 0.00 0 6203 31 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 1032 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 758 16 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 478 10 0 0
t$perf-norm:srv_make_money_saldo 925 925 0 0.00 0 454 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 154 48 0 0
v_add_invoice_to_stock 10094 9880 214 2.12 0 6526 26 0 214
v_all_customers 37108 37108 0 0.00 0 216 4 0 0
v_all_suppliers 30734 30734 0 0.00 0 163 4 0 0
v_all_wares 40793 40793 0 0.00 0 116 4 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1034 14 0 0
v_cancel_client_order 3500 3500 0 0.00 0 1406 9 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 520 10 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 6010 16 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 1205 14 0 0
v_cancel_supplier_invoice 842 822 20 2.37 0 4047 31 0 20
v_cancel_supplier_order 858 858 0 0.00 0 1195 11 0 0
v_cancel_write_off 1950 1950 0 0.00 0 4022 26 0 0
v_max_id_clo_ord 5503 5503 0 0.00 0 120 5 0 0
v_max_id_clo_res 973 973 0 0.00 0 2151 13 0 0
v_max_id_ord_sup 5575 5575 0 0.00 0 2599 7 0 0
v_max_non_paid_invoice 1488 1488 0 0.00 0 61 7 0 0
v_max_non_paid_realizn 3796 3796 0 0.00 0 5344 10 0 0
v_min_id_clo_ord 5503 5503 0 0.00 0 1871 8 0 0
v_min_id_clo_res 973 973 0 0.00 0 5145 19 0 0
v_min_id_ord_sup 5575 5575 0 0.00 0 7285 13 0 0
v_min_non_paid_invoice 5777 5777 0 0.00 0 4265 11 0 0
v_min_non_paid_realizn 7078 7078 0 0.00 0 4446 15 0 0
v_our_firm 10248 10248 0 0.00 0 59 5 0 0
v_reserve_write_off 19868 19868 0 0.00 0 6023 10 0 0
x_lock_depdocs_on_canc_invoice 11549 11549 0 0.00 0 37191 151 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 2878 334 0 0
x_make_qstorno_1000_1200 5505 5505 0 0.00 2 26929 1172 0 0
x_make_qstorno_1200_2000 5586 5586 0 0.00 5 101290 2953 0 0
x_make_qstorno_2100_3300 462145 462145 0 0.00 0 230160 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 07:38:13 to 23-04-2022 07:38:17.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:18 23863 8192000000 9731387072 10033106944 805688816 8.954 100 84 166 1 833711824 7579600 20443408
05:09:46 24498 8192000000 9773831680 10087612416 848732720 9.387 100 84 168 1 881972960 12894032 20346208
05:11:22 16685 8192000000 9831151072 10151444480 879562464 9.695 100 91 182 1 929269264 28604384 21102416
05:12:42 7663 8192000000 9877311936 10215092224 926021520 10.155 100 83 161 1 987821376 43704016 18095840
05:13:52 4265 8192000000 9932547312 10276102144 962271408 10.511 100 87 172 0 1044611872 64474256 17866208
05:15:00 2107 8192000000 9973591104 10329202688 995544784 10.835 100 92 176 2 1091386368 77745456 18096128
05:16:05 1124 8192000000 9989279888 10349424640 1019405952 11.066 100 93 184 0 1103475648 65110224 18959472
05:17:08 1212 8192000000 10015817472 10385584128 1036495488 11.231 100 95 182 1 1135606912 80812624 18298800
05:18:11 1068 8192000000 10033001696 10403467264 1056673008 11.425 100 98 193 0 1145783984 69322032 19788944
05:19:13 1297 8192000000 10045008160 10421719040 1064531696 11.500 100 96 188 0 1169687184 85965760 19189728
05:20:15 1137 8192000000 10039025936 10419068928 1069166624 11.544 100 87 165 1 1159058416 73206432 16685360
05:21:17 5614 8192000000 10022052592 10421981184 1070874096 11.560 100 60 112 2 1147755072 64139952 12741024
05:22:23 1076 8192000000 10032620144 10420973568 1075863776 11.608 100 75 148 0 1153475376 62526192 15085408
05:23:26 960 8192000000 10050777984 10443554816 1080612848 11.653 100 87 162 1 1171870128 74485344 16771936
05:24:28 1020 8192000000 10039555328 10428944384 1087574944 11.720 100 77 150 2 1162378448 58515152 16288352
05:25:32 1146 8192000000 10056531808 10445750272 1085064384 11.696 100 94 176 3 1179428288 76038832 18325072
05:26:33 2878 8192000000 10036018320 10423820288 1084475632 11.690 100 70 131 1 1157478528 59592096 13410800
05:27:38 1103 8192000000 10040204256 10428010496 1080644880 11.654 99 94 182 1 1162500880 64133488 17722512
05:28:40 2230 8192000000 10037273568 10427047936 1089154720 11.735 100 91 178 1 1162632816 54581920 18896176
05:29:43 1669 8192000000 10034520128 10424664064 1065984592 11.514 99 72 145 0 1154263008 73727328 14551088
05:30:48 1328 8192000000 10027677312 10409988096 1057878016 11.436 100 92 174 0 1150407184 73936848 18592320
05:31:50 1096 8192000000 9992112448 10366787584 1048837392 11.350 100 92 174 1 1112785696 44807120 19141184
05:32:52 734 8192000000 10027999344 10409902080 1059895952 11.455 100 88 165 0 1148127856 69922112 18309792
05:33:55 1338 8192000000 9998595776 10375045120 1030804752 11.176 99 87 168 0 1119214128 71659552 16749824
05:34:57 836 8192000000 9982062976 10357432320 1033892208 11.206 100 90 164 4 1109345264 56535920 18917136
05:35:58 3723 8192000000 9997790096 10377105408 1034078288 11.208 100 65 130 0 1115554128 68663200 12812640
05:37:03 10134 8192000000 9974820416 10349780992 1022105952 11.092 98 66 114 2 1088655984 54895568 11654464
05:38:20 5894 8192000000 10003845296 10378665984 1060330304 11.460 100 79 144 1 1122910112 48972608 13607200
05:39:32 14322 8192000000 10067072512 10452172800 1081983056 11.666 100 71 139 0 1184083760 87824112 14276592
05:40:54 2086 8192000000 10086568928 10470010880 1087938064 11.723 100 77 142 3 1206138672 102895392 15305216
05:42:01 1055 8192000000 10128226848 10511204352 1103184240 11.868 100 96 192 3 1243448704 120936592 19327872
05:43:06 1097 8192000000 10137326080 10532286464 1114612464 11.976 100 84 155 1 1255536016 124324880 16598672
05:44:10 1126 8192000000 10145325120 10547523584 1124927072 12.074 100 85 161 0 1257496992 115893904 16676016
05:45:15 862 8192000000 10154880048 10553647104 1138675376 12.203 100 93 173 0 1270997504 112319168 20002960
05:46:20 1376 8192000000 10188663136 10584137728 1153410800 12.342 100 90 175 1 1304209968 130096512 20702656
05:47:24 2044 8192000000 10215502608 10610380800 1166193952 12.461 100 90 171 2 1323882736 139822688 17866096
05:48:30 894 8192000000 10252144752 10649743360 1171411728 12.510 100 90 177 1 1366042160 172852032 21778400
05:49:34 2273 8192000000 10285427856 10693632000 1170490288 12.501 100 82 163 1 1399765504 210698400 18576816
05:50:41 5186 8192000000 10306545984 10710573056 1171126640 12.507 100 73 140 1 1402751040 215820736 15803664
05:51:49 1454 8192000000 10321339664 10743799808 1182620944 12.615 100 91 172 0 1428432896 224227344 21584608
05:52:55 933 8192000000 10348696976 10759606272 1190520128 12.688 100 98 192 1 1456140496 243939200 21681168
05:53:59 904 8192000000 10377398880 10798219264 1190292320 12.686 100 96 190 0 1486789568 275551360 20945888
05:55:01 819 8192000000 10399656832 10806476800 1190781520 12.691 100 94 182 0 1503260752 291447680 21031552
05:56:08 1304 8192000000 10435742608 10852003840 1198600656 12.763 100 88 172 1 1542882816 321949344 22332816
05:57:16 1594 8192000000 10463668336 10887819264 1204272640 12.816 100 87 173 0 1567062288 341870208 20919440
05:58:21 1059 8192000000 10466371840 10903805952 1200784480 12.784 100 92 179 1 1572848160 350466016 21597664
05:59:24 992 8192000000 10540030000 10946392064 1209223728 12.862 100 98 188 2 1644425040 411466576 23734736
06:00:28 1194 8192000000 10588174752 11017674752 1211224368 12.880 100 87 169 0 1693494032 460977680 21291984
06:01:35 773 8192000000 10569260064 11029762048 1208281680 12.853 100 90 171 2 1673772384 443754480 21736224
06:02:44 2738 8192000000 10583519840 11093655552 1211752288 12.885 100 80 159 1 1686350032 453298000 21299744
06:03:52 5210 8192000000 10553635968 11010461696 1209310896 12.863 100 60 116 1 1663122976 439229888 14582192
06:05:13 6358 8192000000 10558034944 11011993600 1187897216 12.664 100 47 94 1 1661634496 460907296 12829984
06:06:24 17507 8192000000 10475169056 10939043840 1152947952 12.337 99 34 68 0 1567850528 405550944 9351632
06:07:43 17662 8192000000 10408338224 10965393408 1162217024 12.424 100 30 59 1 1523689408 354159440 7312944
06:09:18 24329 8192000000 10304322896 10764861440 1137663840 12.194 99 22 44 0 1415419984 272370720 5385424
06:10:44 78464 8192000000 10055947296 10577698816 1092041376 11.762 95 11 22 1 1200812288 106310560 2460352
06:13:03 19242 8192000000 10052388256 10477277184 1108233488 11.916 97 47 91 2 1133150816 14659632 10257696
06:14:41 111180 8192000000 10078609360 10528202752 1099100656 11.829 97 36 70 0 1163552768 57416048 7036064
06:17:33 16976 8192000000 10087233344 10515488768 1127634336 12.099 100 49 95 1 1180880720 44352256 8894128
06:18:57 40865 8192000000 10107437104 10565115904 1139584624 12.212 100 48 96 0 1228968800 79240720 10143456
06:20:39 93116 8192000000 10127520848 10643701760 1111800544 11.949 97 40 79 2 1220758608 99918304 9039760
06:23:30 53382 8192000000 10093076960 10571001856 1103216080 11.868 97 11 22 0 1183515488 77669232 2630176
06:26:03 217784 8192000000 10073560880 10533568512 1067319520 11.526 94 13 26 0 1105432880 35618592 2494768
06:30:42 17719 8192000000 10080490800 10529832960 1126739904 12.091 100 49 94 1 1191833248 54388704 10704640
06:32:07 31536 8192000000 10090776432 10551005184 1116923680 11.998 100 27 54 1 1205638176 82970896 5743600
06:33:41 34623 8192000000 10144599088 10582568960 1126491056 12.088 100 37 72 1 1241468368 107373216 7604096
06:35:16 70653 8192000000 10127949440 10578235392 1096940896 11.809 97 23 47 0 1193896080 91738000 5217184
06:37:38 138547 8192000000 10054140768 10518450176 1083231200 11.678 96 16 32 4 1128981488 42765584 2984704
06:41:05 85598 8192000000 10093719424 10549026816 1122407728 12.050 99 27 54 0 1172877104 44661808 5807568
06:44:59 62028 8192000000 10055542272 10524225536 1107591504 11.910 97 16 32 0 1154601712 44018544 2991664
06:47:03 52125 8192000000 10071808128 10548273152 1134733184 12.166 100 10 20 0 1186584208 49763744 2087280
06:48:56 10970 8192000000 10071760032 10508218368 1124150080 12.066 99 44 75 5 1174088880 41881008 8057792
06:50:12 33866 8192000000 10089668176 10535936000 1115380176 11.983 98 35 70 0 1162396192 39585376 7430640
06:51:54 32994 8192000000 10132626576 10569232384 1117334976 12.002 98 33 66 0 1219922752 94781808 7805968
06:53:37 17452 8192000000 10187116000 10643816448 1150721968 12.316 100 45 90 3 1284007888 123486416 9799504
06:54:58 48889 8192000000 10167808576 10603749376 1104991664 11.885 96 35 70 1 1229554896 117071856 7491376
06:56:48 63826 8192000000 10115657408 10576457728 1131139360 12.132 99 31 60 1 1227867056 90239472 6488224
06:58:52 58653 8192000000 10079494512 10547728384 1121716256 12.043 98 16 32 1 1161508464 36743232 3048976
07:01:09 25313 8192000000 10117689952 10547650560 1131880832 12.139 99 41 82 0 1190713008 51122320 7709856
07:02:35 65246 8192000000 10099804816 10538835968 1122920304 12.055 98 67 127 2 1197914848 60959840 14034704
07:04:48 25164 8192000000 10092842688 10555281408 1142336720 12.238 100 34 68 0 1197765520 48895728 6533072
07:06:15 33057 8192000000 10075939216 10524155904 1141578608 12.230 100 33 66 0 1200811344 52473920 6758816
07:07:52 52938 8192000000 10074813360 10526736384 1128085232 12.103 99 11 21 1 1186539120 56432992 2020896
07:11:55 38770 8192000000 10077420544 10520543232 1129341824 12.115 99 29 54 2 1185445680 49901616 6202240
07:13:36 36321 8192000000 10111760592 10546315264 1114194208 11.972 97 27 53 2 1187260816 66785536 6281072
07:15:14 35370 8192000000 10152550656 10587037696 1136707504 12.185 99 42 84 3 1249443712 103014176 9722032
07:17:01 17461 8192000000 10224009488 10652229632 1151772160 12.326 100 46 92 1 1313024176 151008528 10243488
07:18:24 20771 8192000000 10228026016 10658045952 1137326912 12.190 98 48 96 0 1300376800 152243056 10806832
07:19:45 15162 8192000000 10256877664 10680553472 1147028384 12.282 99 52 102 1 1335574800 176758272 11788144
07:21:05 57533 8192000000 10217517344 10664759296 1120661824 12.033 97 39 77 3 1319931968 189802848 9467296
07:23:02 63678 8192000000 10107117200 10626486272 1083055552 11.677 96 14 27 1 1187811760 101395840 3360368
07:25:40 122406 8192000000 10067555520 10509570048 1120489296 12.032 99 12 24 2 1166984160 44004944 2489920
07:28:54 128845 8192000000 10058093920 10533339136 1084926128 11.694 95 13 26 1 1141186400 53594240 2666032
07:32:04 86850 8192000000 10068844736 10529792000 1140504896 12.220 100 17 35 3 1181793280 37534336 3754048
07:34:31 40636 8192000000 10108298064 10544902144 1123561024 12.061 98 35 68 0 1177236816 46656448 7019344
07:36:20 71578 8192000000 9040009968 9279098880 194472784 2.318 18 1 2 0 201506176 6997984 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 07:38:36 to 23-04-2022 07:38:38.

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          222335
MON$OLDEST_ACTIVE               222336
MON$OLDEST_SNAPSHOT             222336
MON$NEXT_TRANSACTION            222336
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                       2063328
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

23.04.22 07:38:39. 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-23 07:38:42 No severe PSQL-related problems occured
23.04.22 07:38:43. End of report.