Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.06.2022 04: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 16448352 kB
MemAvailable 31336292 kB
Buffers 20 kB
Cached 15238156 kB
SwapCached 2444 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-06-03 04:37:20
DTS_END                         2022-06-03 04:37:20.1810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59642
TRN_ID                          216489
ATT_ID                          658
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: 8502.88 23749 1020346
customer order: creation 165.13 476 19815
customer order: refuse 13.84 385 1661
order to supplier: creation 43.21 1375 5185
order to supplier: removal 3.02 168341 362
invoice (draft): creation 44.14 3467 5297
invoice (draft): removal 46.08 1038 5529
invoice accept: apply 40.22 27682 4826
invoice accept: cancel 41.10 1279 4932
customer reserve: creation 3777.37 263 453284
customer reserve: removal 4050.24 98 486029
realization accept: apply 80.21 269 9625
realization accept: cancel 75.29 173 9035
payment from customer: creation 55.30 165 6636
payment from customer: removal 5.28 114 633
payment to supplier: creation 46.88 178 5626
payment to supplier: removal 3.22 118 386
service: total inventory turnovers 4.28 3519 514
service: total monetary turnovers 7.22 348 866
service: aggregate perf. data 0.84 18569 101
service: refresh index statistics 0.03 247128 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7249
TEST_TIME 2 7647
TEST_TIME 3 7123
TEST_TIME 4 6832
TEST_TIME 5 6382
TEST_TIME 6 6781
TEST_TIME 7 6359
TEST_TIME 8 6020
TEST_TIME 9 5930
TEST_TIME 10 6290
TEST_TIME 11 6431
TEST_TIME 12 6451
TEST_TIME 13 6903
TEST_TIME 14 6777
TEST_TIME 15 6754
TEST_TIME 16 6980
TEST_TIME 17 7163
TEST_TIME 18 7116
TEST_TIME 19 7523
TEST_TIME 20 7982
TEST_TIME 21 8312
TEST_TIME 22 8311
TEST_TIME 23 8287
TEST_TIME 24 8115
TEST_TIME 25 8690
TEST_TIME 26 8831
TEST_TIME 27 9001
TEST_TIME 28 9882
TEST_TIME 29 10153
TEST_TIME 30 10419
TEST_TIME 31 10721
TEST_TIME 32 10427
TEST_TIME 33 10408
TEST_TIME 34 10308
TEST_TIME 35 10258
TEST_TIME 36 10184
TEST_TIME 37 10131
TEST_TIME 38 9923
TEST_TIME 39 10000
TEST_TIME 40 9892
TEST_TIME 41 9808
TEST_TIME 42 9848
TEST_TIME 43 9715
TEST_TIME 44 9547
TEST_TIME 45 9514
TEST_TIME 46 9391
TEST_TIME 47 9325
TEST_TIME 48 9272
TEST_TIME 49 9237
TEST_TIME 50 9121
TEST_TIME 51 8987
TEST_TIME 52 8917
TEST_TIME 53 9038
TEST_TIME 54 8996
TEST_TIME 55 8900
TEST_TIME 56 8946
TEST_TIME 57 8850
TEST_TIME 58 8830
TEST_TIME 59 8873
TEST_TIME 60 8871
TEST_TIME 61 8927
TEST_TIME 62 8871
TEST_TIME 63 8872
TEST_TIME 64 8901
TEST_TIME 65 8954
TEST_TIME 66 9060
TEST_TIME 67 9001
TEST_TIME 68 9090
TEST_TIME 69 9138
TEST_TIME 70 9041
TEST_TIME 71 8990
TEST_TIME 72 8931
TEST_TIME 73 8963
TEST_TIME 74 8941
TEST_TIME 75 8935
TEST_TIME 76 8868
TEST_TIME 77 8890
TEST_TIME 78 8859
TEST_TIME 79 8792
TEST_TIME 80 8726
TEST_TIME 81 8722
TEST_TIME 82 8702
TEST_TIME 83 8688
TEST_TIME 84 8689
TEST_TIME 85 8749
TEST_TIME 86 8769
TEST_TIME 87 8750
TEST_TIME 88 8716
TEST_TIME 89 8757
TEST_TIME 90 8725
TEST_TIME 91 8890
TEST_TIME 92 8890
TEST_TIME 93 8858
TEST_TIME 94 8811
TEST_TIME 95 8821
TEST_TIME 96 8764
TEST_TIME 97 8745
TEST_TIME 98 8709
TEST_TIME 99 8685
TEST_TIME 100 8662
TEST_TIME 101 8661
TEST_TIME 102 8641
TEST_TIME 103 8644
TEST_TIME 104 8636
TEST_TIME 105 8616
TEST_TIME 106 8610
TEST_TIME 107 8594
TEST_TIME 108 8616
TEST_TIME 109 8647
TEST_TIME 110 8676
TEST_TIME 111 8669
TEST_TIME 112 8734
TEST_TIME 113 8742
TEST_TIME 114 8698
TEST_TIME 115 8689
TEST_TIME 116 8637
TEST_TIME 117 8648
TEST_TIME 118 8594
TEST_TIME 119 8586
TEST_TIME 120 8529

