Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2021 10: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 15954908 kB
MemAvailable 30869532 kB
Buffers 0 kB
Cached 15788164 kB
SwapCached 2468 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-11-03 10:37:09
DTS_END                         2021-11-03 10:37:09.3680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44578
TRN_ID                          233107
ATT_ID                          668
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: 8594.25 25184 1031310
customer order: creation 167.48 380 20097
customer order: refuse 14.58 385 1750
order to supplier: creation 45.33 1407 5439
order to supplier: removal 3.38 108581 406
invoice (draft): creation 44.88 3974 5385
invoice (draft): removal 47.08 1060 5650
invoice accept: apply 40.90 19442 4908
invoice accept: cancel 42.68 1020 5122
customer reserve: creation 3708.28 194 444994
customer reserve: removal 4200.62 69 504074
realization accept: apply 82.34 222 9881
realization accept: cancel 70.49 132 8459
payment from customer: creation 57.82 126 6938
payment from customer: removal 5.84 83 701
payment to supplier: creation 48.40 124 5808
payment to supplier: removal 3.55 80 426
service: total inventory turnovers 3.62 4061 434
service: total monetary turnovers 6.01 609 721
service: aggregate perf. data 0.93 13932 112
service: refresh index statistics 0.04 347789 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-11-2021 10:38:41 to 03-11-2021 10:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7069
TEST_TIME 2 4906
TEST_TIME 3 4051
TEST_TIME 4 3801
TEST_TIME 5 4099
TEST_TIME 6 4445
TEST_TIME 7 4508
TEST_TIME 8 4547
TEST_TIME 9 4541
TEST_TIME 10 5042
TEST_TIME 11 5278
TEST_TIME 12 5566
TEST_TIME 13 5809
TEST_TIME 14 5842
TEST_TIME 15 6379
TEST_TIME 16 6750
TEST_TIME 17 7189
TEST_TIME 18 7492
TEST_TIME 19 7585
TEST_TIME 20 7844
TEST_TIME 21 7893
TEST_TIME 22 7971
TEST_TIME 23 8015
TEST_TIME 24 8274
TEST_TIME 25 8263
TEST_TIME 26 8725
TEST_TIME 27 8762
TEST_TIME 28 8703
TEST_TIME 29 8900
TEST_TIME 30 9089
TEST_TIME 31 9478
TEST_TIME 32 9899
TEST_TIME 33 9914
TEST_TIME 34 9907
TEST_TIME 35 9937
TEST_TIME 36 9821
TEST_TIME 37 9836
TEST_TIME 38 9931
TEST_TIME 39 9816
TEST_TIME 40 9634
TEST_TIME 41 9404
TEST_TIME 42 9358
TEST_TIME 43 9214
TEST_TIME 44 9190
TEST_TIME 45 9205
TEST_TIME 46 9322
TEST_TIME 47 9352
TEST_TIME 48 9229
TEST_TIME 49 9142
TEST_TIME 50 9158
TEST_TIME 51 9037
TEST_TIME 52 9105
TEST_TIME 53 9062
TEST_TIME 54 8964
TEST_TIME 55 8923
TEST_TIME 56 8871
TEST_TIME 57 8818
TEST_TIME 58 8860
TEST_TIME 59 8842
TEST_TIME 60 8826
TEST_TIME 61 8785
TEST_TIME 62 8839
TEST_TIME 63 8901
TEST_TIME 64 8877
TEST_TIME 65 8964
TEST_TIME 66 9024
TEST_TIME 67 9072
TEST_TIME 68 9099
TEST_TIME 69 9107
TEST_TIME 70 9115
TEST_TIME 71 9147
TEST_TIME 72 9213
TEST_TIME 73 9150
TEST_TIME 74 9050
TEST_TIME 75 9060
TEST_TIME 76 9064
TEST_TIME 77 9021
TEST_TIME 78 8990
TEST_TIME 79 8943
TEST_TIME 80 8924
TEST_TIME 81 8847
TEST_TIME 82 8859
TEST_TIME 83 8806
TEST_TIME 84 8772
TEST_TIME 85 8777
TEST_TIME 86 8803
TEST_TIME 87 8839
TEST_TIME 88 8838
TEST_TIME 89 8878
TEST_TIME 90 8856
TEST_TIME 91 8830
TEST_TIME 92 8891
TEST_TIME 93 8915
TEST_TIME 94 8982
TEST_TIME 95 8951
TEST_TIME 96 8867
TEST_TIME 97 8839
TEST_TIME 98 8797
TEST_TIME 99 8810
TEST_TIME 100 8812
TEST_TIME 101 8795
TEST_TIME 102 8772
TEST_TIME 103 8777
TEST_TIME 104 8762
TEST_TIME 105 8776
TEST_TIME 106 8754
TEST_TIME 107 8748
TEST_TIME 108 8878
TEST_TIME 109 8876
TEST_TIME 110 8858
TEST_TIME 111 8894
TEST_TIME 112 8842
TEST_TIME 113 8780
TEST_TIME 114 8789
TEST_TIME 115 8748
TEST_TIME 116 8726
TEST_TIME 117 8684
TEST_TIME 118 8678
TEST_TIME 119 8668
TEST_TIME 120 8597

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2021 10:38:44 to 03-11-2021 10:38:47.

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 1444549 1444549 0 0.00 0 26264 7 0 0
doc_list_biud 1444547 1444547 0 0.00 0 69732 31 0 0
sp_add_invoice_to_stock 5014 4908 106 2.11 33 208333 19443 0 106
sp_cancel_adding_invoice 5122 5122 0 0.00 5 108812 1021 0 0
sp_cancel_client_order 1750 1750 0 0.00 5 35794 386 0 0
sp_cancel_customer_reserve 504074 504074 0 0.00 1 69736 69 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 3556 83 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 4659 81 0 0
sp_cancel_supplier_invoice 5657 5650 7 0.12 2 63252 1060 0 7
sp_cancel_supplier_order 406 406 0 0.00 7 1087157 108581 0 0
sp_cancel_write_off 8459 8459 0 0.00 1 12642 133 0 0
sp_client_order 20097 20097 0 0.00 4 68505 380 0 0
sp_customer_reserve 444994 444994 0 0.00 0 51915 195 0 0
sp_fill_shopping_cart 30913 30913 0 0.00 1 20100 47 0 0
sp_fill_shopping_cart_clo_res 444990 444990 0 0.00 0 35027 17 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 36042 409 0 0
sp_kill_cost_storno 14708 14708 0 0.00 0 7792 71 0 0
sp_kill_qstorno_ret_qs2qd 1022663 1022663 0 0.00 0 69724 38 0 0
sp_kill_qty_storno 540244 540244 0 0.00 0 69732 83 0 0
sp_lock_selected_doc 20597 20597 0 0.00 0 8269 5 0 0
sp_make_cost_storno 27531 27531 0 0.00 0 11667 63 0 0
sp_multiply_rows_for_pdistr 27530 27530 0 0.00 0 12542 37 0 0
sp_multiply_rows_for_qdistr 20097 20097 0 0.00 0 68493 193 0 0
sp_pay_from_customer 6938 6938 0 0.00 4 12578 127 0 0
sp_pay_to_supplier 5808 5808 0 0.00 5 11988 124 0 0
sp_payment_common 12746 12746 0 0.00 1 7790 31 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 35757 356 0 0
sp_qd_handle_on_invoice_upd_sts 10026 10026 0 0.00 0 48799 403 0 0
sp_qd_handle_on_reserve_upd_sts 9879 9879 0 0.00 0 35563 83 0 0
sp_reserve_write_off 9881 9881 0 0.00 7 35645 222 0 0
sp_supplier_invoice 5385 5385 0 0.00 7 81919 3975 0 0
sp_supplier_order 5439 5439 0 0.00 5 63531 1408 0 0
srv_aggregate_perf_data 113 112 1 0.88 2969 116063 13932 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1418 147335 43028 0 0
srv_find_qd_qs_mism 985465 985465 0 0.00 0 26262 6 0 0
srv_make_invnt_saldo 434 434 0 0.00 94 116313 4061 0 0
srv_make_money_saldo 721 721 0 0.00 1 16518 610 0 0
srv_recalc_idx_stat 5 5 0 0.00 211445 545162 347789 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4909 4909 0 0.00 0 10036 191 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 7965 123 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 2273 14 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 2303 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 2966 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1152 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1623 22 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 24867 1788 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 4732 18 0 0
t$perf-norm:sp_client_order 20098 20098 0 0.00 0 10714 21 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 4030 18 0 0
t$perf-norm:sp_pay_from_customer 6938 6938 0 0.00 0 26338 16 0 0
t$perf-norm:sp_pay_to_supplier 5808 5808 0 0.00 0 12049 15 0 0
t$perf-norm:sp_reserve_write_off 9881 9881 0 0.00 0 13688 12 0 0
t$perf-norm:sp_supplier_invoice 5385 5385 0 0.00 0 14712 24 0 0
t$perf-norm:sp_supplier_order 5440 5440 0 0.00 0 5438 19 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 3708 53 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 413 8 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 2873 18 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 16522 39 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10018 9806 212 2.11 0 9767 17 0 212
v_all_customers 36668 36668 0 0.00 0 20 1 0 0
v_all_suppliers 29828 29828 0 0.00 0 18 1 0 0
v_all_wares 40194 40194 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 626 7 0 0
v_cancel_client_order 3500 3500 0 0.00 0 2728 6 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1343 7 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2798 7 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 504 4 0 0
v_cancel_supplier_invoice 842 828 14 1.66 0 991 16 0 14
v_cancel_supplier_order 812 812 0 0.00 0 1541 7 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2856 17 0 0
v_max_id_clo_ord 5439 5439 0 0.00 0 685 2 0 0
v_max_id_clo_res 994 994 0 0.00 0 2749 15 0 0
v_max_id_ord_sup 5376 5376 0 0.00 0 20090 9 0 0
v_max_non_paid_invoice 1709 1709 0 0.00 0 2511 4 0 0
v_max_non_paid_realizn 3782 3782 0 0.00 0 1002 3 0 0
v_min_id_clo_ord 5439 5439 0 0.00 0 3329 8 0 0
v_min_id_clo_res 994 994 0 0.00 0 2124 6 0 0
v_min_id_ord_sup 5376 5376 0 0.00 0 3708 7 0 0
v_min_non_paid_invoice 5808 5808 0 0.00 0 6940 19 0 0
v_min_non_paid_realizn 6938 6938 0 0.00 0 7786 28 0 0
v_our_firm 9838 9838 0 0.00 0 16 1 0 0
v_reserve_write_off 19758 19758 0 0.00 0 4170 6 0 0
x_lock_depdocs_on_canc_invoice 10358 10358 0 0.00 0 30628 132 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 10074 382 0 0
x_make_qstorno_1000_1200 5439 5439 0 0.00 2 63518 1314 0 0
x_make_qstorno_1200_2000 5385 5385 0 0.00 3 81856 3799 0 0
x_make_qstorno_2100_3300 444994 444994 0 0.00 0 50392 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2021 10:38:48 to 03-11-2021 10:38:52.

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
08:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 30156 8192000000 9719648240 10017689600 798818096 8.884 100 86 173 0 827199920 7486176 20895648
08:09:49 15198 8192000000 9763361840 10067652608 834837232 9.248 100 90 180 0 868141648 12230432 21073984
08:11:06 24177 8192000000 9788242464 10103857152 859269808 9.493 100 83 166 0 898169328 19871072 19028448
08:12:40 12902 8192000000 9859216512 10188304384 905617072 9.954 100 78 156 0 962442480 39613136 17212272
08:13:56 7571 8192000000 9903346240 10249080832 955754720 10.447 100 82 164 3 1021080400 48022816 17302864
08:15:08 4384 8192000000 9962688864 10317070336 992287248 10.804 100 87 175 0 1077331840 67084176 17960416
08:16:16 3418 8192000000 9997334144 10365710336 1027965632 11.149 100 91 180 2 1126638400 79580848 19091920
08:17:22 4776 8192000000 10016111152 10383527936 1041717584 11.281 100 86 172 1 1133119040 73818432 17583024
08:18:29 1537 8192000000 10050738464 10419060736 1066575408 11.519 100 94 189 0 1158545600 72534480 19435712
08:19:33 21020 8192000000 10017071904 10396680192 1073338880 11.584 100 71 143 0 1148621056 59809824 15472352
08:20:56 2435 8192000000 10010084144 10400448512 1080043104 11.648 100 62 124 2 1125321968 31472384 13806480
08:21:59 6413 8192000000 9994792304 10389921792 1087425648 11.718 100 24 48 0 1115779712 23095072 5258992
08:23:11 5409 8192000000 10011945184 10412609536 1101312160 11.850 100 34 72 1 1135190480 26634096 7244224
08:24:17 21984 8192000000 10021085920 10413002752 1104577808 11.881 100 56 111 1 1132693520 15867760 12247952
08:25:39 5973 8192000000 10038770912 10440773632 1108387344 11.917 100 44 87 0 1149583664 32756464 8439856
08:26:48 8037 8192000000 10031504512 10438660096 1107454368 11.908 100 49 99 1 1137593040 18823456 11315216
08:27:57 11449 8192000000 10015394960 10431299584 1081492160 11.662 98 27 55 2 1112229568 24734592 6002816
08:29:13 23421 8192000000 9964420864 10367852544 973624960 10.622 89 15 30 0 1002178976 26001296 2552720
08:30:36 5692 8192000000 9940749104 10320674816 991784416 10.799 95 40 77 0 1033302848 32705440 8812992
08:31:44 7403 8192000000 9937806960 10322227200 1003455936 10.912 98 46 89 3 1037169440 23146256 10567248
08:32:53 39836 8192000000 9946861232 10326327296 1020781808 11.080 99 69 140 0 1045963824 9933520 15248496
08:34:42 5443 8192000000 9964579872 10362273792 1046431856 11.326 100 28 56 0 1081484640 29069120 5983664
08:35:48 7263 8192000000 9966361664 10358190080 1047130896 11.333 99 31 60 1 1071408496 17230448 7047152
08:36:58 9279 8192000000 9994596672 10396880896 1069840688 11.551 100 25 50 2 1098393072 23125824 5426560
08:38:16 56390 8192000000 10027856928 10424467456 1098991200 11.828 100 50 101 0 1153939792 43936512 11012080
08:40:33 35106 8192000000 10066237936 10482712576 1114927648 11.979 100 31 61 0 1171789040 50838640 6022752
08:42:15 26833 8192000000 10105637104 10529050624 1131381520 12.134 100 36 72 0 1224615328 85697424 7536384
08:43:47 22626 8192000000 10137009280 10544472064 1143356064 12.247 100 52 101 0 1246809840 92417952 11035824
08:45:13 26040 8192000000 10170753072 10584006656 1151982480 12.328 100 39 78 0 1286607360 125550688 9074192
08:46:46 7403 8192000000 10236504976 10662146048 1162612896 12.428 100 69 138 0 1340167424 161934912 15619616
08:48:01 1418 8192000000 10245949840 10647613440 1170420432 12.501 100 75 149 0 1349908960 162540288 16948240
08:49:08 1823 8192000000 10305803792 10710351872 1179739088 12.588 100 86 167 0 1410189216 212019536 18430592
08:50:12 5015 8192000000 10286145504 10710605824 1185384064 12.640 100 61 121 1 1381514272 182016192 14114016
08:51:26 2793 8192000000 10323157088 10730024960 1179672864 12.587 100 84 168 0 1424585760 225626448 19286448
08:52:34 3736 8192000000 10347913408 10756698112 1165659728 12.456 100 82 163 1 1444910768 260732928 18518112
08:53:44 2931 8192000000 10337440112 10744913920 1160142416 12.405 99 82 159 2 1422497536 244956960 17398160
08:54:52 2516 8192000000 10232197600 10655510528 1133506384 12.154 98 66 131 1 1332257408 183187712 15563312
08:56:00 3726 8192000000 10273844864 10672017408 1131588976 12.136 99 88 176 2 1368553952 217700128 19264848
08:57:09 1858 8192000000 10247939984 10639130624 1123133616 12.057 99 86 167 1 1344914608 202155120 19625872
08:58:18 17475 8192000000 10244911168 10665910272 1135712192 12.175 100 41 82 0 1338078352 192525328 9840832
08:59:45 2073 8192000000 10244349840 10638082048 1124358912 12.068 100 84 168 1 1339717488 196112080 19246496
09:00:53 4385 8192000000 10261804768 10678444032 1127614736 12.099 100 35 70 1 1360899024 224778896 8505392
09:02:04 17075 8192000000 10253626160 10683072512 1123534112 12.060 100 24 48 2 1354738752 225596368 5608272
09:03:25 7174 8192000000 10227425904 10671935488 1131386384 12.134 100 37 74 1 1352549216 211903424 9259408
09:04:59 12792 8192000000 10255127600 10676166656 1128139136 12.104 100 31 62 1 1356072128 220576736 7356256
09:06:14 22553 8192000000 10267624432 10703458304 1123047440 12.056 99 25 50 0 1350844448 221244672 6552336
09:07:39 34018 8192000000 10209495824 10682331136 1122486928 12.050 100 26 52 0 1328813680 200240512 6086240
09:09:20 25868 8192000000 10073474032 10515693568 1121924128 12.045 100 15 30 1 1197154576 71868816 3361632
09:11:08 66137 8192000000 10096769248 10551586816 1126169776 12.085 99 41 79 3 1185938256 50718864 9049616
09:13:20 74687 8192000000 10110721456 10555576320 1115279968 11.982 97 17 34 0 1191597200 72731712 3585520
09:15:37 120307 8192000000 10071931712 10506825728 1111841008 11.950 97 19 38 0 1143826240 28234112 3751120
09:18:46 33620 8192000000 10132318736 10549542912 1150125792 12.311 100 55 111 0 1200036112 38320608 11589712
09:20:21 52665 8192000000 10182335216 10643034112 1146990208 12.281 99 28 55 0 1260631696 107671424 5970064
09:22:16 59119 8192000000 10163078672 10673410048 1163715424 12.438 100 17 34 0 1285725712 118145152 3865136
09:24:23 32012 8192000000 10080384928 10552692736 1157778896 12.382 100 7 14 0 1197698688 38473104 1446688
09:25:55 34762 8192000000 10129429280 10586533888 1145533360 12.268 99 28 56 0 1218647632 66810976 6303296
09:27:31 47551 8192000000 10121633488 10610159616 1139064496 12.207 98 8 16 0 1210267152 69535456 1667200
09:29:20 48734 8192000000 10114693616 10604027904 1160104848 12.404 100 14 28 0 1225499616 62579824 2814944
09:31:17 31092 8192000000 10096910256 10543579136 1143357936 12.247 98 33 67 0 1180687824 30332528 6997360
09:32:56 64167 8192000000 10118408560 10605342720 1167402592 12.473 100 18 36 1 1234509632 63271728 3835312
09:35:23 86353 8192000000 10096830832 10568298496 1160952096 12.412 100 11 22 0 1231623984 68035440 2636448
09:37:50 52366 8192000000 10103922016 10552926208 1131711552 12.137 97 18 36 0 1178665136 43147856 3805728
09:39:43 50294 8192000000 10090914400 10570440704 1142694240 12.241 100 28 56 0 1233173744 84898496 5581008
09:41:38 42573 8192000000 10085949104 10554040320 1124352736 12.068 99 27 54 0 1214374736 84470848 5551152
09:43:22 75872 8192000000 10102006960 10558926848 1047656240 11.338 92 10 20 1 1128250720 78807232 1787248
09:45:48 36284 8192000000 10065246608 10505125888 1091528768 11.757 97 27 54 1 1139314560 42254112 5531680
09:47:24 18282 8192000000 10062573248 10488176640 1102673968 11.863 99 59 117 1 1155852832 39618656 13560208
09:48:47 31430 8192000000 10090605440 10548199424 1115587600 11.985 99 30 60 0 1195701104 74100144 6013360
09:50:28 134145 8192000000 10089117360 10544869376 1108246464 11.916 98 21 42 0 1155961168 42913520 4801184
09:53:45 18443 8192000000 10069413040 10548432896 1127853696 12.101 100 23 46 0 1172978992 40667296 4458000
09:55:09 30664 8192000000 10079916352 10517651456 1105400768 11.889 98 21 42 0 1148139216 38555648 4182800
09:56:39 57927 8192000000 10078713088 10558500864 1110065504 11.933 98 9 18 0 1171215776 58744288 2405984
09:58:37 32779 8192000000 10069873120 10520145920 1127357680 12.096 100 12 24 0 1176695088 46896048 2441360
10:01:41 71762 8192000000 10109302944 10583339008 1106019536 11.895 97 18 36 0 1190978944 80985536 3973872
10:04:12 36584 8192000000 10083361936 10519064576 1123697456 12.062 100 39 78 2 1171317856 39838320 7782080
10:05:51 81453 8192000000 10052025568 10479120384 1050740992 11.368 93 21 42 0 1103885744 48156688 4988064
10:08:13 44448 8192000000 10125506320 10572177408 1130891504 12.130 100 36 72 0 1228853408 90298992 7662912
10:10:06 62511 8192000000 10155764080 10635567104 1144798064 12.261 100 18 36 0 1229723968 81349616 3576288
10:12:10 147334 8192000000 10080190832 10529423360 1136670592 12.184 99 26 51 1 1161575296 20183120 4721584
10:15:51 64607 8192000000 10092015744 10588008448 1142936288 12.243 99 9 18 0 1200339168 55418864 1984016
10:17:57 94585 8192000000 10078500304 10523643904 1086006656 11.705 94 27 54 1 1128536448 36666368 5863424
10:20:32 22267 8192000000 10082265920 10524016640 1137403728 12.191 99 41 82 1 1179739280 34247024 8088528
10:22:02 113645 8192000000 10131561408 10587164672 1080047104 11.648 94 20 40 0 1138958336 54966784 3944448
10:25:32 86803 8192000000 10041772000 10480668672 1068081296 11.534 94 10 20 1 1153036320 82751968 2203056
10:28:10 114119 8192000000 10081748272 10573443072 1109251568 11.925 98 23 46 0 1192199248 78539200 4408480
10:31:05 46596 8192000000 10074484544 10554699776 1113331824 11.964 98 22 44 2 1154863152 37202656 4328672
10:32:55 82593 8192000000 10061650064 10515791872 1101350048 11.850 97 14 28 1 1132712848 27744272 3618528
10:35:18 30656 8192000000 10043115136 10503864320 1092231072 11.764 98 33 63 0 1120398416 21633632 6533712
10:36:55 20900 8192000000 9137462720 9418682368 285518656 3.367 26 2 4 0 300682928 15085008 79264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-11-2021 10:38:54 to 03-11-2021 10:39:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 106 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-11-2021 10:39:10 to 03-11-2021 10:39:12.

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          233410
MON$OLDEST_ACTIVE               233411
MON$OLDEST_SNAPSHOT             233411
MON$NEXT_TRANSACTION            233411
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                       2054624
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2650 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2650 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2650 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2650 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

03.11.21 10:39:13. 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-11-03 10:39:15 No severe PSQL-related problems occured
03.11.21 10:39:17. End of report.