Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.05.2022 07: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 16350088 kB
MemAvailable 31506248 kB
Buffers 0 kB
Cached 15388004 kB
SwapCached 2604 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-05-11 07:37:12
DTS_END                         2022-05-11 07:37:12.3240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47848
TRN_ID                          218178
ATT_ID                          583
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: 8934.77 25135 1072172
customer order: creation 165.71 490 19885
customer order: refuse 14.75 415 1770
order to supplier: creation 44.30 1393 5316
order to supplier: removal 3.46 152111 415
invoice (draft): creation 45.06 3133 5407
invoice (draft): removal 50.15 947 6018
invoice accept: apply 41.26 26554 4951
invoice accept: cancel 45.50 1188 5460
customer reserve: creation 3767.46 258 452095
customer reserve: removal 4466.13 92 535936
realization accept: apply 80.76 294 9691
realization accept: cancel 83.26 160 9991
payment from customer: creation 57.69 211 6923
payment from customer: removal 5.33 129 640
payment to supplier: creation 48.34 219 5801
payment to supplier: removal 3.53 129 423
service: total inventory turnovers 3.69 3995 443
service: total monetary turnovers 7.46 355 895
service: aggregate perf. data 0.88 16714 106
service: refresh index statistics 0.05 293910 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-05-2022 07:39:05 to 11-05-2022 07:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7747
TEST_TIME 2 6403
TEST_TIME 3 5874
TEST_TIME 4 5252
TEST_TIME 5 5057
TEST_TIME 6 5135
TEST_TIME 7 5347
TEST_TIME 8 6310
TEST_TIME 9 6385
TEST_TIME 10 6268
TEST_TIME 11 6644
TEST_TIME 12 6902
TEST_TIME 13 7031
TEST_TIME 14 7075
TEST_TIME 15 7076
TEST_TIME 16 7291
TEST_TIME 17 7613
TEST_TIME 18 7747
TEST_TIME 19 7965
TEST_TIME 20 8098
TEST_TIME 21 8126
TEST_TIME 22 8253
TEST_TIME 23 8509
TEST_TIME 24 8515
TEST_TIME 25 8623
TEST_TIME 26 8826
TEST_TIME 27 9085
TEST_TIME 28 9056
TEST_TIME 29 9495
TEST_TIME 30 9942
TEST_TIME 31 10701
TEST_TIME 32 11122
TEST_TIME 33 11018
TEST_TIME 34 11087
TEST_TIME 35 11032
TEST_TIME 36 11214
TEST_TIME 37 11024
TEST_TIME 38 10854
TEST_TIME 39 10592
TEST_TIME 40 10512
TEST_TIME 41 10394
TEST_TIME 42 10461
TEST_TIME 43 10302
TEST_TIME 44 10273
TEST_TIME 45 10196
TEST_TIME 46 10101
TEST_TIME 47 9967
TEST_TIME 48 9967
TEST_TIME 49 9910
TEST_TIME 50 9954
TEST_TIME 51 9836
TEST_TIME 52 9925
TEST_TIME 53 9851
TEST_TIME 54 9743
TEST_TIME 55 9716
TEST_TIME 56 9626
TEST_TIME 57 9577
TEST_TIME 58 9495
TEST_TIME 59 9670
TEST_TIME 60 9615
TEST_TIME 61 9699
TEST_TIME 62 9710
TEST_TIME 63 9691
TEST_TIME 64 9653
TEST_TIME 65 9707
TEST_TIME 66 9728
TEST_TIME 67 9866
TEST_TIME 68 9811
TEST_TIME 69 9715
TEST_TIME 70 9595
TEST_TIME 71 9733
TEST_TIME 72 9618
TEST_TIME 73 9587
TEST_TIME 74 9567
TEST_TIME 75 9528
TEST_TIME 76 9507
TEST_TIME 77 9466
TEST_TIME 78 9424
TEST_TIME 79 9442
TEST_TIME 80 9457
TEST_TIME 81 9517
TEST_TIME 82 9541
TEST_TIME 83 9538
TEST_TIME 84 9564
TEST_TIME 85 9599
TEST_TIME 86 9526
TEST_TIME 87 9560
TEST_TIME 88 9491
TEST_TIME 89 9452
TEST_TIME 90 9427
TEST_TIME 91 9422
TEST_TIME 92 9396
TEST_TIME 93 9356
TEST_TIME 94 9315
TEST_TIME 95 9281
TEST_TIME 96 9270
TEST_TIME 97 9240
TEST_TIME 98 9231
TEST_TIME 99 9210
TEST_TIME 100 9243
TEST_TIME 101 9217
TEST_TIME 102 9205
TEST_TIME 103 9234
TEST_TIME 104 9190
TEST_TIME 105 9219
TEST_TIME 106 9321
TEST_TIME 107 9325
TEST_TIME 108 9307
TEST_TIME 109 9301
TEST_TIME 110 9259
TEST_TIME 111 9288
TEST_TIME 112 9284
TEST_TIME 113 9245
TEST_TIME 114 9194
TEST_TIME 115 9167
TEST_TIME 116 9111
TEST_TIME 117 9068
TEST_TIME 118 9014
TEST_TIME 119 9036
TEST_TIME 120 8966

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-05-2022 07:39:09 to 11-05-2022 07:39: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 1489268 1489268 0 0.00 0 32712 13 0 0
doc_list_biud 1489259 1489259 0 0.00 0 39108 39 0 0
sp_add_invoice_to_stock 5074 4951 123 2.42 20 226017 26555 0 123
sp_cancel_adding_invoice 5460 5460 0 0.00 7 79787 1189 0 0
sp_cancel_client_order 1770 1770 0 0.00 5 19782 416 0 0
sp_cancel_customer_reserve 535936 535936 0 0.00 1 39170 93 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 4 7229 130 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 3684 130 0 0
sp_cancel_supplier_invoice 6028 6018 10 0.16 3 14656 947 0 10
sp_cancel_supplier_order 415 415 0 0.00 18 1519776 152111 0 0
sp_cancel_write_off 9991 9991 0 0.00 2 8251 161 0 0
sp_client_order 19885 19885 0 0.00 5 33082 491 0 0
sp_customer_reserve 452095 452095 0 0.00 1 80460 259 0 0
sp_fill_shopping_cart 30604 30604 0 0.00 1 10471 82 0 0
sp_fill_shopping_cart_clo_res 452067 452067 0 0.00 0 16843 19 0 0
sp_get_clo_for_invoice 4918 4918 0 0.00 0 20457 265 0 0
sp_kill_cost_storno 16514 16514 0 0.00 0 8182 69 0 0
sp_kill_qstorno_ret_qs2qd 1088296 1088296 0 0.00 0 39005 40 0 0
sp_kill_qty_storno 574197 574197 0 0.00 0 39102 93 0 0
sp_lock_selected_doc 20193 20193 0 0.00 0 7788 7 0 0
sp_make_cost_storno 27331 27331 0 0.00 0 17820 74 0 0
sp_multiply_rows_for_pdistr 27330 27330 0 0.00 0 7884 37 0 0
sp_multiply_rows_for_qdistr 19885 19885 0 0.00 0 22278 181 0 0
sp_pay_from_customer 6923 6923 0 0.00 5 47344 211 0 0
sp_pay_to_supplier 5801 5801 0 0.00 7 30932 220 0 0
sp_payment_common 12721 12721 0 0.00 2 23083 46 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 19774 336 0 0
sp_qd_handle_on_invoice_upd_sts 10377 10377 0 0.00 0 31904 499 0 0
sp_qd_handle_on_reserve_upd_sts 9690 9690 0 0.00 0 20922 67 0 0
sp_reserve_write_off 9691 9691 0 0.00 9 25251 294 0 0
sp_supplier_invoice 5407 5407 0 0.00 9 97508 3133 0 0
sp_supplier_order 5316 5316 0 0.00 8 42543 1394 0 0
srv_aggregate_perf_data 106 106 0 0.00 2531 141932 16715 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 725 169176 35845 0 0
srv_find_qd_qs_mism 1024842 1024842 0 0.00 0 32712 11 0 0
srv_make_invnt_saldo 443 443 0 0.00 99 80704 3996 0 0
srv_make_money_saldo 895 895 0 0.00 0 26153 356 0 0
srv_recalc_idx_stat 6 6 0 0.00 130517 447699 293911 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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 4956 4956 0 0.00 1 12164 927 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 8631 505 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 1503 28 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 1084 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 614 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 816 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1044 37 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 1 102385 6076 0 0
t$perf-norm:sp_cancel_write_off 888 888 0 0.00 0 13936 40 0 0
t$perf-norm:sp_client_order 19885 19885 0 0.00 0 5731 33 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 1848 19 0 0
t$perf-norm:sp_pay_from_customer 6923 6923 0 0.00 0 4800 19 0 0
t$perf-norm:sp_pay_to_supplier 5801 5801 0 0.00 0 3371 19 0 0
t$perf-norm:sp_reserve_write_off 9691 9691 0 0.00 0 5441 23 0 0
t$perf-norm:sp_supplier_invoice 5407 5407 0 0.00 0 4906 30 0 0
t$perf-norm:sp_supplier_order 5316 5316 0 0.00 0 3643 32 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1403 44 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1454 26 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 904 12 0 0
t$perf-norm:srv_make_money_saldo 895 895 0 0.00 0 303 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 139 27 0 0
v_add_invoice_to_stock 10076 9830 246 2.44 0 14394 31 0 246
v_all_customers 36774 36774 0 0.00 0 67 4 0 0
v_all_suppliers 29928 29928 0 0.00 0 63 4 0 0
v_all_wares 39766 39766 0 0.00 0 267 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 890 15 0 0
v_cancel_client_order 3540 3540 0 0.00 0 5803 13 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 2355 12 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 2730 15 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 3575 16 0 0
v_cancel_supplier_invoice 770 750 20 2.59 0 6773 36 0 20
v_cancel_supplier_order 830 830 0 0.00 0 4705 18 0 0
v_cancel_write_off 1776 1776 0 0.00 0 6227 29 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 9645 7 0 0
v_max_id_clo_res 964 964 0 0.00 0 1898 18 0 0
v_max_id_ord_sup 5404 5404 0 0.00 0 7601 9 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 987 8 0 0
v_max_non_paid_realizn 3562 3562 0 0.00 0 900 7 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 3642 9 0 0
v_min_id_clo_res 964 964 0 0.00 0 1020 9 0 0
v_min_id_ord_sup 5404 5404 0 0.00 0 5671 10 0 0
v_min_non_paid_invoice 5799 5799 0 0.00 0 4369 13 0 0
v_min_non_paid_realizn 6921 6921 0 0.00 0 23013 21 0 0
v_our_firm 9702 9702 0 0.00 0 77 4 0 0
v_reserve_write_off 19377 19377 0 0.00 0 25154 20 0 0
x_lock_depdocs_on_canc_invoice 11103 11103 0 0.00 0 16051 119 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 5402 298 0 0
x_make_qstorno_1000_1200 5316 5316 0 0.00 2 42424 1217 0 0
x_make_qstorno_1200_2000 5407 5407 0 0.00 4 97478 2925 0 0
x_make_qstorno_2100_3300 452093 452093 0 0.00 0 80458 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-05-2022 07:39:13 to 11-05-2022 07:39: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:16 36 8192000000 8809037888 8824090624 8924928 0.108 4 2 2 0 9030032 69328 35776
05:08:17 24613 8192000000 9717366592 10020200448 798348256 8.880 100 85 170 0 827321264 7677168 21295840
05:09:45 30213 8192000000 9783298096 10099593216 856567024 9.466 100 86 173 0 888368912 11205664 20596224
05:11:25 14034 8192000000 9813648432 10140143616 888380736 9.783 100 82 162 1 926668768 19876000 18412032
05:12:42 10579 8192000000 9882115296 10221510656 929886720 10.194 100 91 175 0 999168320 50477712 18803888
05:14:02 5539 8192000000 9928421680 10277376000 971206608 10.598 100 76 150 0 1037187936 50556624 15424704
05:15:11 1917 8192000000 9978365056 10337558528 1009884048 10.974 100 93 184 1 1099574464 70160144 19530272
05:16:16 1271 8192000000 10002834736 10369466368 1023506352 11.106 100 95 182 2 1124486592 81541488 19438752
05:17:19 876 8192000000 10032917344 10402062336 1043819744 11.301 100 92 181 1 1152227552 90945760 17462048
05:18:21 1001 8192000000 10040024960 10415509504 1055108512 11.410 100 97 188 0 1152057456 77083360 19865584
05:19:24 1003 8192000000 10041716128 10417528832 1063353760 11.489 100 98 191 2 1161430496 79349056 18727680
05:20:26 876 8192000000 10041179984 10426580992 1069258176 11.545 100 84 161 3 1161343408 75537312 16547920
05:21:27 1339 8192000000 10031519792 10417438720 1074698768 11.597 100 82 160 0 1154356800 63545408 16112624
05:22:30 1046 8192000000 10068101648 10451566592 1082194432 11.668 100 93 180 0 1189539264 89228560 18116272
05:23:33 1200 8192000000 10050845584 10440790016 1088266896 11.726 100 88 171 0 1171349840 66273280 16809664
05:24:35 962 8192000000 10065555040 10456285184 1091685744 11.759 100 92 175 0 1187715296 78235808 17793744
05:25:37 1080 8192000000 10076254576 10467237888 1093740192 11.778 100 90 173 1 1196549024 85435136 17373696
05:26:38 1859 8192000000 10064335296 10463604736 1099010192 11.828 100 85 163 1 1186930464 69475184 18445088
05:27:43 1243 8192000000 10052467568 10435977216 1083866224 11.684 100 98 181 0 1175388768 72989392 18533152
05:28:44 985 8192000000 10073304832 10462429184 1086746512 11.712 100 93 177 2 1189983312 84927776 18309024
05:29:47 1461 8192000000 10031628192 10419924992 1082117904 11.668 100 92 173 3 1153270592 52640560 18512128
05:30:50 1515 8192000000 10034595552 10422996992 1084189488 11.687 100 83 158 1 1158533024 57990864 16352672
05:31:52 1151 8192000000 10032381776 10411692032 1062011232 11.476 100 92 175 0 1153978416 74222880 17744304
05:32:54 1152 8192000000 10019076560 10398023680 1064046736 11.495 100 97 193 0 1138311536 54326624 19938176
05:33:56 1164 8192000000 9986520576 10365857792 1048476336 11.346 100 82 154 3 1105288864 39607904 17204624
05:34:58 1025 8192000000 9968656576 10345652224 1013590768 11.010 99 78 148 1 1092317424 63088288 15638368
05:36:00 2650 8192000000 9955072944 10330800128 1028648672 11.155 99 69 127 4 1079033104 36169328 14215104
05:37:04 10175 8192000000 10022623872 10407510016 1051249200 11.373 100 56 106 3 1143928160 82478832 10200128
05:38:21 712 8192000000 10007782176 10384789504 1065233552 11.507 100 90 172 1 1127611728 44439792 17938384
05:39:29 12115 8192000000 10045742592 10434121728 1079835840 11.646 100 65 121 2 1160997984 68617504 12544640
05:40:46 9387 8192000000 10052457072 10444247040 1088272320 11.726 100 61 114 1 1176283360 77790864 10220176
05:42:02 5711 8192000000 10096410960 10487255040 1111093152 11.943 100 90 174 0 1215610128 84721392 19795584
05:43:12 4704 8192000000 10138565856 10534326272 1126162192 12.085 100 78 150 1 1261630704 118653024 16815488
05:44:26 1479 8192000000 10172793824 10577293312 1139617632 12.212 100 84 164 0 1292322624 133661952 19043040
05:45:30 903 8192000000 10202731232 10609025024 1142229856 12.237 100 97 181 1 1322028768 158772016 21026896
05:46:33 983 8192000000 10231090096 10626727936 1158262592 12.387 100 95 184 1 1344034736 165745616 20026528
05:47:37 3481 8192000000 10244647168 10653650944 1165969728 12.459 100 74 142 0 1358647104 177035696 15641680
05:48:43 908 8192000000 10284865392 10684940288 1166370880 12.463 100 96 187 1 1400517808 214122768 20024160
05:49:46 1180 8192000000 10301170928 10703998976 1168302832 12.481 100 96 182 2 1413167680 224668864 20195984
05:50:50 1127 8192000000 10333376608 10735910912 1175465440 12.548 100 87 168 1 1443644544 250350368 17828736
05:51:56 2400 8192000000 10372936368 10774827008 1186593696 12.652 100 91 172 0 1474206496 267950112 19662688
05:53:03 1144 8192000000 10385695488 10826633216 1178316256 12.574 99 93 185 0 1493359664 292097984 22945424
05:54:09 1144 8192000000 10396227456 10817474560 1188874368 12.673 100 90 174 2 1503975680 294309056 20792256
05:55:13 2591 8192000000 10434352960 10843209728 1181205952 12.601 98 94 184 1 1537685120 335224640 21254528
05:56:17 844 8192000000 10492242640 10915840000 1200844000 12.784 100 99 190 2 1601426400 378280128 22302272
05:57:20 2295 8192000000 10485548720 10913255424 1178963760 12.581 100 84 167 3 1590387008 392424960 18998288
05:58:25 1238 8192000000 10506983696 10944548864 1180703168 12.597 100 82 157 1 1613256416 412855584 19697664
05:59:32 1184 8192000000 10511229296 10939252736 1196761680 12.746 100 96 189 1 1621959920 401986288 23211952
06:00:36 1350 8192000000 10545104096 10981879808 1188091808 12.666 99 82 160 0 1647200864 438927808 20181248
06:01:41 3063 8192000000 10524788768 10963628032 1178647904 12.578 100 65 125 1 1629644880 435322832 15674144
06:02:56 9480 8192000000 10511369792 11010187264 1167335648 12.472 100 50 100 1 1644094672 463099168 13659856
06:04:12 4978 8192000000 10530356544 10969612288 1167657792 12.475 100 53 106 0 1634555568 453003888 13893888
06:05:18 20430 8192000000 10495842704 10952519680 1158710512 12.391 100 34 68 0 1593108112 425613536 8784064
06:06:57 29665 8192000000 10358412864 10894139392 1152430752 12.332 100 32 64 0 1516594272 356281904 7881616
06:08:39 74726 8192000000 10065678112 10513252352 1125178736 12.076 99 10 20 0 1190204880 63426048 1600096
06:10:54 31943 8192000000 10114271424 10556141568 1129407040 12.116 99 32 62 1 1187997872 51838416 6752416
06:12:26 85062 8192000000 10061373280 10547372032 1123560400 12.061 98 14 28 0 1171557424 44626608 3370416
06:14:52 129067 8192000000 10116767440 10552324096 1131629712 12.137 99 32 64 1 1199108496 60847152 6631632
06:18:06 169174 8192000000 10100482384 10536153088 1096834256 11.808 96 32 65 1 1188377616 85022848 6520512
06:21:56 54511 8192000000 10061652800 10525876224 1111004544 11.942 98 18 36 1 1160678496 45808816 3865136
06:23:52 30118 8192000000 10088828480 10549911552 1115822832 11.988 98 14 28 1 1181475616 62401888 3250896
06:25:22 44734 8192000000 10116715584 10596298752 1112922240 11.960 98 17 34 0 1192345648 75582624 3840784
06:27:07 25665 8192000000 10104317552 10567467008 1103865584 11.874 97 26 48 1 1209236496 98847632 6523280
06:28:33 65235 8192000000 10101957456 10558521344 1096672416 11.806 98 16 32 1 1190590976 90874448 3044112
06:30:40 46307 8192000000 10081220384 10546159616 1127941504 12.102 100 18 32 2 1181018096 49767408 3309184
06:32:29 75306 8192000000 10079738496 10549325824 1119022448 12.018 98 19 38 0 1152832992 29739392 4071152
06:34:46 45473 8192000000 10138537280 10584326144 1125020384 12.074 98 29 58 1 1219819056 88541264 6257408
06:36:38 52639 8192000000 10095690544 10551586816 1128990192 12.112 99 12 24 0 1186868352 55148144 2730016
06:38:31 64986 8192000000 10064327472 10515898368 1099997312 11.838 96 45 89 1 1199194672 89265104 9932256
06:40:40 6483 8192000000 10088865232 10518806528 1135813472 12.176 100 47 94 1 1195947760 50589040 9545248
06:41:49 26619 8192000000 10169871856 10622537728 1145952848 12.271 100 52 104 0 1274221536 117492768 10775920
06:43:19 49795 8192000000 10132880560 10601562112 1140316544 12.219 99 17 34 0 1252983968 109122608 3544816
06:45:09 39141 8192000000 10109206672 10544087040 1156108272 12.367 100 19 38 0 1183473904 23972208 3393424
06:46:49 74151 8192000000 10176217808 10617802752 1141792384 12.232 98 35 70 1 1245856448 96032416 8031648
06:49:08 43341 8192000000 10096573040 10633097216 1154152560 12.348 100 5 10 0 1207027712 52144240 730912
06:50:57 44358 8192000000 10065442816 10522791936 1099139680 11.829 96 16 32 0 1134926400 32897808 2888912
06:52:42 37698 8192000000 10081302960 10538405888 1114563216 11.976 98 33 66 0 1168873840 47585792 6724832
06:54:20 27987 8192000000 10128358464 10569400320 1131200240 12.133 100 32 64 1 1221735472 83292848 7242384
06:55:51 87100 8192000000 10160217200 10633154560 1126211632 12.086 99 23 46 1 1265208912 133102944 5894336
06:58:23 40810 8192000000 10094575008 10542055424 1108012288 11.914 98 35 72 3 1168339408 53546096 6781024
07:00:10 7264 8192000000 10100879392 10513768448 1117897632 12.007 100 72 144 1 1180478816 49187568 13393616
07:01:38 48792 8192000000 10110144336 10559414272 1093372000 11.775 97 37 73 0 1173112512 72731248 7009264
07:03:27 21607 8192000000 10119284528 10539208704 1133009840 12.150 100 52 101 2 1206895712 62884784 11001088
07:04:50 99044 8192000000 10084111776 10528272384 1108559776 11.919 99 46 92 1 1204862608 86976560 9326272
07:07:30 10317 8192000000 10047942800 10483466240 1125711792 12.081 100 43 82 2 1156225984 22249520 8264672
07:08:41 38292 8192000000 10080844512 10531602432 1112849968 11.959 99 24 48 1 1188142992 70408112 4884912
07:10:21 61312 8192000000 10064448480 10507243520 1105932160 11.894 98 26 49 3 1165522496 54057936 5532400
07:12:26 37625 8192000000 10088476256 10546970624 1115973584 11.989 100 10 20 0 1190006992 71596992 2436416
07:14:04 60548 8192000000 10078093568 10532466688 1116315024 11.992 99 11 22 0 1158667824 40176448 2176352
07:16:19 70274 8192000000 10080966880 10532646912 1100197840 11.840 97 18 34 1 1150050304 46017056 3835408
07:18:31 39175 8192000000 10088967104 10528772096 1101112464 11.848 97 25 50 0 1157176320 51267264 4796592
07:20:16 45067 8192000000 10094862352 10527301632 1103891184 11.875 97 18 36 0 1157599232 50023008 3685040
07:22:02 103023 8192000000 10104463568 10613977088 1101078960 11.848 96 25 50 1 1222817856 116401760 5337136
07:25:32 43368 8192000000 10085322112 10526564352 1081755504 11.664 95 26 52 3 1124880432 37856480 5268448
07:27:19 11858 8192000000 10105370576 10522517504 1133858752 12.158 100 65 119 0 1192887440 47281536 11747152
07:28:33 39773 8192000000 10117579600 10565672960 1135377872 12.172 100 38 76 1 1206193472 63263072 7552528
07:30:14 86099 8192000000 10070385776 10527985664 1123612080 12.061 99 27 52 0 1177187888 47939312 5636496
07:32:40 83302 8192000000 10040166208 10509991936 1099263744 11.831 97 10 19 1 1152031168 50409696 2357728
07:35:07 66773 8192000000 10121693040 10578280448 1141443664 12.229 100 20 40 0 1202293552 56940256 3909632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-05-2022 07:39:19 to 11-05-2022 07:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 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 11-05-2022 07:39:36 to 11-05-2022 07:39: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          218127
MON$OLDEST_ACTIVE               218397
MON$OLDEST_SNAPSHOT             218397
MON$NEXT_TRANSACTION            218397
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                       2058976
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:294: 
:295: mscs-test-161.budzdorov.ru    Wed May 11 07:37:13 2022
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:297: 
:298: 
:299: mscs-test-161.budzdorov.ru    Wed May 11 07:37:31 2022
:300:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:301: 
--- end of diff output ---

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

11.05.22 07:39: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-05-11 07:39:42 No severe PSQL-related problems occured
11.05.22 07:39:43. End of report.