Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.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 15942300 kB
MemAvailable 31208020 kB
Buffers 20 kB
Cached 15695148 kB
SwapCached 2460 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-19 04:37:18
DTS_END                         2022-06-19 04:37:18.8730
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/51768
TRN_ID                          216943
ATT_ID                          610
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: 8883.89 24829 1066067
customer order: creation 168.68 449 20242
customer order: refuse 14.73 401 1768
order to supplier: creation 44.53 1352 5343
order to supplier: removal 3.33 175408 399
invoice (draft): creation 45.65 2985 5478
invoice (draft): removal 50.43 1028 6051
invoice accept: apply 41.01 24689 4921
invoice accept: cancel 45.13 1220 5416
customer reserve: creation 3795.32 234 455438
customer reserve: removal 4384.48 103 526138
realization accept: apply 82.24 273 9869
realization accept: cancel 81.89 179 9827
payment from customer: creation 57.73 167 6927
payment from customer: removal 5.59 111 671
payment to supplier: creation 47.90 193 5748
payment to supplier: removal 3.22 126 386
service: total inventory turnovers 4.09 3926 491
service: total monetary turnovers 7.03 334 843
service: aggregate perf. data 0.89 17386 107
service: refresh index statistics 0.03 266023 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5017
TEST_TIME 2 3723
TEST_TIME 3 4711
TEST_TIME 4 4602
TEST_TIME 5 4980
TEST_TIME 6 5694
TEST_TIME 7 5919
TEST_TIME 8 6241
TEST_TIME 9 6652
TEST_TIME 10 6730
TEST_TIME 11 6725
TEST_TIME 12 6630
TEST_TIME 13 6628
TEST_TIME 14 6509
TEST_TIME 15 6841
TEST_TIME 16 6866
TEST_TIME 17 6969
TEST_TIME 18 7223
TEST_TIME 19 7274
TEST_TIME 20 7386
TEST_TIME 21 7421
TEST_TIME 22 7475
TEST_TIME 23 7903
TEST_TIME 24 8202
TEST_TIME 25 8545
TEST_TIME 26 8615
TEST_TIME 27 9101
TEST_TIME 28 9241
TEST_TIME 29 9637
TEST_TIME 30 9786
TEST_TIME 31 10215
TEST_TIME 32 10781
TEST_TIME 33 11106
TEST_TIME 34 11187
TEST_TIME 35 11158
TEST_TIME 36 11045
TEST_TIME 37 10918
TEST_TIME 38 10683
TEST_TIME 39 10510
TEST_TIME 40 10404
TEST_TIME 41 10387
TEST_TIME 42 10473
TEST_TIME 43 10494
TEST_TIME 44 10550
TEST_TIME 45 10410
TEST_TIME 46 10278
TEST_TIME 47 10128
TEST_TIME 48 9987
TEST_TIME 49 9830
TEST_TIME 50 9791
TEST_TIME 51 9646
TEST_TIME 52 9546
TEST_TIME 53 9513
TEST_TIME 54 9446
TEST_TIME 55 9444
TEST_TIME 56 9333
TEST_TIME 57 9267
TEST_TIME 58 9504
TEST_TIME 59 9508
TEST_TIME 60 9500
TEST_TIME 61 9490
TEST_TIME 62 9600
TEST_TIME 63 9594
TEST_TIME 64 9521
TEST_TIME 65 9506
TEST_TIME 66 9566
TEST_TIME 67 9628
TEST_TIME 68 9624
TEST_TIME 69 9607
TEST_TIME 70 9502
TEST_TIME 71 9447
TEST_TIME 72 9413
TEST_TIME 73 9365
TEST_TIME 74 9329
TEST_TIME 75 9308
TEST_TIME 76 9274
TEST_TIME 77 9168
TEST_TIME 78 9203
TEST_TIME 79 9166
TEST_TIME 80 9126
TEST_TIME 81 9091
TEST_TIME 82 9150
TEST_TIME 83 9189
TEST_TIME 84 9160
TEST_TIME 85 9157
TEST_TIME 86 9139
TEST_TIME 87 9158
TEST_TIME 88 9323
TEST_TIME 89 9288
TEST_TIME 90 9359
TEST_TIME 91 9298
TEST_TIME 92 9271
TEST_TIME 93 9303
TEST_TIME 94 9251
TEST_TIME 95 9200
TEST_TIME 96 9143
TEST_TIME 97 9172
TEST_TIME 98 9135
TEST_TIME 99 9147
TEST_TIME 100 9118
TEST_TIME 101 9090
TEST_TIME 102 9159
TEST_TIME 103 9155
TEST_TIME 104 9194
TEST_TIME 105 9207
TEST_TIME 106 9250
TEST_TIME 107 9355
TEST_TIME 108 9299
TEST_TIME 109 9238
TEST_TIME 110 9220
TEST_TIME 111 9183
TEST_TIME 112 9130
TEST_TIME 113 9075
TEST_TIME 114 9056
TEST_TIME 115 9017
TEST_TIME 116 8975
TEST_TIME 117 8965
TEST_TIME 118 8940
TEST_TIME 119 8945
TEST_TIME 120 8898

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-06-2022 04:38:40 to 19-06-2022 04:38:43.

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 1485244 1485244 0 0.00 0 19919 14 0 0
doc_list_biud 1485242 1485242 0 0.00 0 49763 43 0 0
sp_add_invoice_to_stock 5007 4921 86 1.71 24 194070 24690 0 86
sp_cancel_adding_invoice 5416 5416 0 0.00 7 78826 1220 0 0
sp_cancel_client_order 1768 1768 0 0.00 7 13871 402 0 0
sp_cancel_customer_reserve 526138 526138 0 0.00 1 49782 104 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 5660 111 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 4100 126 0 0
sp_cancel_supplier_invoice 6061 6051 10 0.16 2 34375 1028 0 10
sp_cancel_supplier_order 399 399 0 0.00 7 1488381 175409 0 0
sp_cancel_write_off 9827 9827 0 0.00 2 13462 179 0 0
sp_client_order 20242 20242 0 0.00 5 29489 449 0 0
sp_customer_reserve 455438 455438 0 0.00 1 104603 235 0 0
sp_fill_shopping_cart 31055 31055 0 0.00 1 13560 80 0 0
sp_fill_shopping_cart_clo_res 455424 455424 0 0.00 0 25387 18 0 0
sp_get_clo_for_invoice 4907 4907 0 0.00 0 26236 308 0 0
sp_kill_cost_storno 16300 16300 0 0.00 0 19912 74 0 0
sp_kill_qstorno_ret_qs2qd 1068553 1068553 0 0.00 0 49720 45 0 0
sp_kill_qty_storno 564375 564375 0 0.00 0 49759 103 0 0
sp_lock_selected_doc 20480 20480 0 0.00 0 6040 7 0 0
sp_make_cost_storno 27451 27451 0 0.00 0 11594 79 0 0
sp_multiply_rows_for_pdistr 27451 27451 0 0.00 0 11116 41 0 0
sp_multiply_rows_for_qdistr 20242 20242 0 0.00 0 27605 183 0 0
sp_pay_from_customer 6927 6927 0 0.00 5 16616 168 0 0
sp_pay_to_supplier 5748 5748 0 0.00 6 14677 193 0 0
sp_payment_common 12675 12675 0 0.00 2 14406 48 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 13858 334 0 0
sp_qd_handle_on_invoice_upd_sts 10323 10323 0 0.00 0 32491 542 0 0
sp_qd_handle_on_reserve_upd_sts 9869 9869 0 0.00 0 14373 65 0 0
sp_reserve_write_off 9869 9869 0 0.00 9 14399 274 0 0
sp_supplier_invoice 5478 5478 0 0.00 11 65794 2986 0 0
sp_supplier_order 5343 5343 0 0.00 8 38278 1352 0 0
srv_aggregate_perf_data 107 107 0 0.00 2554 104804 17386 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 785 172750 37930 0 0
srv_find_qd_qs_mism 1017952 1017952 0 0.00 0 7178 10 0 0
srv_make_invnt_saldo 491 491 0 0.00 50 61976 3927 0 0
srv_make_money_saldo 843 843 0 0.00 1 9136 335 0 0
srv_recalc_idx_stat 4 4 0 0.00 163382 407516 266024 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4925 4925 0 0.00 1 33787 1023 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 6749 443 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 1400 28 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 614 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 6458 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 601 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1109 38 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 106657 8195 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 1054 19 0 0
t$perf-norm:sp_client_order 20242 20242 0 0.00 0 9470 37 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1636 17 0 0
t$perf-norm:sp_pay_from_customer 6927 6927 0 0.00 0 21503 25 0 0
t$perf-norm:sp_pay_to_supplier 5748 5748 0 0.00 0 19687 21 0 0
t$perf-norm:sp_reserve_write_off 9869 9869 0 0.00 0 9181 23 0 0
t$perf-norm:sp_supplier_invoice 5478 5478 0 0.00 0 22285 44 0 0
t$perf-norm:sp_supplier_order 5343 5343 0 0.00 0 12116 33 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 623 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1454 32 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 18407 56 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 797 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 105 35 0 0
v_add_invoice_to_stock 9986 9814 172 1.72 0 3445 22 0 172
v_all_customers 36808 36808 0 0.00 0 77 4 0 0
v_all_suppliers 30280 30280 0 0.00 0 243 4 0 0
v_all_wares 40482 40482 0 0.00 0 144 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 4612 21 0 0
v_cancel_client_order 3536 3536 0 0.00 0 1096 9 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 501 10 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 872 10 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 452 11 0 0
v_cancel_supplier_invoice 846 826 20 2.36 0 2899 29 0 20
v_cancel_supplier_order 798 798 0 0.00 0 1092 10 0 0
v_cancel_write_off 1966 1966 0 0.00 0 6239 26 0 0
v_max_id_clo_ord 5343 5343 0 0.00 0 1649 5 0 0
v_max_id_clo_res 972 972 0 0.00 0 2248 12 0 0
v_max_id_ord_sup 5468 5468 0 0.00 0 1801 6 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 424 8 0 0
v_max_non_paid_realizn 3610 3610 0 0.00 0 7156 11 0 0
v_min_id_clo_ord 5343 5343 0 0.00 0 3202 8 0 0
v_min_id_clo_res 972 972 0 0.00 0 3695 12 0 0
v_min_id_ord_sup 5468 5468 0 0.00 0 8910 9 0 0
v_min_non_paid_invoice 5748 5748 0 0.00 0 9516 19 0 0
v_min_non_paid_realizn 6926 6926 0 0.00 0 14401 18 0 0
v_our_firm 10308 10308 0 0.00 0 60 5 0 0
v_reserve_write_off 19738 19738 0 0.00 0 3117 10 0 0
x_lock_depdocs_on_canc_invoice 11054 11054 0 0.00 0 33961 127 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 7585 340 0 0
x_make_qstorno_1000_1200 5343 5343 0 0.00 2 38252 1184 0 0
x_make_qstorno_1200_2000 5478 5478 0 0.00 5 65782 2779 0 0
x_make_qstorno_2100_3300 455437 455437 0 0.00 0 104330 204 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-06-2022 04:38:44 to 19-06-2022 04:38:48.

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:22 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 12937 8192000000 9724881856 10022010880 802771152 8.924 100 88 176 0 831553568 7680608 21101808
02:09:41 17174 8192000000 9764890944 10071891968 839496336 9.295 100 88 174 1 871964208 11996912 20470960
02:11:03 12595 8192000000 9797615760 10114756608 868445936 9.585 100 87 174 1 905348512 16773408 20129168
02:12:21 9846 8192000000 9860432128 10192789504 919242112 10.089 100 83 166 0 975965504 38227120 18496272
02:13:35 9132 8192000000 9914905568 10262712320 959436176 10.483 100 77 155 0 1030065776 54427984 16201616
02:14:47 2950 8192000000 9948651392 10301157376 992717680 10.808 100 87 164 0 1071574928 61909040 16948208
02:15:53 1679 8192000000 9979355248 10340446208 1020614432 11.078 100 92 178 1 1102773648 63282928 18876288
02:16:57 1129 8192000000 10017988368 10379345920 1031967504 11.187 100 97 191 1 1129994832 78144896 19882432
02:18:00 1324 8192000000 10019105760 10390073344 1050981888 11.370 100 93 184 1 1133943744 64802320 18159536
02:19:04 708 8192000000 10038271248 10415230976 1061392880 11.470 100 97 184 2 1158615120 77988496 19233744
02:20:07 1236 8192000000 10040295872 10418581504 1062474272 11.480 100 96 189 0 1164852816 83628928 18749616
02:21:10 1107 8192000000 10032964016 10415722496 1071936144 11.571 100 89 173 1 1153279040 64017840 17325056
02:22:12 1283 8192000000 10031958704 10415845376 1076408704 11.613 100 95 185 0 1152514544 57762096 18343744
02:23:15 1277 8192000000 10055891552 10439565312 1081567168 11.662 100 99 191 0 1177180272 76470288 19142816
02:24:18 5624 8192000000 10046485856 10440056832 1082390560 11.670 100 70 133 0 1165600736 69816048 13394128
02:25:24 4727 8192000000 10040239584 10432020480 1083457312 11.680 100 73 137 3 1163591280 66795056 13338912
02:26:29 1901 8192000000 10058970320 10449985536 1090087008 11.743 100 96 190 1 1184413056 76467024 17859024
02:27:32 1095 8192000000 10075748848 10464333824 1091362096 11.756 100 92 176 0 1193377440 83906192 18109152
02:28:34 986 8192000000 10040211568 10431361024 1083366400 11.680 100 91 173 1 1166686896 65754672 17565824
02:29:37 1196 8192000000 10046058256 10436079616 1091915984 11.761 100 94 179 0 1170117552 59841216 18360352
02:30:39 2644 8192000000 10053990656 10440372224 1088928400 11.732 100 90 162 1 1170845072 67173952 14742720
02:31:42 857 8192000000 10023469584 10407419904 1068623312 11.539 100 88 167 4 1149320864 63521808 17175744
02:32:44 1020 8192000000 10039019008 10424721408 1067352368 11.527 100 86 170 2 1163143584 77421696 18369520
02:33:47 1297 8192000000 10017267856 10394365952 1048420512 11.346 100 95 177 0 1132851232 65125840 19304880
02:34:49 3684 8192000000 9951604064 10326319104 1014881984 11.023 98 60 105 1 1066949552 40914608 11152960
02:35:53 975 8192000000 9977258128 10347302912 1036968640 11.236 100 91 174 2 1098858160 44277760 17611760
02:36:55 1087 8192000000 9991507856 10367324160 1045696848 11.319 100 91 180 0 1117533024 52600304 19235872
02:38:04 7692 8192000000 9966739696 10356297728 1054199744 11.401 99 45 79 2 1077317712 14961680 8156288
02:39:22 14423 8192000000 10041030608 10429095936 1076692192 11.616 100 62 122 0 1151085184 61830560 12562432
02:40:48 1255 8192000000 10078845536 10463055872 1096663184 11.806 99 96 185 3 1199357856 83608208 19086464
02:41:53 6519 8192000000 10138681408 10532503552 1116852720 11.997 100 66 123 2 1251772576 121735520 13184336
02:43:06 2591 8192000000 10164144096 10566803456 1119678688 12.024 100 84 160 0 1276599440 139850048 17070704
02:44:12 2531 8192000000 10189020032 10587893760 1139364752 12.210 100 89 166 2 1311932400 156251280 16316368
02:45:21 1282 8192000000 10214891472 10617032704 1149818176 12.308 100 95 181 2 1329807104 159352320 20636608
02:46:25 1293 8192000000 10262092576 10657972224 1153978592 12.347 100 96 188 2 1369883232 194613760 21290880
02:47:29 2738 8192000000 10258689216 10661613568 1166458512 12.464 100 85 161 0 1377364784 193363760 17542512
02:48:36 1064 8192000000 10301965840 10704039936 1181098352 12.600 100 93 181 1 1415848208 213832864 20916992
02:49:41 1190 8192000000 10337529584 10740695040 1184414320 12.631 100 96 187 2 1449692720 243736608 21541792
02:50:48 1610 8192000000 10373313072 10776723456 1192839216 12.710 100 94 186 0 1483374112 268872464 21662432
02:51:53 1781 8192000000 10396672384 10813087744 1192702528 12.709 100 89 174 1 1507095856 293539632 20853696
02:52:58 1452 8192000000 10435442112 10847571968 1204595584 12.819 100 91 176 1 1544297920 319231200 20471136
02:54:05 1657 8192000000 10454508320 10875301888 1207327680 12.844 100 91 178 1 1560604448 332155120 21121648
02:55:10 1601 8192000000 10487140640 10907295744 1199240848 12.769 100 94 179 1 1590827728 370469024 21117856
02:56:16 1103 8192000000 10519495328 10930917376 1203553584 12.809 100 95 186 0 1624981232 399414144 22013504
02:57:20 1740 8192000000 10532052480 10960510976 1206193424 12.834 100 88 171 0 1643302272 416218128 20890720
02:58:24 865 8192000000 10595735376 11006152704 1202545744 12.800 100 95 187 0 1703079680 477233280 23300656
02:59:27 820 8192000000 10608346784 11063816192 1199870112 12.775 100 97 187 2 1711009008 488413808 22725088
03:00:29 777 8192000000 10560870128 11066736640 1198542592 12.763 100 87 167 2 1667458688 446795472 22120624
03:01:34 5019 8192000000 10588111792 11059425280 1205525280 12.828 100 79 155 0 1686586272 460975904 20085088
03:02:43 4216 8192000000 10565294576 11016368128 1198600736 12.763 100 70 140 1 1663868624 447739552 17528336
03:03:56 15345 8192000000 10501535488 11022225408 1192860208 12.710 100 54 107 1 1634037232 427550288 13626736
03:05:16 18041 8192000000 10488079120 10965057536 1186321856 12.649 100 34 68 1 1597994672 402690208 8982608
03:06:45 30362 8192000000 10400096288 10883571712 1165230512 12.452 100 27 54 1 1537131152 364755616 7145024
03:08:18 18360 8192000000 10321864256 10814091264 1151575920 12.324 99 28 57 0 1452499424 293916672 7006832
03:09:49 14920 8192000000 10168897040 10637836288 1138622992 12.203 100 33 64 1 1302313904 156519504 7171408
03:11:05 36063 8192000000 10082880192 10518999040 1122078976 12.047 99 27 52 0 1185819488 58136544 5603968
03:12:44 46481 8192000000 10139900464 10605535232 1137130720 12.189 99 33 66 1 1237502720 93102544 7269456
03:14:34 96662 8192000000 10188396736 10641137664 1120402080 12.031 97 48 96 1 1232283600 101830640 10050880
03:17:12 124537 8192000000 10172533232 10685329408 1086007920 11.705 95 27 54 0 1229552080 137428976 6115184
03:20:20 172749 8192000000 10071709056 10587815936 1126597952 12.089 99 16 32 0 1186997360 57226400 3173008
03:24:13 137218 8192000000 10033127328 10494160896 1095278048 11.793 96 7 14 0 1109845056 13188448 1378560
03:27:38 60828 8192000000 10045855120 10528796672 1113684032 11.967 99 16 33 0 1158243584 40664896 3894656
03:29:42 39827 8192000000 10093175840 10544988160 1100689072 11.844 97 41 80 1 1174670576 64470000 9511504
03:31:24 44253 8192000000 10110379344 10568794112 1114121280 11.971 98 24 48 1 1203497744 84157296 5219168
03:33:09 60441 8192000000 10133498576 10592223232 1120434352 12.031 99 22 44 1 1220033376 94774432 4824592
03:35:33 38474 8192000000 10067019392 10597261312 1106889312 11.903 98 12 24 0 1202911312 92912080 3109920
03:37:12 45225 8192000000 10083076272 10520367104 1086618800 11.710 96 35 70 1 1165147392 71044688 7483904
03:38:59 67754 8192000000 10068825360 10535317504 1108600272 11.919 98 35 67 0 1168817216 53708608 6508336
03:41:07 23991 8192000000 10086801408 10518343680 1130620208 12.127 100 38 76 0 1179725728 41188032 7917488
03:42:37 38670 8192000000 10112856624 10574090240 1125289760 12.077 99 31 62 2 1209258096 77975600 5992736
03:44:20 122814 8192000000 10069440224 10534813696 1069610800 11.548 94 13 26 0 1174563904 101994688 2958416
03:47:25 80288 8192000000 10111538720 10550931456 1134810384 12.167 100 25 50 0 1158407328 18731744 4865200
03:49:47 47627 8192000000 10083725552 10546495488 1134598176 12.165 99 17 34 1 1196237552 58116304 3523072
03:51:57 65063 8192000000 10054097552 10522865664 1069530448 11.548 94 7 15 0 1106412880 35321808 1560624
03:54:08 68893 8192000000 10100384496 10570178560 1133068128 12.150 100 12 24 1 1202566784 66769424 2729232
03:56:36 18075 8192000000 10065913920 10517688320 1135662704 12.175 99 33 60 3 1157362736 15274784 6425248
03:57:54 60697 8192000000 10142968880 10589282304 1150445088 12.314 100 27 54 0 1241152656 85232816 5474752
03:59:57 56096 8192000000 10107299584 10584813568 1103015568 11.866 96 21 41 2 1178040928 70428320 4597040
04:01:54 17797 8192000000 10139783456 10575400960 1130724432 12.128 99 36 70 0 1221711872 83380192 7607248
04:03:18 42061 8192000000 10178544160 10631766016 1114699888 11.977 99 39 78 0 1254535824 131414992 8420944
04:05:06 31265 8192000000 10062279296 10540171264 1100023408 11.838 98 25 50 1 1206816000 101158864 5633728
04:06:43 63135 8192000000 10125249328 10586226688 1135898064 12.177 100 43 86 2 1279281472 134525696 8857712
04:08:47 94611 8192000000 10089288912 10579791872 1135557872 12.174 99 28 53 2 1252530624 110620640 6352112
04:11:23 51145 8192000000 10096638224 10558451712 1142812000 12.242 100 11 22 1 1201816544 56617936 2386608
04:13:14 75969 8192000000 10106434304 10608930816 1115351808 11.983 98 19 38 0 1230639600 110691152 4596640
04:15:31 33874 8192000000 10150892080 10608099328 1124962512 12.074 98 32 64 1 1231962096 98897856 8101728
04:17:11 14562 8192000000 10153917296 10589224960 1131672928 12.137 99 40 80 2 1237330128 96895088 8762112
04:18:27 20422 8192000000 10187626896 10627203072 1135902720 12.177 99 43 85 3 1276533040 131416032 9214288
04:19:48 54748 8192000000 10156311184 10601955328 1134877520 12.167 99 33 66 0 1259762768 117260528 7624720
04:21:49 41896 8192000000 10128729728 10565009408 1103600576 11.872 97 25 48 1 1212199632 103779312 4819744
04:23:35 90596 8192000000 10081039472 10587185152 1109906544 11.932 98 17 34 0 1195807888 82338224 3563120
04:26:54 64292 8192000000 10038523952 10522750976 1106981184 11.904 98 12 24 0 1148713136 39589456 2142496
04:28:58 69703 8192000000 10047597136 10515320832 1117457520 12.003 99 13 26 1 1149505344 29212832 2834992
04:31:09 20090 8192000000 10041288080 10494619648 1105690096 11.892 99 21 40 1 1138012944 28441456 3881392
04:32:29 54538 8192000000 10037136768 10496581632 1097977536 11.818 98 18 36 0 1132833872 31531328 3325008
04:34:24 56382 8192000000 10027182976 10487943168 1085075584 11.696 97 19 36 1 1125871984 37174672 3621728
04:36:28 42775 8192000000 10061013072 10506711040 1105279584 11.888 98 32 64 3 1165221344 52821776 7119984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-06-2022 04:38:50 to 19-06-2022 04:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 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 19-06-2022 04:39:07 to 19-06-2022 04:39:09.

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          215047
MON$OLDEST_ACTIVE               217166
MON$OLDEST_SNAPSHOT             217166
MON$NEXT_TRANSACTION            217166
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                       2065440
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:181: 
:182: mscs-test-161.budzdorov.ru    Sun Jun 19 04:37:21 2022
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:184: 
:185: 
:186: mscs-test-161.budzdorov.ru    Sun Jun 19 04:37:27 2022
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
--- end of diff output ---

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

19.06.22 04:39:10. 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-19 04:39:12 No severe PSQL-related problems occured
19.06.22 04:39:13. End of report.