Performance per minute, chart


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

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 1436152 1436152 0 0.00 0 20687 13 0 0
doc_list_biud 1436148 1436148 0 0.00 0 53483 39 0 0
sp_add_invoice_to_stock 4912 4826 86 1.75 74 237350 27683 0 86
sp_cancel_adding_invoice 4932 4932 0 0.00 6 91448 1280 0 0
sp_cancel_client_order 1661 1661 0 0.00 5 9646 386 0 0
sp_cancel_customer_reserve 486029 486029 0 0.00 1 26235 99 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 4 8536 115 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 5879 118 0 0
sp_cancel_supplier_invoice 5540 5529 11 0.19 3 53491 1039 0 11
sp_cancel_supplier_order 362 362 0 0.00 10 1281161 168342 0 0
sp_cancel_write_off 9035 9035 0 0.00 2 15337 174 0 0
sp_client_order 19815 19815 0 0.00 5 27094 476 0 0
sp_customer_reserve 453284 453284 0 0.00 1 144953 264 0 0
sp_fill_shopping_cart 30292 30292 0 0.00 1 26074 84 0 0
sp_fill_shopping_cart_clo_res 453264 453264 0 0.00 0 14888 21 0 0
sp_get_clo_for_invoice 4784 4784 0 0.00 0 29524 366 0 0
sp_kill_cost_storno 14986 14986 0 0.00 0 20678 74 0 0
sp_kill_qstorno_ret_qs2qd 986984 986984 0 0.00 0 53481 44 0 0
sp_kill_qty_storno 521957 521957 0 0.00 0 53482 100 0 0
sp_lock_selected_doc 19860 19860 0 0.00 0 9815 8 0 0
sp_make_cost_storno 26671 26671 0 0.00 0 16561 74 0 0
sp_multiply_rows_for_pdistr 26671 26671 0 0.00 0 10859 37 0 0
sp_multiply_rows_for_qdistr 19815 19815 0 0.00 0 26920 184 0 0
sp_pay_from_customer 6636 6636 0 0.00 5 12949 166 0 0
sp_pay_to_supplier 5626 5626 0 0.00 6 16575 178 0 0
sp_payment_common 12262 12262 0 0.00 2 7192 41 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 9640 312 0 0
sp_qd_handle_on_invoice_upd_sts 9716 9716 0 0.00 0 25177 490 0 0
sp_qd_handle_on_reserve_upd_sts 9625 9625 0 0.00 0 13006 63 0 0
sp_reserve_write_off 9625 9625 0 0.00 8 15629 270 0 0
sp_supplier_invoice 5297 5297 0 0.00 8 93289 3468 0 0
sp_supplier_order 5185 5185 0 0.00 8 84435 1375 0 0
srv_aggregate_perf_data 101 101 0 0.00 2984 117900 18570 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 925 149499 41765 0 0
srv_find_qd_qs_mism 972425 972425 0 0.00 0 15336 10 0 0
srv_make_invnt_saldo 514 514 0 0.00 51 107798 3520 0 0
srv_make_money_saldo 866 866 0 0.00 1 34174 348 0 0
srv_recalc_idx_stat 4 4 0 0.00 53784 521255 247128 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4830 4830 0 0.00 1 31613 897 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 6921 493 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 3853 28 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 30411 68 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 4118 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 279 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 3612 61 0 0
t$perf-norm:sp_cancel_supplier_order 362 362 0 0.00 0 105571 7460 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 1465 20 0 0
t$perf-norm:sp_client_order 19815 19815 0 0.00 0 24651 41 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 4811 17 0 0
t$perf-norm:sp_pay_from_customer 6636 6636 0 0.00 0 10947 24 0 0
t$perf-norm:sp_pay_to_supplier 5626 5626 0 0.00 0 11912 28 0 0
t$perf-norm:sp_reserve_write_off 9625 9625 0 0.00 0 26495 32 0 0
t$perf-norm:sp_supplier_invoice 5297 5297 0 0.00 0 8851 32 0 0
t$perf-norm:sp_supplier_order 5185 5185 0 0.00 0 11841 35 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1002 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 4496 73 0 0
t$perf-norm:srv_make_invnt_saldo 514 514 0 0.00 0 1675 17 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 3216 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 185 53 0 0
v_add_invoice_to_stock 9740 9568 172 1.76 0 13239 24 0 172
v_all_customers 35876 35876 0 0.00 0 225 3 0 0
v_all_suppliers 29322 29322 0 0.00 0 149 4 0 0
v_all_wares 39630 39630 0 0.00 0 150 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 3783 24 0 0
v_cancel_client_order 3322 3322 0 0.00 0 4249 12 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 4564 14 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 11664 27 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 5682 16 0 0
v_cancel_supplier_invoice 814 792 22 2.70 0 1090 21 0 22
v_cancel_supplier_order 724 724 0 0.00 0 457 10 0 0
v_cancel_write_off 1924 1924 0 0.00 0 2358 21 0 0
v_max_id_clo_ord 5185 5185 0 0.00 0 962 5 0 0
v_max_id_clo_res 958 958 0 0.00 0 3690 19 0 0
v_max_id_ord_sup 5292 5292 0 0.00 0 6166 8 0 0
v_max_non_paid_invoice 1442 1442 0 0.00 0 1361 8 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 653 6 0 0
v_min_id_clo_ord 5185 5185 0 0.00 0 3142 8 0 0
v_min_id_clo_res 958 958 0 0.00 0 7781 22 0 0
v_min_id_ord_sup 5292 5292 0 0.00 0 7459 14 0 0
v_min_non_paid_invoice 5626 5626 0 0.00 0 5138 12 0 0
v_min_non_paid_realizn 6636 6636 0 0.00 0 6469 15 0 0
v_our_firm 9976 9976 0 0.00 0 55 4 0 0
v_reserve_write_off 19250 19250 0 0.00 0 12573 12 0 0
x_lock_depdocs_on_canc_invoice 10065 10065 0 0.00 0 28946 126 0 0
x_lock_depdocs_on_canc_sup_ord 362 362 0 0.00 0 8881 391 0 0
x_make_qstorno_1000_1200 5185 5185 0 0.00 2 84426 1218 0 0
x_make_qstorno_1200_2000 5297 5297 0 0.00 4 93255 3225 0 0
x_make_qstorno_2100_3300 453284 453284 0 0.00 0 144949 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-06-2022 04:39:12 to 03-06-2022 04:39:16.

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
02:07:24 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 18441 8192000000 9726974384 10028744704 810738480 9.005 100 88 176 0 839669072 7522384 21408208
02:09:47 28494 8192000000 9772868048 10080530432 840605968 9.306 100 89 178 0 873864336 12492336 20766032
02:11:21 25630 8192000000 9818946272 10140672000 871476736 9.615 100 87 172 2 924209952 32548640 20184576
02:12:51 8476 8192000000 9880262560 10215960576 925498544 10.150 100 81 162 0 994859664 51365120 17996000
02:14:07 4086 8192000000 9921525520 10270121984 970817536 10.595 100 87 173 0 1038688848 49498016 18373296
02:15:14 2308 8192000000 9967648960 10319642624 995750896 10.837 100 94 179 1 1082365632 68876224 17738512
02:16:20 1274 8192000000 9997466400 10357395456 1017384224 11.047 100 92 177 3 1111069552 76215168 17470160
02:17:23 945 8192000000 10029056288 10399277056 1039809984 11.263 100 97 185 3 1149890192 91356560 18723648
02:18:26 1001 8192000000 10026312800 10403188736 1054474368 11.404 100 93 178 2 1144058064 71422864 18160832
02:19:28 759 8192000000 10030293312 10402295808 1061400688 11.470 100 100 192 1 1150920928 70862224 18658016
02:20:30 874 8192000000 10019192560 10398416896 1066724592 11.521 100 94 180 0 1142377904 57617376 18035936
02:21:33 1071 8192000000 10035842944 10415063040 1071401008 11.565 100 98 185 1 1154885168 64005584 19478576
02:22:37 1069 8192000000 10040808016 10425876480 1075517344 11.605 100 96 181 1 1164725456 70023904 19184208
02:23:40 1097 8192000000 10055633440 10436472832 1079507200 11.643 100 97 182 4 1178970496 80759680 18703616
02:24:41 6438 8192000000 10035461088 10425376768 1084817152 11.693 100 60 116 3 1154417344 57566768 12033424
02:25:49 1883 8192000000 10056129472 10446729216 1091096544 11.753 100 77 150 0 1181632080 75163584 15371952
02:26:51 1099 8192000000 10048801792 10430853120 1086170496 11.706 100 96 178 0 1169764176 64815312 18778368
02:27:55 6238 8192000000 10020625616 10405793792 1073004000 11.581 98 69 126 1 1129849376 43455440 13389936
02:29:02 5400 8192000000 10031963872 10421485568 1083443344 11.680 99 71 137 0 1159355872 62579072 13333456
02:30:08 967 8192000000 10030579184 10418819072 1074155184 11.592 100 76 141 3 1147350624 58253584 14941856
02:31:10 1496 8192000000 9988210640 10368004096 1064343680 11.498 100 90 169 2 1117049168 34702512 18002976
02:32:13 1046 8192000000 9987108208 10362732544 1027783728 11.147 99 89 168 0 1109901360 65016096 17101536
02:33:15 1284 8192000000 10018649984 10393853952 1039891232 11.264 99 88 156 1 1139838208 84765088 15181888
02:34:18 1157 8192000000 9989746416 10360913920 1024759056 11.118 100 93 171 0 1112178272 69463632 17955584
02:35:19 1161 8192000000 9982480464 10357784576 1030373344 11.172 99 95 184 0 1106788736 57411456 19003936
02:36:21 1040 8192000000 10005667296 10381606912 1040504480 11.270 100 98 181 1 1128006336 69402944 18098912
02:37:23 2586 8192000000 10014280704 10398855168 1057586240 11.433 100 82 151 1 1133102656 60545936 14970480
02:38:31 2405 8192000000 10027156448 10411524096 1077938736 11.628 100 76 151 2 1148359360 55420784 14999840
02:39:38 944 8192000000 10077538448 10465153024 1090892928 11.751 100 95 184 1 1199211536 87130176 21188432
02:40:45 1677 8192000000 10067027056 10454642688 1104284048 11.878 100 77 149 3 1183654080 64025424 15344608
02:41:52 7222 8192000000 10128646336 10521759744 1121002928 12.036 100 77 147 1 1247845872 111851696 14991248
02:43:02 1140 8192000000 10142434576 10533384192 1118721264 12.015 100 73 144 0 1256737280 123014224 15001792
02:44:08 9339 8192000000 10151300000 10558984192 1128396064 12.106 100 64 123 0 1271896144 131204304 12295776
02:45:29 5833 8192000000 10174643584 10580029440 1141015504 12.225 100 60 115 1 1295002000 140548912 13437584
02:46:40 4628 8192000000 10220853136 10624917504 1133160208 12.151 99 75 149 1 1324498384 175052416 16285760
02:47:50 1153 8192000000 10269581792 10662891520 1161600528 12.418 100 93 179 0 1378125824 195078464 21446832
02:48:54 2465 8192000000 10293975904 10700464128 1174163552 12.536 100 84 158 1 1403026048 210579904 18282592
02:50:02 1493 8192000000 10327965248 10753073152 1180494160 12.595 100 94 186 1 1442118176 239354016 22270000
02:51:08 1364 8192000000 10336259552 10739437568 1189005520 12.674 100 94 181 2 1442818096 232634560 21178016
02:52:13 1654 8192000000 10392347456 10805923840 1193247248 12.714 100 75 148 3 1507972288 297221472 17503568
02:53:17 917 8192000000 10435193760 10841731072 1183721392 12.625 99 98 191 0 1545848096 338986928 23139776
02:54:21 935 8192000000 10441068768 10864353280 1195402336 12.734 100 100 198 0 1548958496 330027312 23528848
02:55:26 2464 8192000000 10457337936 10884866048 1183735296 12.625 100 83 156 1 1563767360 361525840 18506224
02:56:30 1222 8192000000 10447708512 10925703168 1192164640 12.704 100 89 173 0 1558440944 346496800 19779504
02:57:34 1359 8192000000 10461755248 10920144896 1195047232 12.730 100 93 177 0 1568951552 353154960 20749360
02:58:37 9268 8192000000 10455699824 10891137024 1179577312 12.586 99 58 116 0 1547583936 353294384 14712240
02:59:50 6381 8192000000 10459731536 10890063872 1187529136 12.660 100 53 106 1 1561755312 361131760 13094416
03:01:02 4466 8192000000 10489288160 10918223872 1160212384 12.405 100 48 94 1 1593310240 420589440 12508416
03:02:24 3185 8192000000 10460465952 10934026240 1152106768 12.329 100 79 155 1 1574429856 402653840 19669248
03:03:36 18530 8192000000 10468778160 10939400192 1154531536 12.352 100 43 85 1 1561701216 395843152 11326528
03:04:57 17323 8192000000 10371933456 10870857728 1147353744 12.285 100 34 68 1 1481451648 324878864 9219040
03:06:15 41106 8192000000 10242183744 10741125120 1137505520 12.192 100 21 42 0 1387553984 244840624 5207840
03:07:57 43306 8192000000 10056285072 10470043648 1060636304 11.463 95 22 42 0 1118612752 53331216 4645232
03:09:42 5978 8192000000 10091632704 10500505600 1109234704 11.925 100 89 158 2 1184093424 57877472 16981248
03:10:49 21839 8192000000 10125918048 10556145664 1122170880 12.047 99 43 82 2 1224342224 92813008 9358336
03:12:12 149499 8192000000 10118311056 10627784704 1104460784 11.880 97 12 24 0 1229026640 121618624 2947232
03:15:42 94426 8192000000 10030441600 10516185088 1082406864 11.670 96 31 54 2 1134134288 46320288 5407136
03:18:19 85634 8192000000 10066747888 10543271936 1109134912 11.924 99 44 89 2 1185399056 66466992 9797152
03:20:45 49153 8192000000 10055698544 10489098240 1124941968 12.074 100 42 84 1 1151754192 17750240 9061984
03:22:40 46829 8192000000 10071832192 10516480000 1115649552 11.986 98 25 50 1 1154008096 32986432 5372112
03:24:38 38821 8192000000 10102611488 10559303680 1131239392 12.133 99 34 67 0 1204886288 66424288 7222608
03:26:25 76591 8192000000 10095477200 10563575808 1127059584 12.094 99 18 36 0 1201537104 70381008 4096512
03:28:42 106564 8192000000 10125075056 10603311104 1144996816 12.263 100 35 68 1 1259902976 106666864 8239296
03:31:30 98021 8192000000 10102939664 10538602496 1096053376 11.800 96 30 60 0 1159280544 56080944 7146224
03:34:22 95482 8192000000 10136198192 10591051776 1133768368 12.157 99 19 38 1 1235739968 97456432 4515168
03:37:04 113875 8192000000 10076081840 10542899200 1128486976 12.107 99 21 39 0 1210512832 78358096 3667760
03:40:02 57639 8192000000 10156145040 10590294016 1145556464 12.268 100 47 93 1 1247093936 92201232 9336240
03:41:59 51960 8192000000 10131371872 10604539904 1131007008 12.131 100 26 52 0 1237861264 101612384 5241872
03:43:52 50838 8192000000 10127564800 10559672320 1146253552 12.274 100 36 71 0 1215286240 61825840 7206848
03:45:45 78498 8192000000 10104074224 10542317568 1128075312 12.103 98 16 32 0 1218345104 86942016 3327776
03:48:07 124098 8192000000 10123407392 10559963136 1137385696 12.191 99 41 81 1 1211365424 65338144 8641584
03:51:12 14404 8192000000 10102417168 10539495424 1139196064 12.208 100 43 81 2 1187815776 39517024 9102688
03:52:28 88669 8192000000 10076385808 10541645824 1114508080 11.975 98 15 30 0 1164161280 46534688 3118512
03:54:57 47973 8192000000 10043619888 10531401728 1102112192 11.858 98 16 32 1 1150911680 45080480 3719008
03:56:46 59404 8192000000 10061070560 10517495808 1114452000 11.975 99 9 18 0 1154581824 38328464 1801360
03:58:57 52230 8192000000 10071555792 10511937536 1097958624 11.818 98 20 40 1 1126242592 24070720 4213248
04:00:53 33444 8192000000 10102947232 10536947712 1106470000 11.899 99 27 54 1 1193341664 81055376 5816288
04:02:31 80058 8192000000 10068341552 10499932160 1095565952 11.796 97 31 60 1 1149420352 46926784 6927616
04:05:02 46544 8192000000 10147683312 10579636224 1080893712 11.656 95 38 76 1 1195582768 106467632 8221424
04:06:52 25360 8192000000 10140149280 10568916992 1113531008 11.966 99 39 78 1 1217921600 95670160 8720432
04:08:18 77205 8192000000 10045619824 10469818368 1091169328 11.754 97 23 46 1 1134325344 37987584 5168432
04:10:36 71953 8192000000 10106872768 10562981888 1119508928 12.022 99 48 90 2 1214231104 85552736 9169440
04:13:18 9720 8192000000 10060242816 10481172480 1129806912 12.120 100 55 100 3 1166042880 27534800 8701168
04:14:28 67389 8192000000 10064204880 10534735872 1126280016 12.086 99 16 32 0 1189533488 60295968 2957504
04:16:41 86973 8192000000 10082209264 10549387264 1125801024 12.082 99 18 36 0 1195045136 65323488 3920624
04:19:10 56626 8192000000 10074707456 10512318464 1125770848 12.081 99 22 42 2 1166365808 35904272 4690688
04:21:07 66894 8192000000 10087014288 10543030272 1093044176 11.772 96 28 56 0 1127266496 28250976 5971344
04:23:22 32281 8192000000 10147129664 10596417536 1147872400 12.290 100 42 84 1 1251958512 95264736 8821376
04:24:55 33651 8192000000 10161323696 10598432768 1157800288 12.383 100 37 74 1 1264525680 99035840 7689552
04:26:29 66876 8192000000 10140087072 10604535808 1137308112 12.190 99 24 48 3 1233463824 91461088 4694624
04:28:38 64274 8192000000 10090978432 10564218880 1113920464 11.970 98 38 74 1 1199756848 78354288 7482096
04:30:44 116778 8192000000 10065502896 10512101376 1099595968 11.834 97 29 58 2 1170751792 64488464 6667360
04:33:42 23495 8192000000 10080558176 10512883712 1127947408 12.102 100 50 100 2 1177773616 39792960 10033248
04:35:08 77486 8192000000 10041518736 10497519616 1108680048 11.920 98 6 12 0 1139049872 29245920 1123904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-06-2022 04:39:18 to 03-06-2022 04:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-06-2022 04:39:35 to 03-06-2022 04:39:37.

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          216721
MON$OLDEST_ACTIVE               216722
MON$OLDEST_SNAPSHOT             216722
MON$NEXT_TRANSACTION            216722
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                       2025248
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Fri Jun  3 04:37:38 2022
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Jun  3 04:37:38 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
--- end of diff output ---

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

03.06.22 04:39:38. 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-06-03 04:39:40 No severe PSQL-related problems occured
03.06.22 04:39:41. End of report.