Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.08.2021 13: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16181896 kB
MemAvailable 30934784 kB
Buffers 0 kB
Cached 15489532 kB
SwapCached 1324 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.1
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 2021-08-25 13:37:07
DTS_END                         2021-08-25 13:37:07.4630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51702
TRN_ID                          230142
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: 8563.38 22393 1027606
customer order: creation 169.59 424 20351
customer order: refuse 14.36 361 1723
order to supplier: creation 43.99 1483 5279
order to supplier: removal 3.18 118963 381
invoice (draft): creation 45.15 3900 5418
invoice (draft): removal 48.09 1085 5771
invoice accept: apply 40.00 19413 4800
invoice accept: cancel 43.63 980 5235
customer reserve: creation 3639.60 194 436752
customer reserve: removal 4229.44 69 507533
realization accept: apply 82.38 206 9886
realization accept: cancel 78.02 133 9362
payment from customer: creation 58.62 128 7034
payment from customer: removal 5.68 122 681
payment to supplier: creation 47.71 154 5725
payment to supplier: removal 3.37 89 404
service: total inventory turnovers 3.70 3835 444
service: total monetary turnovers 5.91 707 709
service: aggregate perf. data 0.95 14890 114
service: refresh index statistics 0.03 280718 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-08-2021 13:38:21 to 25-08-2021 13:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3893
TEST_TIME 2 2322
TEST_TIME 3 3119
TEST_TIME 4 3694
TEST_TIME 5 4288
TEST_TIME 6 5241
TEST_TIME 7 5429
TEST_TIME 8 5758
TEST_TIME 9 5822
TEST_TIME 10 5920
TEST_TIME 11 6591
TEST_TIME 12 6749
TEST_TIME 13 6662
TEST_TIME 14 6863
TEST_TIME 15 7023
TEST_TIME 16 7318
TEST_TIME 17 7656
TEST_TIME 18 8075
TEST_TIME 19 7995
TEST_TIME 20 8055
TEST_TIME 21 8328
TEST_TIME 22 8444
TEST_TIME 23 8451
TEST_TIME 24 8595
TEST_TIME 25 8551
TEST_TIME 26 8364
TEST_TIME 27 8760
TEST_TIME 28 9263
TEST_TIME 29 9802
TEST_TIME 30 10061
TEST_TIME 31 10371
TEST_TIME 32 10767
TEST_TIME 33 10463
TEST_TIME 34 10338
TEST_TIME 35 10139
TEST_TIME 36 9984
TEST_TIME 37 9815
TEST_TIME 38 9681
TEST_TIME 39 9569
TEST_TIME 40 9449
TEST_TIME 41 9444
TEST_TIME 42 9360
TEST_TIME 43 9285
TEST_TIME 44 9177
TEST_TIME 45 9131
TEST_TIME 46 9085
TEST_TIME 47 9085
TEST_TIME 48 9263
TEST_TIME 49 9160
TEST_TIME 50 9148
TEST_TIME 51 9031
TEST_TIME 52 9060
TEST_TIME 53 9116
TEST_TIME 54 9096
TEST_TIME 55 9135
TEST_TIME 56 9244
TEST_TIME 57 9260
TEST_TIME 58 9234
TEST_TIME 59 9210
TEST_TIME 60 9314
TEST_TIME 61 9258
TEST_TIME 62 9307
TEST_TIME 63 9262
TEST_TIME 64 9302
TEST_TIME 65 9196
TEST_TIME 66 9101
TEST_TIME 67 9071
TEST_TIME 68 9032
TEST_TIME 69 8973
TEST_TIME 70 8937
TEST_TIME 71 8930
TEST_TIME 72 8832
TEST_TIME 73 8751
TEST_TIME 74 8823
TEST_TIME 75 8770
TEST_TIME 76 8767
TEST_TIME 77 8930
TEST_TIME 78 8920
TEST_TIME 79 8942
TEST_TIME 80 8926
TEST_TIME 81 8911
TEST_TIME 82 8882
TEST_TIME 83 8988
TEST_TIME 84 9043
TEST_TIME 85 9100
TEST_TIME 86 9000
TEST_TIME 87 8911
TEST_TIME 88 8906
TEST_TIME 89 8879
TEST_TIME 90 8803
TEST_TIME 91 8749
TEST_TIME 92 8789
TEST_TIME 93 8769
TEST_TIME 94 8748
TEST_TIME 95 8758
TEST_TIME 96 8773
TEST_TIME 97 8753
TEST_TIME 98 8746
TEST_TIME 99 8807
TEST_TIME 100 8781
TEST_TIME 101 8874
TEST_TIME 102 8903
TEST_TIME 103 8868
TEST_TIME 104 8876
TEST_TIME 105 8895
TEST_TIME 106 8833
TEST_TIME 107 8809
TEST_TIME 108 8767
TEST_TIME 109 8782
TEST_TIME 110 8745
TEST_TIME 111 8719
TEST_TIME 112 8693
TEST_TIME 113 8669
TEST_TIME 114 8651
TEST_TIME 115 8653
TEST_TIME 116 8647
TEST_TIME 117 8655
TEST_TIME 118 8641
TEST_TIME 119 8633
TEST_TIME 120 8569

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-08-2021 13:38:24 to 25-08-2021 13:38:28.

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 1433288 1433288 0 0.00 0 45505 7 0 0
doc_list_biud 1433288 1433288 0 0.00 0 40461 32 0 0
sp_add_invoice_to_stock 4915 4800 115 2.33 29 197021 19413 0 115
sp_cancel_adding_invoice 5235 5235 0 0.00 6 82904 980 0 0
sp_cancel_client_order 1723 1723 0 0.00 4 8509 362 0 0
sp_cancel_customer_reserve 507533 507533 0 0.00 1 40463 70 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 6782 123 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 13092 89 0 0
sp_cancel_supplier_invoice 5788 5771 17 0.29 2 32988 1085 0 17
sp_cancel_supplier_order 381 381 0 0.00 11 1133485 118964 0 0
sp_cancel_write_off 9362 9362 0 0.00 1 14621 134 0 0
sp_client_order 20351 20351 0 0.00 4 42686 424 0 0
sp_customer_reserve 436752 436752 0 0.00 0 53835 194 0 0
sp_fill_shopping_cart 31040 31040 0 0.00 1 37942 52 0 0
sp_fill_shopping_cart_clo_res 436742 436742 0 0.00 0 14243 15 0 0
sp_get_clo_for_invoice 4789 4789 0 0.00 0 27044 398 0 0
sp_kill_cost_storno 15682 15682 0 0.00 0 13059 69 0 0
sp_kill_qstorno_ret_qs2qd 1030580 1030580 0 0.00 0 40456 38 0 0
sp_kill_qty_storno 544680 544680 0 0.00 0 40460 83 0 0
sp_lock_selected_doc 20316 20316 0 0.00 0 8493 5 0 0
sp_make_cost_storno 27434 27434 0 0.00 0 28864 68 0 0
sp_multiply_rows_for_pdistr 27434 27434 0 0.00 0 13950 35 0 0
sp_multiply_rows_for_qdistr 20351 20351 0 0.00 0 30161 219 0 0
sp_pay_from_customer 7034 7034 0 0.00 4 19294 129 0 0
sp_pay_to_supplier 5725 5725 0 0.00 5 23606 154 0 0
sp_payment_common 12759 12759 0 0.00 1 14232 29 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 8462 335 0 0
sp_qd_handle_on_invoice_upd_sts 10024 10024 0 0.00 0 16202 374 0 0
sp_qd_handle_on_reserve_upd_sts 9886 9886 0 0.00 0 20727 61 0 0
sp_reserve_write_off 9886 9886 0 0.00 6 20750 206 0 0
sp_supplier_invoice 5418 5418 0 0.00 7 118139 3901 0 0
sp_supplier_order 5279 5279 0 0.00 6 32724 1483 0 0
srv_aggregate_perf_data 114 114 0 0.00 2796 160408 14891 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1016 112307 43547 0 0
srv_find_qd_qs_mism 982070 982070 0 0.00 0 45503 6 0 0
srv_make_invnt_saldo 444 444 0 0.00 48 77206 3836 0 0
srv_make_money_saldo 709 709 0 0.00 1 19159 707 0 0
srv_recalc_idx_stat 4 4 0 0.00 235427 339293 280718 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4800 4800 0 0.00 0 10813 172 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 4972 97 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 12397 24 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 4349 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 3765 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 3288 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 11615 56 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 24857 1673 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 1183 4 0 0
t$perf-norm:sp_client_order 20351 20351 0 0.00 0 19882 21 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 1684 8 0 0
t$perf-norm:sp_pay_from_customer 7034 7034 0 0.00 0 17445 14 0 0
t$perf-norm:sp_pay_to_supplier 5725 5725 0 0.00 0 4269 9 0 0
t$perf-norm:sp_reserve_write_off 9886 9886 0 0.00 0 23956 14 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 3199 17 0 0
t$perf-norm:sp_supplier_order 5279 5279 0 0.00 0 2888 18 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 101 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 290 6 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 1995 20 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 694 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9804 9574 230 2.34 0 5560 17 0 230
v_all_customers 37052 37052 0 0.00 0 21 1 0 0
v_all_suppliers 29658 29658 0 0.00 0 22 1 0 0
v_all_wares 40702 40702 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2479 10 0 0
v_cancel_client_order 3446 3446 0 0.00 0 2885 5 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 3781 15 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 709 5 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 682 5 0 0
v_cancel_supplier_invoice 880 846 34 3.86 0 2531 19 0 34
v_cancel_supplier_order 762 762 0 0.00 0 556 4 0 0
v_cancel_write_off 2004 2004 0 0.00 0 2559 15 0 0
v_max_id_clo_ord 5278 5278 0 0.00 0 2352 2 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 6300 19 0 0
v_max_id_ord_sup 5411 5411 0 0.00 0 5083 5 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 2312 4 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 3094 6 0 0
v_min_id_clo_ord 5278 5278 0 0.00 0 4297 8 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 6051 20 0 0
v_min_id_ord_sup 5411 5411 0 0.00 0 5356 9 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 10019 18 0 0
v_min_non_paid_realizn 7034 7034 0 0.00 0 13451 19 0 0
v_our_firm 10292 10292 0 0.00 0 16 1 0 0
v_reserve_write_off 19772 19772 0 0.00 0 5529 6 0 0
x_lock_depdocs_on_canc_invoice 10583 10583 0 0.00 0 11988 117 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 3812 359 0 0
x_make_qstorno_1000_1200 5279 5279 0 0.00 2 32711 1396 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 4 106974 3696 0 0
x_make_qstorno_2100_3300 436752 436752 0 0.00 0 53831 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-08-2021 13:38:29 to 25-08-2021 13:38:33.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:11 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 30739 8192000000 9716357008 10016460800 796951728 8.865 100 84 168 2 825341216 7765712 20623776
11:09:50 33833 8192000000 9772539936 10084216832 843490160 9.335 100 86 172 0 877223584 12994432 20738992
11:11:30 18423 8192000000 9831476080 10155368448 891010624 9.809 100 82 164 0 930488896 21139184 18339088
11:12:56 13558 8192000000 9898805936 10235035648 933673248 10.231 100 87 174 1 1008103984 55850176 18580560
11:14:18 8259 8192000000 9932122224 10281295872 967573888 10.563 100 78 154 0 1050959488 67636528 15749072
11:15:28 4807 8192000000 9976171296 10333274112 999310368 10.872 100 80 160 1 1094148352 78922480 15915504
11:16:36 3329 8192000000 10012251744 10375376896 1022837536 11.099 100 88 175 1 1127215872 87042704 17335632
11:17:42 1985 8192000000 10003273968 10370519040 1040205376 11.267 100 85 170 1 1112715968 55535840 16974752
11:18:46 1729 8192000000 10031978544 10405138432 1059181584 11.449 100 84 167 0 1140091936 63916624 16993728
11:19:50 19051 8192000000 10027137072 10406789120 1066977552 11.523 100 78 154 0 1142156240 59269808 15908880
11:21:11 3574 8192000000 9999227984 10388824064 1076854688 11.617 100 39 78 1 1117014496 32483712 7676096
11:22:16 4584 8192000000 9999399040 10385096704 1079669552 11.644 100 51 101 0 1105229680 14052304 11507824
11:23:22 55753 8192000000 9984872208 10385420288 1082154384 11.668 100 35 68 1 1102201456 12738048 7309024
11:25:18 6337 8192000000 10016032112 10413731840 1086256592 11.707 100 39 78 1 1126662656 32323904 8082160
11:26:26 9275 8192000000 10014628352 10419048448 1092747072 11.769 100 36 69 2 1124738160 23958096 8032992
11:27:35 7451 8192000000 9991834960 10393198592 1062104512 11.477 98 32 65 1 1088787056 19679712 7002832
11:28:48 24835 8192000000 9969304592 10369744896 1040492624 11.269 97 32 62 1 1065152176 17997296 6662256
11:30:17 4667 8192000000 9967929760 10350022656 1027675312 11.146 98 51 100 0 1060326080 21978960 10671808
11:31:23 5136 8192000000 9942301568 10314780672 998465904 10.864 96 43 84 2 1023119536 14969184 9684448
11:32:29 14525 8192000000 9947238800 10326958080 1005676144 10.934 97 39 73 1 1034966944 21239200 8051600
11:34:05 24049 8192000000 9939239760 10336247808 1000282064 10.881 96 23 46 0 1024448080 19370160 4795856
11:35:31 5155 8192000000 9989567504 10389553152 1058438832 11.442 100 54 109 0 1101321872 30348960 12534080
11:36:38 7437 8192000000 9989625600 10389311488 1072695520 11.578 100 45 88 1 1090792784 8806608 9290656
11:37:51 9059 8192000000 10014018000 10409402368 1094741936 11.788 100 53 104 2 1125539840 20431584 10366320
11:39:07 106200 8192000000 10068612944 10479509504 1110841920 11.940 100 37 74 1 1192208896 72506816 8860160
11:42:06 22382 8192000000 10091360448 10494275584 1128210560 12.104 100 34 67 1 1206294304 70996304 7087440
11:43:32 1014 8192000000 10134876128 10536497152 1139193040 12.208 100 79 155 3 1238541360 83695984 15652336
11:44:46 7237 8192000000 10160099136 10567241728 1139816784 12.214 100 57 113 0 1257344880 105254816 12273280
11:45:58 5265 8192000000 10160662096 10571010048 1152426400 12.332 100 60 120 1 1265078016 100383376 12268240
11:47:07 11710 8192000000 10207789952 10622586880 1161032624 12.413 100 64 126 1 1311336064 135978480 14324960
11:48:29 1886 8192000000 10225746096 10635124736 1173769248 12.532 100 77 151 1 1328693616 137839472 17084896
11:49:33 6535 8192000000 10212347760 10618368000 1153909824 12.346 100 73 145 0 1312680336 143363104 15407408
11:50:42 5664 8192000000 10268424528 10677882880 1164837888 12.449 100 68 134 0 1371222736 191568368 14816480
11:51:53 1286 8192000000 10265195184 10685530112 1164363632 12.444 99 83 167 3 1365500288 182193888 18942768
11:52:59 1237 8192000000 10301739872 10711863296 1163947536 12.440 100 70 139 0 1407069744 226769408 16352800
11:54:08 5194 8192000000 10275403440 10690830336 1135636736 12.174 99 70 140 0 1373959328 221712768 16609824
11:55:19 1790 8192000000 10289881680 10716831744 1166251888 12.462 100 81 158 1 1387171520 203019792 17899840
11:56:26 5065 8192000000 10318106192 10732040192 1141672176 12.231 98 57 114 0 1411303296 256218784 13412336
11:57:54 3681 8192000000 10351044976 10748260352 1130917024 12.130 99 82 164 2 1436994496 287404272 18673200
11:59:03 4685 8192000000 10350515648 10764124160 1149246880 12.302 100 68 136 1 1447154544 282119600 15788064
12:00:22 4838 8192000000 10284685536 10730745856 1127404368 12.097 100 31 60 1 1383728944 248873696 7450880
12:01:33 23526 8192000000 10350151248 10797502464 1122416288 12.050 99 28 56 2 1425144400 295012864 7715248
12:03:15 17922 8192000000 10360217280 10792669184 1094530816 11.786 99 33 62 1 1445849088 342945568 8372704
12:04:45 8099 8192000000 10333459568 10774777856 1107264864 11.907 100 42 84 2 1426872944 309161056 10447024
12:05:56 17815 8192000000 10221696192 10730606592 1125762480 12.081 100 22 44 0 1379033632 247374576 5896576
12:07:20 33081 8192000000 10200424608 10621997056 1134466480 12.163 100 33 67 1 1293524240 151759664 7298096
12:08:55 39274 8192000000 10147285744 10655522816 1140530288 12.221 100 13 26 1 1269003840 125214640 3258912
12:10:36 50069 8192000000 10092352880 10525782016 1131188768 12.133 99 29 59 0 1178927904 41315792 6423344
12:12:57 95356 8192000000 10059739632 10516430848 1115489264 11.984 98 22 44 0 1179620160 59531456 4599440
12:16:07 112307 8192000000 10111280784 10569871360 1116975328 11.998 98 23 44 1 1160700752 38963664 4761760
12:19:01 96540 8192000000 10134467808 10590343168 1139588416 12.212 99 21 42 2 1222580800 77404432 5587952
12:21:38 31759 8192000000 10149708336 10581508096 1155844288 12.364 100 51 103 1 1246929840 80199568 10885984
12:23:11 27503 8192000000 10156851120 10601783296 1160230208 12.405 100 25 50 0 1255274208 88926320 6117680
12:24:40 49400 8192000000 10138709360 10673782784 1134538576 12.164 98 19 39 0 1254581584 115579680 4463328
12:26:30 49873 8192000000 10104582080 10600067072 1147296192 12.284 100 5 10 0 1207303840 59123712 883936
12:28:27 37270 8192000000 10136618048 10637504512 1147139456 12.283 100 4 8 1 1201898512 53873792 885264
12:31:34 95542 8192000000 10147168848 10648416256 1149445776 12.304 99 23 46 0 1251753968 97193120 5115072
12:34:10 81835 8192000000 10084698976 10526425088 1111425920 11.946 96 27 54 0 1142799488 25712464 5661104
12:36:32 38278 8192000000 10102948288 10566053888 1100915600 11.846 96 29 58 1 1175571808 68823264 5832944
12:38:21 61553 8192000000 10086589216 10562502656 1123130096 12.057 98 15 28 1 1191659008 65657904 2871008
12:40:29 110768 8192000000 10075086112 10553020416 1106796992 11.902 97 16 32 0 1143866944 33609232 3460720
12:43:48 4124 8192000000 10057210592 10508349440 1129778976 12.119 99 36 70 1 1160319200 23237504 7302720
12:44:54 57777 8192000000 10080273280 10542972928 1113993632 11.970 97 15 30 0 1156044288 38979296 3071360
12:46:52 58339 8192000000 10088210800 10558689280 1129632544 12.118 99 11 22 1 1195294704 63529184 2132976
12:48:53 55952 8192000000 10078573456 10525687808 1111474240 11.946 98 7 14 0 1168053088 54986800 1592048
12:50:56 46786 8192000000 10093523664 10566533120 1115401696 11.984 98 17 31 1 1206567360 87199232 3966432
12:52:46 82511 8192000000 10168118304 10642747392 1114156592 11.972 97 24 48 1 1235222928 115764608 5301728
12:55:25 88119 8192000000 10059852656 10513711104 1079147712 11.639 95 16 32 0 1113198832 30860144 3190976
12:57:53 46853 8192000000 10094994608 10532343808 1105320144 11.888 98 39 78 1 1160617040 47369936 7926960
12:59:49 83961 8192000000 10058534224 10600357888 1082895824 11.675 96 21 42 0 1192439744 104884752 4659168
13:02:18 89546 8192000000 10034081744 10500755456 1074194816 11.592 96 14 28 0 1111054096 33562336 3296944
13:04:48 26204 8192000000 10034142064 10486497280 1098601360 11.824 99 27 51 1 1123904176 19844336 5458480
13:06:17 57857 8192000000 10050119408 10523213824 1109494432 11.928 99 13 25 0 1162015392 49930128 2590832
13:08:17 85694 8192000000 10064570752 10559971328 1105453712 11.889 98 10 20 0 1166134672 58895216 1785744
13:10:44 45792 8192000000 10070440672 10512343040 1103944256 11.875 97 19 37 0 1136135840 28214704 3976880
13:12:33 76316 8192000000 10074636720 10532327424 1113228928 11.963 98 19 38 0 1170481712 52814128 4438656
13:14:54 67290 8192000000 10108719168 10550865920 1088494192 11.728 96 21 42 1 1182909280 89944320 4470768
13:17:01 38208 8192000000 10117966752 10558918656 1116649584 11.995 98 40 80 0 1165936640 40800192 8486864
13:18:39 61144 8192000000 10079143696 10598072320 1126325984 12.087 100 11 22 1 1201161408 72691008 2144416
13:20:47 37187 8192000000 10075599600 10524315648 1079048672 11.638 95 39 78 1 1158975952 72008624 7918656
13:22:27 37742 8192000000 10110309360 10562093056 1126881072 12.092 99 42 84 1 1180326720 45249920 8195728
13:24:14 88494 8192000000 10074137568 10521972736 1126670784 12.090 99 29 58 1 1170949728 38469936 5809008
13:26:43 63519 8192000000 10041654288 10506592256 1108429680 11.918 99 11 22 0 1142971392 32257776 2283936
13:28:46 51143 8192000000 10036258624 10496040960 1094484480 11.785 98 11 22 0 1117099808 20117216 2498112
13:31:53 54539 8192000000 10065331776 10526244864 1124126656 12.066 100 26 51 1 1170433744 40434512 5872576
13:33:58 41008 8192000000 10068152960 10536415232 1133433680 12.154 100 21 43 1 1192875200 54905760 4535760
13:35:39 58347 8192000000 10104933824 10561196032 1093815760 11.779 96 25 50 0 1173445584 74379984 5249840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-08-2021 13:38:35 to 25-08-2021 13:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-08-2021 13:38:51 to 25-08-2021 13:38:53.

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          227966
MON$OLDEST_ACTIVE               232855
MON$OLDEST_SNAPSHOT             232855
MON$NEXT_TRANSACTION            232855
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                       2039328
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:292: 
:293: mscs-test-161.budzdorov.ru    Wed Aug 25 13:37:14 2021
:294:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:295: 
:296: 
:297: mscs-test-161.budzdorov.ru    Wed Aug 25 13:37:15 2021
:298:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:299: 
:300: 
:301: mscs-test-161.budzdorov.ru    Wed Aug 25 13:37:20 2021
:302:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:303: 
--- end of diff output ---

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

25.08.21 13:38:54. 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
2021-08-25 13:38:56 No severe PSQL-related problems occured
25.08.21 13:38:58. End of report.