Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.01.2021 10:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16675512 kB
MemAvailable 31212184 kB
Buffers 0 kB
Cached 14873832 kB
SwapCached 4864 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.0
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-01-16 10:26:54
DTS_END                         2021-01-16 10:26:54.9860
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/53054
TRN_ID                          229015
ATT_ID                          663
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: 8565.32 25566 1027838
customer order: creation 163.22 437 19586
customer order: refuse 14.15 381 1698
order to supplier: creation 42.95 1347 5154
order to supplier: removal 3.00 131108 360
invoice (draft): creation 44.15 3772 5298
invoice (draft): removal 50.27 986 6032
invoice accept: apply 39.13 19006 4696
invoice accept: cancel 44.95 1008 5394
customer reserve: creation 3543.16 188 425179
customer reserve: removal 4333.63 72 520035
realization accept: apply 79.63 216 9555
realization accept: cancel 83.30 125 9996
payment from customer: creation 57.43 143 6891
payment from customer: removal 5.23 136 628
payment to supplier: creation 46.99 144 5639
payment to supplier: removal 3.70 121 444
service: total inventory turnovers 3.36 4532 403
service: total monetary turnovers 6.24 686 749
service: aggregate perf. data 0.80 18898 96
service: refresh index statistics 0.04 328016 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-01-2021 10:27:48 to 16-01-2021 10:27:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2203
TEST_TIME 2 3709
TEST_TIME 3 3640
TEST_TIME 4 4421
TEST_TIME 5 4121
TEST_TIME 6 4280
TEST_TIME 7 4986
TEST_TIME 8 5126
TEST_TIME 9 5317
TEST_TIME 10 5555
TEST_TIME 11 5905
TEST_TIME 12 5923
TEST_TIME 13 5941
TEST_TIME 14 5959
TEST_TIME 15 6144
TEST_TIME 16 6588
TEST_TIME 17 6738
TEST_TIME 18 6809
TEST_TIME 19 7259
TEST_TIME 20 7258
TEST_TIME 21 7553
TEST_TIME 22 7564
TEST_TIME 23 7813
TEST_TIME 24 7776
TEST_TIME 25 8341
TEST_TIME 26 8639
TEST_TIME 27 8664
TEST_TIME 28 8730
TEST_TIME 29 9521
TEST_TIME 30 10096
TEST_TIME 31 10089
TEST_TIME 32 9992
TEST_TIME 33 10098
TEST_TIME 34 10042
TEST_TIME 35 9979
TEST_TIME 36 9732
TEST_TIME 37 9719
TEST_TIME 38 9543
TEST_TIME 39 9489
TEST_TIME 40 9460
TEST_TIME 41 9365
TEST_TIME 42 9242
TEST_TIME 43 9167
TEST_TIME 44 9041
TEST_TIME 45 9039
TEST_TIME 46 8971
TEST_TIME 47 8911
TEST_TIME 48 8749
TEST_TIME 49 8697
TEST_TIME 50 8778
TEST_TIME 51 8779
TEST_TIME 52 8750
TEST_TIME 53 8698
TEST_TIME 54 8747
TEST_TIME 55 8719
TEST_TIME 56 8724
TEST_TIME 57 8747
TEST_TIME 58 8762
TEST_TIME 59 8793
TEST_TIME 60 8810
TEST_TIME 61 8818
TEST_TIME 62 8845
TEST_TIME 63 8879
TEST_TIME 64 8805
TEST_TIME 65 8684
TEST_TIME 66 8675
TEST_TIME 67 8588
TEST_TIME 68 8577
TEST_TIME 69 8529
TEST_TIME 70 8514
TEST_TIME 71 8587
TEST_TIME 72 8664
TEST_TIME 73 8629
TEST_TIME 74 8609
TEST_TIME 75 8619
TEST_TIME 76 8636
TEST_TIME 77 8700
TEST_TIME 78 8853
TEST_TIME 79 8852
TEST_TIME 80 8852
TEST_TIME 81 8859
TEST_TIME 82 8953
TEST_TIME 83 8935
TEST_TIME 84 8857
TEST_TIME 85 8770
TEST_TIME 86 8735
TEST_TIME 87 8668
TEST_TIME 88 8636
TEST_TIME 89 8628
TEST_TIME 90 8601
TEST_TIME 91 8645
TEST_TIME 92 8628
TEST_TIME 93 8615
TEST_TIME 94 8623
TEST_TIME 95 8603
TEST_TIME 96 8606
TEST_TIME 97 8606
TEST_TIME 98 8588
TEST_TIME 99 8689
TEST_TIME 100 8734
TEST_TIME 101 8707
TEST_TIME 102 8705
TEST_TIME 103 8765
TEST_TIME 104 8726
TEST_TIME 105 8773
TEST_TIME 106 8698
TEST_TIME 107 8671
TEST_TIME 108 8650
TEST_TIME 109 8604
TEST_TIME 110 8562
TEST_TIME 111 8546
TEST_TIME 112 8525
TEST_TIME 113 8544
TEST_TIME 114 8517
TEST_TIME 115 8501
TEST_TIME 116 8513
TEST_TIME 117 8619
TEST_TIME 118 8601
TEST_TIME 119 8587
TEST_TIME 120 8567

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-01-2021 10:27:51 to 16-01-2021 10:27:55.

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 1421381 1421381 0 0.00 0 26088 7 0 0
doc_list_biud 1421380 1421380 0 0.00 0 35205 34 0 0
sp_add_invoice_to_stock 4780 4696 84 1.75 41 195143 19007 0 84
sp_cancel_adding_invoice 5394 5394 0 0.00 5 61396 1009 0 0
sp_cancel_client_order 1698 1698 0 0.00 5 11667 382 0 0
sp_cancel_customer_reserve 520035 520035 0 0.00 1 35209 72 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 3 13504 137 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 5094 121 0 0
sp_cancel_supplier_invoice 6037 6032 5 0.08 2 33212 986 0 5
sp_cancel_supplier_order 360 360 0 0.00 12 1222514 131109 0 0
sp_cancel_write_off 9996 9996 0 0.00 1 23986 125 0 0
sp_client_order 19586 19586 0 0.00 4 36829 437 0 0
sp_customer_reserve 425179 425179 0 0.00 0 40511 189 0 0
sp_fill_shopping_cart 30030 30030 0 0.00 1 24980 51 0 0
sp_fill_shopping_cart_clo_res 425173 425173 0 0.00 0 19771 17 0 0
sp_get_clo_for_invoice 4690 4690 0 0.00 0 29071 485 0 0
sp_kill_cost_storno 16462 16462 0 0.00 0 26075 71 0 0
sp_kill_qstorno_ret_qs2qd 1056458 1056458 0 0.00 0 35114 39 0 0
sp_kill_qty_storno 557760 557760 0 0.00 0 35205 85 0 0
sp_lock_selected_doc 19786 19786 0 0.00 0 7537 6 0 0
sp_make_cost_storno 26775 26775 0 0.00 0 15401 66 0 0
sp_multiply_rows_for_pdistr 26774 26774 0 0.00 0 13490 34 0 0
sp_multiply_rows_for_qdistr 19586 19586 0 0.00 0 36813 232 0 0
sp_pay_from_customer 6891 6891 0 0.00 4 25559 144 0 0
sp_pay_to_supplier 5639 5639 0 0.00 5 18470 145 0 0
sp_payment_common 12529 12529 0 0.00 1 14122 28 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 11660 341 0 0
sp_qd_handle_on_invoice_upd_sts 10084 10084 0 0.00 0 28370 436 0 0
sp_qd_handle_on_reserve_upd_sts 9555 9555 0 0.00 0 15819 67 0 0
sp_reserve_write_off 9555 9555 0 0.00 7 18057 216 0 0
sp_supplier_invoice 5298 5298 0 0.00 7 102033 3772 0 0
sp_supplier_order 5154 5154 0 0.00 6 21965 1348 0 0
srv_aggregate_perf_data 96 96 0 0.00 2539 289538 18899 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1726 130364 45571 0 0
srv_find_qd_qs_mism 982775 982775 0 0.00 0 10337 6 0 0
srv_make_invnt_saldo 403 403 0 0.00 54 126535 4532 0 0
srv_make_money_saldo 749 749 0 0.00 1 19145 687 0 0
srv_recalc_idx_stat 5 5 0 0.00 63362 545648 328017 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4696 4696 0 0.00 0 17123 187 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 2072 86 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 13141 21 0 0
t$perf-norm:sp_cancel_customer_reserve 616 616 0 0.00 0 966 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 503 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 345 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 2356 27 0 0
t$perf-norm:sp_cancel_supplier_order 360 360 0 0.00 0 23198 2051 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 12365 19 0 0
t$perf-norm:sp_client_order 19586 19586 0 0.00 0 23640 21 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 3680 10 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 4949 9 0 0
t$perf-norm:sp_pay_to_supplier 5639 5639 0 0.00 0 5380 9 0 0
t$perf-norm:sp_reserve_write_off 9555 9555 0 0.00 0 6101 10 0 0
t$perf-norm:sp_supplier_invoice 5298 5298 0 0.00 0 3402 16 0 0
t$perf-norm:sp_supplier_order 5154 5154 0 0.00 0 23404 21 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 277 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 224 10 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 3064 29 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 1683 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 10 6 0 0
v_add_invoice_to_stock 9546 9378 168 1.75 0 5210 19 0 168
v_all_customers 36352 36352 0 0.00 0 16 1 0 0
v_all_suppliers 28828 28828 0 0.00 0 19 1 0 0
v_all_wares 39170 39170 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 869 8 0 0
v_cancel_client_order 3396 3396 0 0.00 0 4882 9 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 5765 13 0 0
v_cancel_customer_reserve 1232 1232 0 0.00 0 2656 9 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 4180 24 0 0
v_cancel_supplier_invoice 864 854 10 1.15 0 2971 22 0 10
v_cancel_supplier_order 720 720 0 0.00 0 1900 5 0 0
v_cancel_write_off 1902 1902 0 0.00 0 23750 28 0 0
v_max_id_clo_ord 5150 5150 0 0.00 0 397 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 2602 19 0 0
v_max_id_ord_sup 5294 5294 0 0.00 0 7162 5 0 0
v_max_non_paid_invoice 1668 1668 0 0.00 0 967 3 0 0
v_max_non_paid_realizn 3428 3428 0 0.00 0 9630 6 0 0
v_min_id_clo_ord 5150 5150 0 0.00 0 4185 6 0 0
v_min_id_clo_res 979 979 0 0.00 0 2745 10 0 0
v_min_id_ord_sup 5294 5294 0 0.00 0 7751 9 0 0
v_min_non_paid_invoice 5638 5638 0 0.00 0 14094 20 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 13449 23 0 0
v_our_firm 9744 9744 0 0.00 0 12 1 0 0
v_reserve_write_off 19110 19110 0 0.00 0 5448 9 0 0
x_lock_depdocs_on_canc_invoice 10999 10999 0 0.00 0 14167 121 0 0
x_lock_depdocs_on_canc_sup_ord 360 360 0 0.00 0 9483 475 0 0
x_make_qstorno_1000_1200 5154 5154 0 0.00 2 21918 1254 0 0
x_make_qstorno_1200_2000 5298 5298 0 0.00 3 91089 3577 0 0
x_make_qstorno_2100_3300 425179 425179 0 0.00 0 40510 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-01-2021 10:27:56 to 16-01-2021 10:28:00.

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
07:56:58 9 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
07:57:59 27111 8192000000 9730943344 10032562176 809153488 8.989 100 84 168 0 841952496 12755136 20043872
07:59:35 24811 8192000000 9785988592 10096549888 853954672 9.440 100 85 170 1 887146720 13238464 19953584
08:01:07 14021 8192000000 9807366800 10130612224 875589088 9.656 100 78 156 0 915304352 21370624 18344640
08:02:24 18579 8192000000 9857900368 10192592896 910580560 10.003 100 71 142 1 972853856 46215888 16057408
08:03:46 7254 8192000000 9912986624 10256171008 956431504 10.454 100 76 152 0 1025480000 53507344 15541152
08:04:57 8939 8192000000 9965774416 10318491648 996023072 10.840 100 82 163 1 1079297072 66493120 16780880
08:06:08 2744 8192000000 9997429456 10357506048 1022797072 11.099 100 88 176 2 1109297680 68503984 17996624
08:07:13 2061 8192000000 10036624400 10403516416 1040025120 11.265 100 83 164 2 1143030976 86349008 16656848
08:08:16 1983 8192000000 10035203328 10403016704 1066755056 11.521 100 96 196 1 1150536240 63475328 20305856
08:09:18 23866 8192000000 9999110528 10399342592 1071656976 11.568 100 43 86 2 1130604368 50386032 8561360
08:10:49 6677 8192000000 10011516768 10405019648 1088057840 11.724 100 45 89 1 1124261104 26994672 9208592
08:11:56 6771 8192000000 10009231872 10409639936 1092850704 11.770 100 34 68 2 1128827184 28981904 6994576
08:13:06 8271 8192000000 10001059184 10407825408 1095698656 11.797 100 30 58 2 1129814528 27319136 6796736
08:14:18 23312 8192000000 10032172496 10417942528 1101544528 11.852 100 70 142 3 1129294064 12179472 15570064
08:15:43 6908 8192000000 10028966176 10440355840 1101683824 11.854 100 30 59 0 1140128928 31783120 6661984
08:16:52 9361 8192000000 9998300080 10405924864 1075035808 11.600 98 36 69 2 1098969680 15962112 7971760
08:18:07 6962 8192000000 10001121776 10405867520 1074146144 11.592 100 35 71 0 1122720272 40964432 7609696
08:19:14 35069 8192000000 9955840240 10347212800 966178512 10.549 90 33 65 2 993420016 19904864 7336640
08:20:49 2416 8192000000 9943346496 10322681856 1031979360 11.188 99 43 81 1 1047946128 4491376 11475392
08:21:56 10153 8192000000 9937633744 10318340096 995892848 10.839 97 31 62 1 1021198256 16961136 8344272
08:23:07 68661 8192000000 9943504864 10347769856 1041014112 11.274 100 25 50 1 1073608880 27667184 4927584
08:25:18 9065 8192000000 9970195024 10371162112 1053953232 11.399 100 33 64 1 1079791072 18225504 7612336
08:26:27 7458 8192000000 10006771840 10403028992 1084035296 11.686 100 30 61 1 1113147792 22478448 6634048
08:27:38 24132 8192000000 10029114448 10420940800 1095035456 11.791 100 53 107 0 1133459664 27279696 11144512
08:29:10 64028 8192000000 10081377264 10501382144 1120251920 12.029 100 43 86 0 1206620688 76335472 10033296
08:31:38 16532 8192000000 10126571184 10531790848 1132042592 12.141 100 42 84 1 1222303056 81074592 9185872
08:33:00 18253 8192000000 10161282048 10578386944 1138675360 12.203 100 41 80 1 1268668592 121677344 8315888
08:34:23 20602 8192000000 10177219840 10600828928 1148763360 12.298 100 38 76 0 1277734160 120539744 8431056
08:35:50 7205 8192000000 10182405200 10595377152 1157118080 12.376 100 50 96 1 1292033008 124156384 10758544
08:37:05 4719 8192000000 10204987296 10633568256 1172080688 12.516 100 63 124 1 1328103888 141715120 14308080
08:38:13 4123 8192000000 10226355552 10636546048 1149454592 12.304 98 68 135 0 1319679200 155975808 14248800
08:39:22 4954 8192000000 10266178320 10675154944 1161908256 12.421 99 67 134 0 1360876352 184066848 14901248
08:40:35 1724 8192000000 10291488496 10688417792 1178155152 12.573 100 84 167 0 1386634496 189545920 18933424
08:41:42 6647 8192000000 10320743808 10725888000 1172714224 12.522 100 76 152 1 1421483264 231038048 17730992
08:42:54 5259 8192000000 10305777232 10712973312 1141329120 12.228 99 72 140 0 1403067360 246211744 15526496
08:44:04 2423 8192000000 10350649152 10749702144 1152534096 12.333 99 84 168 0 1446178976 274561024 19083856
08:45:12 12412 8192000000 10372839392 10800357376 1159615664 12.400 100 69 137 2 1459876656 284427376 15833616
08:46:32 1731 8192000000 10379109408 10782195712 1164090208 12.442 100 79 159 2 1477192016 295222400 17879408
08:47:38 13724 8192000000 10389177248 10811211776 1164010000 12.441 100 47 94 2 1491670640 315822208 11838432
08:49:02 13416 8192000000 10420381520 10841128960 1157587680 12.381 100 45 90 1 1515804192 346029120 12187392
08:50:19 5207 8192000000 10430615136 10842501120 1161190528 12.414 100 61 122 1 1528624208 352427808 15005872
08:51:31 9163 8192000000 10404882576 10855120896 1147674304 12.288 99 47 94 1 1542198480 382395024 12129152
08:52:49 13741 8192000000 10348213344 10797539328 1158197120 12.386 100 36 72 1 1480046592 313058976 8790496
08:54:09 8937 8192000000 10388128432 10853830656 1139069504 12.207 100 29 59 1 1491586080 345048592 7467984
08:55:25 34771 8192000000 10237366848 10741002240 1099105296 11.829 98 23 45 1 1448558816 343256048 6197472
08:57:02 14923 8192000000 10129919008 10588483584 1130356976 12.125 99 24 48 1 1248379952 112790416 5232560
08:59:01 10939 8192000000 10153041264 10581368832 1145734624 12.269 100 60 119 1 1248492800 90536016 12222160
09:00:26 40406 8192000000 10120203280 10590793728 1114297568 11.973 98 10 20 0 1207694272 91399088 1997616
09:02:12 51732 8192000000 10121523136 10597298176 1136078064 12.179 100 22 43 0 1211219936 70615808 4526064
09:04:04 27568 8192000000 10082821344 10548604928 1110859488 11.941 98 14 28 0 1171770384 57979664 2931232
09:05:36 69908 8192000000 10044294976 10487480320 1128316496 12.105 100 30 59 0 1186837232 52376848 6143888
09:08:00 75983 8192000000 10075658848 10507812864 1094601088 11.786 96 22 44 3 1129654992 30000224 5053680
09:10:16 52056 8192000000 10118279696 10558812160 1097279568 11.812 97 18 36 0 1184180736 82488672 4412496
09:12:12 50242 8192000000 10100350496 10537160704 1124243376 12.067 100 17 33 0 1201304784 73473792 3587616
09:14:04 130364 8192000000 10154626496 10628775936 1141669248 12.231 100 28 56 1 1260451504 112631136 6151120
09:17:25 77697 8192000000 10077985008 10552274944 1116385200 11.993 98 8 16 0 1177271840 59353216 1533424
09:19:46 88221 8192000000 10106378432 10574548992 1097130800 11.810 96 14 28 0 1167617168 66895824 3590544
09:22:15 78622 8192000000 10114078048 10559852544 1118481840 12.013 99 15 30 0 1170758288 49139520 3136928
09:24:50 38811 8192000000 10099717728 10560126976 1129302240 12.115 100 48 96 4 1179192384 40307872 9582272
09:26:38 53941 8192000000 10114222672 10565128192 1132176784 12.142 99 18 36 1 1183310928 47629952 3504192
09:28:34 78818 8192000000 10074805760 10535964672 1082942192 11.675 94 16 32 1 1133471936 47565840 2963904
09:30:54 83970 8192000000 10073385296 10513489920 1122008672 12.046 99 17 34 1 1174621200 48726048 3886480
09:33:22 51320 8192000000 10073647920 10545037312 1125521840 12.079 100 14 28 0 1177533280 48881184 3130256
09:35:14 37731 8192000000 10107305824 10594177024 1136826256 12.186 100 10 19 0 1186622400 48023744 1772400
09:36:53 92192 8192000000 10104464240 10650161152 1117705600 12.005 97 19 38 1 1213157184 91118848 4332736
09:39:50 55584 8192000000 10121697632 10573684736 1126047392 12.084 98 18 36 2 1186995680 57157984 3790304
09:41:49 75337 8192000000 10181093008 10637819904 1111133088 11.943 98 20 40 0 1271369952 155583280 4653584
09:44:05 72103 8192000000 10066619536 10522730496 1091525616 11.757 97 11 22 1 1140040688 46343376 2171696
09:46:27 60685 8192000000 10109072672 10586406912 1128956768 12.112 99 20 40 0 1199579280 66738880 3883632
09:48:39 125860 8192000000 10069984384 10538106880 1102052784 11.857 97 9 18 0 1151955184 48139168 1763232
09:51:57 63319 8192000000 10071202640 10526703616 1108894288 11.922 98 29 57 1 1152519072 37594528 6030256
09:54:01 32266 8192000000 10113632480 10580287488 1122710672 12.053 99 6 12 0 1205154160 81202448 1241040
09:55:33 65538 8192000000 10105862368 10572283904 1109067984 11.924 98 23 46 2 1193713568 79232320 5413264
09:57:54 59317 8192000000 10051155360 10513215488 1103762048 11.873 98 20 40 0 1149349616 41430688 4156880
09:59:56 85901 8192000000 10077962688 10554929152 1092213136 11.764 96 16 32 1 1131899392 35966432 3719824
10:02:28 55991 8192000000 10118301952 10591039488 1119294272 12.020 98 21 42 0 1212785744 89345088 4146384
10:04:28 76495 8192000000 10077716016 10541682688 1102146912 11.858 97 14 28 0 1218695152 113382560 3165680
10:06:49 38161 8192000000 10084597184 10517082112 1140660832 12.222 100 27 53 1 1180823648 34222560 5940256
10:08:33 42938 8192000000 10107952688 10565271552 1115613632 11.986 98 34 68 2 1184969264 62635872 6719760
10:10:17 59698 8192000000 10115580848 10555682816 1097768896 11.816 96 29 57 1 1159080560 55600256 5711408
10:12:24 93955 8192000000 10068747232 10530062336 1109292992 11.926 98 15 30 0 1182439376 69731168 3415216
10:14:58 49107 8192000000 10031152576 10496045056 1100637648 11.844 98 13 26 1 1125338704 21701312 2999744
10:16:49 61764 8192000000 10037689312 10489323520 1083083088 11.677 96 6 12 0 1105331104 21171504 1076512
10:18:51 30790 8192000000 10077341744 10520645632 1116693392 11.996 99 14 29 1 1159523200 40009824 2819984
10:20:24 64320 8192000000 10126031840 10563645440 1131775152 12.138 99 23 46 3 1174673744 38047760 4850832
10:22:37 96694 8192000000 10086689248 10611175424 1128841104 12.110 99 15 30 0 1239434032 107317920 3275008
10:25:14 51521 8192000000 10086736800 10521829376 1070342608 11.555 94 29 58 2 1105787200 29804816 5639776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-01-2021 10:28:02 to 16-01-2021 10:28:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-01-2021 10:28:18 to 16-01-2021 10:28:19.

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          226755
MON$OLDEST_ACTIVE               229165
MON$OLDEST_SNAPSHOT             229165
MON$NEXT_TRANSACTION            229165
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                       2088864
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:136: 
:137: mscs-test-161.budzdorov.ru    Sat Jan 16 10:27:05 2021
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Sat Jan 16 10:27:05 2021
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
:144: 
:145: mscs-test-161.budzdorov.ru    Sat Jan 16 10:27:06 2021
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Sat Jan 16 10:27:08 2021
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
--- end of diff output ---

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

16.01.21 10:28:21. 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-01-16 10:28:23 No severe PSQL-related problems occured
16.01.21 10:28:24. End of report.