Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2021 16:43
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 16256140 kB
MemAvailable 31010316 kB
Buffers 0 kB
Cached 15448284 kB
SwapCached 2600 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-18 16:41:35
DTS_END                         2021-08-18 16:41:35.7110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50106
TRN_ID                          241074
ATT_ID                          665
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: 9495.93 19912 1139512
customer order: creation 175.77 334 21092
customer order: refuse 15.00 346 1800
order to supplier: creation 47.03 1225 5644
order to supplier: removal 3.78 103305 454
invoice (draft): creation 48.27 3243 5792
invoice (draft): removal 54.53 841 6543
invoice accept: apply 42.21 16619 5065
invoice accept: cancel 48.98 722 5877
customer reserve: creation 3961.52 161 475382
customer reserve: removal 4788.90 63 574668
realization accept: apply 85.95 178 10314
realization accept: cancel 91.24 112 10949
payment from customer: creation 60.50 100 7260
payment from customer: removal 5.82 85 698
payment to supplier: creation 50.39 129 6047
payment to supplier: removal 3.62 61 434
service: total inventory turnovers 4.71 1246 565
service: total monetary turnovers 6.93 27 832
service: aggregate perf. data 0.77 22314 92
service: refresh index statistics 0.03 247125 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2021 16:43:28 to 18-08-2021 16:43:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4703
TEST_TIME 2 4909
TEST_TIME 3 4912
TEST_TIME 4 5220
TEST_TIME 5 4653
TEST_TIME 6 4488
TEST_TIME 7 4698
TEST_TIME 8 4651
TEST_TIME 9 4780
TEST_TIME 10 4977
TEST_TIME 11 5332
TEST_TIME 12 5344
TEST_TIME 13 5936
TEST_TIME 14 6012
TEST_TIME 15 6418
TEST_TIME 16 6323
TEST_TIME 17 6833
TEST_TIME 18 7129
TEST_TIME 19 7570
TEST_TIME 20 7486
TEST_TIME 21 7625
TEST_TIME 22 8381
TEST_TIME 23 8334
TEST_TIME 24 8873
TEST_TIME 25 8880
TEST_TIME 26 9157
TEST_TIME 27 9995
TEST_TIME 28 11319
TEST_TIME 29 11229
TEST_TIME 30 11191
TEST_TIME 31 11051
TEST_TIME 32 10864
TEST_TIME 33 11211
TEST_TIME 34 11137
TEST_TIME 35 11226
TEST_TIME 36 11043
TEST_TIME 37 10764
TEST_TIME 38 10743
TEST_TIME 39 10539
TEST_TIME 40 10393
TEST_TIME 41 10415
TEST_TIME 42 10412
TEST_TIME 43 10321
TEST_TIME 44 10295
TEST_TIME 45 10141
TEST_TIME 46 10065
TEST_TIME 47 10014
TEST_TIME 48 10118
TEST_TIME 49 10032
TEST_TIME 50 10026
TEST_TIME 51 10015
TEST_TIME 52 9934
TEST_TIME 53 9826
TEST_TIME 54 9766
TEST_TIME 55 9617
TEST_TIME 56 9592
TEST_TIME 57 9631
TEST_TIME 58 9561
TEST_TIME 59 9671
TEST_TIME 60 9622
TEST_TIME 61 9651
TEST_TIME 62 9664
TEST_TIME 63 9687
TEST_TIME 64 9733
TEST_TIME 65 9704
TEST_TIME 66 9781
TEST_TIME 67 9719
TEST_TIME 68 9788
TEST_TIME 69 9732
TEST_TIME 70 9912
TEST_TIME 71 9966
TEST_TIME 72 10002
TEST_TIME 73 9881
TEST_TIME 74 9837
TEST_TIME 75 9740
TEST_TIME 76 9738
TEST_TIME 77 9717
TEST_TIME 78 9662
TEST_TIME 79 9632
TEST_TIME 80 9635
TEST_TIME 81 9605
TEST_TIME 82 9599
TEST_TIME 83 9583
TEST_TIME 84 9623
TEST_TIME 85 9648
TEST_TIME 86 9634
TEST_TIME 87 9632
TEST_TIME 88 9693
TEST_TIME 89 9661
TEST_TIME 90 9667
TEST_TIME 91 9678
TEST_TIME 92 9752
TEST_TIME 93 9747
TEST_TIME 94 9714
TEST_TIME 95 9644
TEST_TIME 96 9632
TEST_TIME 97 9589
TEST_TIME 98 9580
TEST_TIME 99 9564
TEST_TIME 100 9547
TEST_TIME 101 9476
TEST_TIME 102 9607
TEST_TIME 103 9592
TEST_TIME 104 9572
TEST_TIME 105 9531
TEST_TIME 106 9566
TEST_TIME 107 9578
TEST_TIME 108 9604
TEST_TIME 109 9636
TEST_TIME 110 9642
TEST_TIME 111 9674
TEST_TIME 112 9687
TEST_TIME 113 9688
TEST_TIME 114 9724
TEST_TIME 115 9646
TEST_TIME 116 9618
TEST_TIME 117 9645
TEST_TIME 118 9600
TEST_TIME 119 9555
TEST_TIME 120 9500

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-08-2021 16:43:31 to 18-08-2021 16:43:35.

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 1579970 1579970 0 0.00 0 15777 6 0 0
doc_list_biud 1579969 1579969 0 0.00 0 46132 29 0 0
sp_add_invoice_to_stock 5189 5065 124 2.38 32 200773 16620 0 124
sp_cancel_adding_invoice 5877 5877 0 0.00 6 71634 723 0 0
sp_cancel_client_order 1800 1800 0 0.00 4 17062 347 0 0
sp_cancel_customer_reserve 574668 574668 0 0.00 1 46134 64 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 5545 85 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 2 2350 62 0 0
sp_cancel_supplier_invoice 6549 6543 6 0.09 2 17854 842 0 6
sp_cancel_supplier_order 454 454 0 0.00 6 1156213 103306 0 0
sp_cancel_write_off 10949 10949 0 0.00 1 8988 112 0 0
sp_client_order 21092 21092 0 0.00 3 17775 335 0 0
sp_customer_reserve 475382 475382 0 0.00 0 40997 162 0 0
sp_fill_shopping_cart 32512 32512 0 0.00 1 19729 35 0 0
sp_fill_shopping_cart_clo_res 475372 475372 0 0.00 0 12094 12 0 0
sp_get_clo_for_invoice 5051 5051 0 0.00 0 27620 281 0 0
sp_kill_cost_storno 17958 17958 0 0.00 0 8582 57 0 0
sp_kill_qstorno_ret_qs2qd 1167282 1167282 0 0.00 0 46130 35 0 0
sp_kill_qty_storno 615655 615655 0 0.00 0 46132 74 0 0
sp_lock_selected_doc 21266 21266 0 0.00 0 8514 7 0 0
sp_make_cost_storno 28671 28671 0 0.00 0 8943 46 0 0
sp_multiply_rows_for_pdistr 28671 28671 0 0.00 0 5646 27 0 0
sp_multiply_rows_for_qdistr 21092 21092 0 0.00 0 11565 155 0 0
sp_pay_from_customer 7260 7260 0 0.00 4 8131 101 0 0
sp_pay_to_supplier 6047 6047 0 0.00 5 13382 129 0 0
sp_payment_common 13307 13307 0 0.00 1 8120 28 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 8461 298 0 0
sp_qd_handle_on_invoice_upd_sts 10927 10927 0 0.00 0 32246 317 0 0
sp_qd_handle_on_reserve_upd_sts 10314 10314 0 0.00 0 14007 51 0 0
sp_reserve_write_off 10314 10314 0 0.00 6 17754 179 0 0
sp_supplier_invoice 5792 5792 0 0.00 7 83184 3244 0 0
sp_supplier_order 5644 5644 0 0.00 5 58995 1226 0 0
srv_aggregate_perf_data 92 92 0 0.00 2587 351913 22314 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1720 124725 45015 0 0
srv_find_qd_qs_mism 1090603 1090603 0 0.00 0 15775 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 43 115830 1247 0 0
srv_make_money_saldo 832 832 0 0.00 0 4384 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 128316 466224 247126 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 12230 192 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 1 9385 111 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 7850 18 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 1371 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 1035 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 4464 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 358 8 0 0
t$perf-norm:sp_cancel_supplier_order 454 454 0 0.00 0 33126 1630 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 1689 7 0 0
t$perf-norm:sp_client_order 21092 21092 0 0.00 0 7782 20 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 2820 7 0 0
t$perf-norm:sp_pay_from_customer 7260 7260 0 0.00 0 7949 19 0 0
t$perf-norm:sp_pay_to_supplier 6047 6047 0 0.00 0 9272 15 0 0
t$perf-norm:sp_reserve_write_off 10314 10314 0 0.00 0 13276 12 0 0
t$perf-norm:sp_supplier_invoice 5792 5792 0 0.00 0 4448 15 0 0
t$perf-norm:sp_supplier_order 5644 5644 0 0.00 0 9147 21 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 517 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 148 4 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 6117 32 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 627 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 462 119 0 0
v_add_invoice_to_stock 10346 10098 248 2.39 0 6917 22 0 248
v_all_customers 38624 38624 0 0.00 0 134 1 0 0
v_all_suppliers 31826 31826 0 0.00 0 82 1 0 0
v_all_wares 42184 42184 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 2305 8 0 0
v_cancel_client_order 3600 3600 0 0.00 0 16696 15 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 1812 5 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 7431 15 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 276 3 0 0
v_cancel_supplier_invoice 834 822 12 1.43 0 2627 22 0 12
v_cancel_supplier_order 908 908 0 0.00 0 2561 11 0 0
v_cancel_write_off 2072 2072 0 0.00 0 1580 11 0 0
v_max_id_clo_ord 5644 5644 0 0.00 0 292 1 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 3170 12 0 0
v_max_id_ord_sup 5774 5774 0 0.00 0 6083 3 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 1771 4 0 0
v_max_non_paid_realizn 3702 3702 0 0.00 0 1601 3 0 0
v_min_id_clo_ord 5644 5644 0 0.00 0 1843 4 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 1298 3 0 0
v_min_id_ord_sup 5774 5774 0 0.00 0 7749 5 0 0
v_min_non_paid_invoice 6047 6047 0 0.00 0 7350 18 0 0
v_min_non_paid_realizn 7260 7260 0 0.00 0 8038 18 0 0
v_our_firm 10676 10676 0 0.00 0 16 1 0 0
v_reserve_write_off 20628 20628 0 0.00 0 16720 9 0 0
x_lock_depdocs_on_canc_invoice 12009 12009 0 0.00 0 17934 119 0 0
x_lock_depdocs_on_canc_sup_ord 454 454 0 0.00 0 10653 340 0 0
x_make_qstorno_1000_1200 5644 5644 0 0.00 2 58962 1158 0 0
x_make_qstorno_1200_2000 5792 5792 0 0.00 4 83167 3112 0 0
x_make_qstorno_2100_3300 475382 475382 0 0.00 0 33353 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2021 16:43:36 to 18-08-2021 16:43:40.

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
14:11:39 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:12:40 26749 8192000000 10369979744 10688143360 1420182272 14.774 100 85 170 0 1453515600 5780256 27553072
14:14:14 22667 8192000000 10442538304 10769006592 1489081248 15.381 100 89 177 1 1525831664 7958144 28792272
14:15:47 16512 8192000000 10497700192 10844934144 1553625776 15.941 100 84 168 0 1589138032 9287024 26225232
14:17:06 8826 8192000000 10572424688 10931339264 1628481984 16.582 100 79 156 2 1662987344 11263456 23241904
14:18:19 5679 8192000000 10627423040 10995691520 1688765088 17.091 100 74 148 2 1722474576 12373360 21336128
14:19:27 5854 8192000000 10690794624 11068637184 1756146704 17.653 100 79 158 0 1791280736 12528304 22605728
14:20:35 9433 8192000000 10736683568 11126697984 1801947552 18.030 100 68 136 2 1833447120 13026992 18472576
14:21:47 1926 8192000000 10800662864 11185999872 1858072528 18.488 100 91 185 2 1898031136 14239360 25719248
14:22:51 1877 8192000000 10822003184 11209330688 1873645280 18.614 100 89 178 2 1912072720 12842688 25584752
14:23:55 7803 8192000000 10829789440 11222347776 1886854080 18.720 100 76 152 0 1918565728 9184000 22527648
14:25:04 8406 8192000000 10798311872 11227578368 1889897744 18.745 100 25 50 1 1903466432 6727312 6841376
14:26:13 15279 8192000000 10801169168 11237994496 1899621184 18.823 100 14 28 2 1909347872 6046656 3680032
14:27:28 37677 8192000000 10822551072 11255468032 1906891904 18.882 100 44 86 3 1924149520 5249200 12008416
14:29:07 7855 8192000000 10830416928 11263627264 1917901040 18.970 100 21 42 0 1929798848 6202608 5695200
14:30:15 7068 8192000000 10846487952 11270434816 1906759232 18.881 99 15 30 0 1917232256 6409296 4063728
14:31:23 58319 8192000000 10834652736 11243614208 1878876848 18.656 99 29 58 1 1896463904 9401728 8185328
14:33:23 5970 8192000000 10751574112 11177242624 1820348784 18.181 98 32 64 0 1835424800 5955856 9120160
14:34:29 10665 8192000000 10716463408 11134562304 1752352000 17.621 96 21 42 1 1764001696 5506608 6143088
14:35:43 9235 8192000000 10708989264 11131219968 1771790256 17.782 98 20 38 1 1783331952 5698576 5843120
14:36:54 22739 8192000000 10705595360 11126046720 1781732784 17.864 99 22 44 1 1793838048 6063744 6041520
14:38:18 5377 8192000000 10770573920 11188969472 1846735760 18.396 100 30 60 2 1861437232 6526208 8175264
14:39:24 9047 8192000000 10779158704 11198898176 1858325488 18.490 100 26 52 2 1871449584 5825952 7298144
14:40:33 19854 8192000000 10783300144 11215151104 1873941440 18.616 100 24 48 1 1887141568 5179040 8021088
14:42:26 13637 8192000000 10842167424 11259252736 1906626576 18.880 100 48 96 0 1928487152 8454592 13405984
14:43:45 24140 8192000000 10871933232 11296632832 1934715712 19.105 100 48 96 0 1959361776 11263968 13382096
14:45:14 41996 8192000000 10878873104 11321348096 1956868928 19.281 100 26 51 0 1977734816 13821104 7044784
14:47:05 19820 8192000000 10922809280 11352887296 1979509808 19.461 100 46 92 0 2012699472 19337392 13852272
14:48:39 9268 8192000000 10943821968 11371106304 1994626624 19.580 100 54 106 3 2034956176 23504016 16825536
14:49:56 14370 8192000000 10953988912 11385163776 2004255920 19.656 100 62 124 1 2051764048 29157232 18350896
14:51:18 8136 8192000000 10966951280 11399700480 2009483152 19.697 100 56 110 2 2059277392 32644720 17149520
14:52:29 2127 8192000000 11004490016 11424919552 2028466976 19.847 100 84 170 2 2089066640 35254496 25345168
14:53:37 10512 8192000000 11020345232 11444158464 2022230672 19.798 99 76 152 1 2093066656 47046240 23789744
14:54:52 2105 8192000000 11021108240 11434389504 2023913024 19.811 100 95 190 1 2097549296 44329680 29306592
14:55:56 7314 8192000000 11009001792 11440304128 2020847312 19.787 100 65 128 0 2089218528 48721024 19650192
14:57:12 7820 8192000000 11003326704 11432947712 2003244064 19.648 99 62 124 1 2075778672 51992416 20542192
14:58:28 1717 8192000000 11007446384 11415220224 1995697280 19.589 100 94 188 0 2086873984 61630368 29546336
14:59:33 6204 8192000000 10977192720 11410157568 1993450880 19.571 100 48 96 3 2065634160 55947280 16236000
15:00:46 9005 8192000000 10971042320 11411759104 1988241328 19.530 100 39 78 1 2057357104 55672272 13443504
15:02:03 2161 8192000000 10988394656 11399217152 1978491568 19.453 100 87 172 0 2065745296 60488720 26765008
15:03:10 9586 8192000000 10959636768 11396329472 1962960432 19.330 100 52 102 1 2044790048 65168944 16660672
15:04:24 21214 8192000000 10932478864 11383488512 1940609264 19.152 99 30 60 1 2012300704 60890288 10801152
15:05:48 21395 8192000000 10941875776 11395485696 1939329216 19.141 99 34 66 0 2010118032 59481728 11307088
15:07:14 27797 8192000000 10924255440 11385364480 1909882176 18.906 98 26 53 1 1989211648 69542736 9786736
15:08:44 18822 8192000000 10920462944 11380109312 1937203488 19.124 100 28 56 2 1999353568 52357760 9792320
15:10:03 40847 8192000000 10882067648 11333722112 1924277792 19.021 99 16 34 0 1937148912 8211088 4660032
15:11:45 55543 8192000000 10905495104 11358797824 1931371648 19.078 98 29 60 0 1953308336 13621088 8315600
15:13:44 73369 8192000000 10900742768 11368079360 1933833888 19.098 98 12 24 0 1951584096 14127424 3622784
15:15:59 124725 8192000000 10909247872 11376586752 1922403552 19.006 97 8 16 1 1941053024 16398096 2251376
15:19:27 73295 8192000000 10892725024 11364655104 1949534352 19.223 99 8 16 0 1959701728 8157424 2009952
15:22:29 40861 8192000000 10920675248 11382558720 1968210608 19.371 99 33 65 1 1987923904 9338288 10375008
15:24:50 63101 8192000000 10907637488 11390103552 1982269328 19.483 100 13 26 0 2001976560 15656336 4050896
15:27:00 43362 8192000000 10936640800 11413123072 1999033488 19.615 100 13 26 2 2018941360 15722400 4185472
15:28:46 66147 8192000000 10896943552 11387523072 1955450192 19.270 99 15 30 0 1976476112 16354160 4671760
15:30:53 43141 8192000000 10916416624 11384610816 1984716736 19.502 100 21 42 0 2001835104 10939008 6179360
15:32:36 47056 8192000000 10908653168 11396726784 1989013440 19.536 100 14 28 1 2003194096 10279296 3901360
15:34:42 68623 8192000000 10908966704 11388112896 1988768528 19.534 100 18 36 0 2000737248 6869264 5099456
15:36:51 71459 8192000000 10913261808 11406659584 1977256992 19.443 99 11 22 1 1995286032 14930496 3098544
15:39:03 42042 8192000000 10919839232 11402702848 1974674448 19.423 99 18 36 0 1996672848 16342576 5655824
15:40:47 75796 8192000000 10902962224 11368972288 1863245360 18.530 93 17 34 0 1876488336 8588816 4654160
15:43:06 23607 8192000000 10920745168 11384119296 1969297744 19.380 99 42 84 0 1990098992 8835152 11966096
15:44:32 39374 8192000000 10909221456 11360649216 1917537168 18.967 97 36 72 2 1940424448 12879136 10008144
15:46:20 16150 8192000000 10906486000 11360649216 1954475968 19.262 99 35 70 2 1972060992 7905728 9679296
15:47:43 70493 8192000000 10900961440 11351953408 1884723872 18.703 96 24 48 0 1909392528 17367616 7301040
15:49:54 100343 8192000000 10858198112 11337908224 1885831760 18.712 97 10 20 0 1910537104 21738224 2967120
15:52:34 104195 8192000000 10874182128 11340664832 1870328864 18.587 96 13 26 3 1890507184 16874640 3303680
15:55:19 97826 8192000000 10877903008 11330019328 1904089584 18.859 98 31 62 1 1924427216 11905088 8432544
15:58:02 73588 8192000000 10857536912 11338612736 1922188368 19.004 99 18 36 1 1937608272 10828112 4591792
16:00:22 73978 8192000000 10842539856 11326017536 1924686896 19.024 99 18 36 0 1941217856 10594512 5936448
16:02:43 66904 8192000000 10850813104 11320127488 1902518976 18.847 98 10 20 0 1915117888 9816736 2782176
16:05:09 49382 8192000000 10851235024 11330314240 1889906768 18.745 97 18 36 1 1906134432 10813920 5413744
16:07:09 39622 8192000000 10878717024 11328225280 1916463888 18.959 99 29 58 0 1933795616 8943664 8388064
16:08:51 64542 8192000000 10860877104 11345027072 1917383008 18.966 99 13 26 0 1933890448 13171632 3335808
16:11:03 67445 8192000000 10898118192 11355824128 1922559696 19.007 98 18 36 2 1936305248 8376976 5368576
16:13:14 61166 8192000000 10883385408 11353546752 1939348880 19.142 98 15 30 1 1960574512 17182480 4043152
16:15:15 22171 8192000000 10905845088 11364311040 1944691264 19.184 98 22 44 1 1958699728 8357536 5650928
16:16:42 38116 8192000000 10923185104 11372142592 1979293296 19.459 100 54 108 2 2003738224 8659664 15785264
16:18:31 35909 8192000000 10913661296 11384270848 1948736080 19.216 98 17 34 1 1965961696 12148336 5077280
16:20:07 54838 8192000000 10898776112 11384598528 1978267616 19.451 100 9 18 0 1992167872 11263552 2636704
16:22:14 61371 8192000000 10907725888 11387912192 1937670880 19.128 97 22 44 2 1957386544 12693344 7022320
16:24:34 57580 8192000000 10874718784 11357122560 1923129600 19.012 97 11 21 2 1938403712 11588720 3685392
16:27:05 54292 8192000000 10881577824 11347828736 1916141568 18.956 98 29 58 2 1936942688 12502096 8299024
16:29:04 52205 8192000000 10896634928 11378302976 1969310832 19.380 100 33 66 1 1990604912 11579360 9714720
16:31:02 83078 8192000000 10887226080 11365638144 1874260864 18.619 95 15 30 1 1889809952 11392176 4156912
16:33:33 42452 8192000000 10913657408 11360796672 1954915008 19.266 100 19 37 1 1968471952 8753120 4803824
16:35:18 94110 8192000000 10891101248 11375443968 1926977408 19.043 98 5 10 0 1944787328 16387024 1422896
16:38:02 85779 8192000000 10858702688 11358830592 1901644352 18.840 97 11 22 1 1917438672 12160816 3633504
16:40:34 41801 8192000000 10871145728 11348054016 1936649744 19.120 99 24 47 2 1951483456 7421408 7412304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-08-2021 16:43:41 to 18-08-2021 16:43:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-08-2021 16:43:57 to 18-08-2021 16:43:59.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239460
MON$OLDEST_ACTIVE               241229
MON$OLDEST_SNAPSHOT             241229
MON$NEXT_TRANSACTION            241229
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-03 16:41:55.3470
MON$PAGES                       2028128
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Aug 18 16:41:38 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Aug 18 16:41:39 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Aug 18 16:41:46 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Wed Aug 18 16:41:48 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Wed Aug 18 16:41:48 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Wed Aug 18 16:41:48 2021
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:29: 
--- end of diff output ---

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

18.08.21 16:44:00. There are 3 logs in /var/tmp/logs-oltp30 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-18 16:44:03 No severe PSQL-related problems occured
18.08.21 16:44:04. End of report.