Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2021 07: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 15751468 kB
MemAvailable 30766396 kB
Buffers 0 kB
Cached 15937380 kB
SwapCached 3072 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-09-27 07:37:06
DTS_END                         2021-09-27 07:37:06.8490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57518
TRN_ID                          237351
ATT_ID                          656
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: 8842.38 24084 1061086
customer order: creation 175.31 449 21037
customer order: refuse 15.01 323 1801
order to supplier: creation 46.12 1472 5534
order to supplier: removal 3.24 123507 389
invoice (draft): creation 46.69 3866 5603
invoice (draft): removal 48.19 1228 5783
invoice accept: apply 42.53 18304 5103
invoice accept: cancel 43.88 983 5265
customer reserve: creation 3891.73 180 467008
customer reserve: removal 4233.23 73 507987
realization accept: apply 84.92 202 10190
realization accept: cancel 81.43 124 9772
payment from customer: creation 61.18 125 7342
payment from customer: removal 5.95 78 714
payment to supplier: creation 48.88 152 5866
payment to supplier: removal 3.43 83 411
service: total inventory turnovers 3.62 3882 434
service: total monetary turnovers 6.22 551 746
service: aggregate perf. data 0.81 21625 97
service: refresh index statistics 0.03 304476 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2021 07:38:24 to 27-09-2021 07:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3817
TEST_TIME 2 5184
TEST_TIME 3 5252
TEST_TIME 4 5386
TEST_TIME 5 5304
TEST_TIME 6 5521
TEST_TIME 7 6154
TEST_TIME 8 6962
TEST_TIME 9 7008
TEST_TIME 10 7183
TEST_TIME 11 7561
TEST_TIME 12 7713
TEST_TIME 13 8301
TEST_TIME 14 8482
TEST_TIME 15 8496
TEST_TIME 16 8511
TEST_TIME 17 8522
TEST_TIME 18 8521
TEST_TIME 19 9053
TEST_TIME 20 9160
TEST_TIME 21 8920
TEST_TIME 22 9084
TEST_TIME 23 9262
TEST_TIME 24 9467
TEST_TIME 25 9594
TEST_TIME 26 10038
TEST_TIME 27 11012
TEST_TIME 28 12111
TEST_TIME 29 11859
TEST_TIME 30 11509
TEST_TIME 31 11434
TEST_TIME 32 11542
TEST_TIME 33 11310
TEST_TIME 34 11142
TEST_TIME 35 10933
TEST_TIME 36 10705
TEST_TIME 37 10632
TEST_TIME 38 10483
TEST_TIME 39 10327
TEST_TIME 40 10360
TEST_TIME 41 10161
TEST_TIME 42 10016
TEST_TIME 43 9899
TEST_TIME 44 9837
TEST_TIME 45 9806
TEST_TIME 46 9688
TEST_TIME 47 9597
TEST_TIME 48 9577
TEST_TIME 49 9424
TEST_TIME 50 9286
TEST_TIME 51 9160
TEST_TIME 52 9301
TEST_TIME 53 9237
TEST_TIME 54 9290
TEST_TIME 55 9207
TEST_TIME 56 9225
TEST_TIME 57 9245
TEST_TIME 58 9266
TEST_TIME 59 9280
TEST_TIME 60 9313
TEST_TIME 61 9365
TEST_TIME 62 9338
TEST_TIME 63 9300
TEST_TIME 64 9409
TEST_TIME 65 9388
TEST_TIME 66 9397
TEST_TIME 67 9278
TEST_TIME 68 9262
TEST_TIME 69 9283
TEST_TIME 70 9235
TEST_TIME 71 9202
TEST_TIME 72 9141
TEST_TIME 73 9080
TEST_TIME 74 9076
TEST_TIME 75 9048
TEST_TIME 76 8997
TEST_TIME 77 9014
TEST_TIME 78 9008
TEST_TIME 79 9033
TEST_TIME 80 9073
TEST_TIME 81 9111
TEST_TIME 82 9095
TEST_TIME 83 9043
TEST_TIME 84 9104
TEST_TIME 85 9115
TEST_TIME 86 9259
TEST_TIME 87 9283
TEST_TIME 88 9220
TEST_TIME 89 9176
TEST_TIME 90 9183
TEST_TIME 91 9111
TEST_TIME 92 9110
TEST_TIME 93 9092
TEST_TIME 94 9043
TEST_TIME 95 9024
TEST_TIME 96 9023
TEST_TIME 97 9010
TEST_TIME 98 8990
TEST_TIME 99 9051
TEST_TIME 100 9069
TEST_TIME 101 9053
TEST_TIME 102 9083
TEST_TIME 103 9063
TEST_TIME 104 9069
TEST_TIME 105 9049
TEST_TIME 106 9047
TEST_TIME 107 9183
TEST_TIME 108 9161
TEST_TIME 109 9156
TEST_TIME 110 9089
TEST_TIME 111 9042
TEST_TIME 112 9030
TEST_TIME 113 9012
TEST_TIME 114 8973
TEST_TIME 115 8953
TEST_TIME 116 8928
TEST_TIME 117 8921
TEST_TIME 118 8927
TEST_TIME 119 8908
TEST_TIME 120 8843

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-09-2021 07:38:28 to 27-09-2021 07:38:31.

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 1492826 1492826 0 0.00 0 22986 7 0 0
doc_list_biud 1492813 1492813 0 0.00 0 77353 33 0 0
sp_add_invoice_to_stock 5240 5103 137 2.61 22 203228 18304 0 137
sp_cancel_adding_invoice 5265 5265 0 0.00 6 64417 984 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 10224 323 0 0
sp_cancel_customer_reserve 507987 507987 0 0.00 1 35897 73 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 1699 79 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 2675 84 0 0
sp_cancel_supplier_invoice 5795 5783 12 0.20 3 77410 1228 0 12
sp_cancel_supplier_order 389 389 0 0.00 9 1115626 123507 0 0
sp_cancel_write_off 9772 9772 0 0.00 1 24673 124 0 0
sp_client_order 21037 21037 0 0.00 4 50904 450 0 0
sp_customer_reserve 467008 467008 0 0.00 0 56055 181 0 0
sp_fill_shopping_cart 32151 32151 0 0.00 1 52546 57 0 0
sp_fill_shopping_cart_clo_res 466994 466994 0 0.00 0 32786 16 0 0
sp_get_clo_for_invoice 5088 5088 0 0.00 0 38035 367 0 0
sp_kill_cost_storno 16162 16162 0 0.00 0 11978 62 0 0
sp_kill_qstorno_ret_qs2qd 1031918 1031918 0 0.00 0 77350 41 0 0
sp_kill_qty_storno 546265 546265 0 0.00 0 77352 88 0 0
sp_lock_selected_doc 21142 21142 0 0.00 0 8377 5 0 0
sp_make_cost_storno 28486 28486 0 0.00 0 22014 66 0 0
sp_multiply_rows_for_pdistr 28474 28474 0 0.00 0 22801 32 0 0
sp_multiply_rows_for_qdistr 21037 21037 0 0.00 0 38912 217 0 0
sp_pay_from_customer 7342 7342 0 0.00 4 22818 126 0 0
sp_pay_to_supplier 5866 5866 0 0.00 5 22991 152 0 0
sp_payment_common 13206 13206 0 0.00 1 10658 27 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 10215 298 0 0
sp_qd_handle_on_invoice_upd_sts 10349 10349 0 0.00 0 43411 413 0 0
sp_qd_handle_on_reserve_upd_sts 10184 10184 0 0.00 0 23785 64 0 0
sp_reserve_write_off 10190 10190 0 0.00 6 23891 203 0 0
sp_supplier_invoice 5603 5603 0 0.00 7 113787 3867 0 0
sp_supplier_order 5534 5534 0 0.00 5 72322 1472 0 0
srv_aggregate_perf_data 97 97 0 0.00 3081 384014 21626 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 936 107944 42596 0 0
srv_find_qd_qs_mism 1012387 1012387 0 0.00 0 22233 5 0 0
srv_make_invnt_saldo 434 434 0 0.00 79 142607 3883 0 0
srv_make_money_saldo 746 746 0 0.00 1 11986 552 0 0
srv_recalc_idx_stat 4 4 0 0.00 186284 497721 304476 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5103 5103 0 0.00 0 16050 172 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 4653 78 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 6089 15 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 857 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 3531 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 429 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 365 10 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 21529 1674 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 3524 12 0 0
t$perf-norm:sp_client_order 21037 21037 0 0.00 0 11939 18 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 4014 10 0 0
t$perf-norm:sp_pay_from_customer 7342 7342 0 0.00 0 12021 12 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 2584 9 0 0
t$perf-norm:sp_reserve_write_off 10190 10190 0 0.00 0 13221 12 0 0
t$perf-norm:sp_supplier_invoice 5603 5603 0 0.00 0 6128 15 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 11883 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 310 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 5223 112 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 647 7 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 972 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10442 10168 274 2.62 0 7785 18 0 274
v_all_customers 38612 38612 0 0.00 0 64 1 0 0
v_all_suppliers 30382 30382 0 0.00 0 58 1 0 0
v_all_wares 42046 42046 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1273 10 0 0
v_cancel_client_order 3602 3602 0 0.00 0 1042 3 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 1165 7 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 2455 8 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 2566 8 0 0
v_cancel_supplier_invoice 850 826 24 2.82 0 1152 15 0 24
v_cancel_supplier_order 778 778 0 0.00 0 872 7 0 0
v_cancel_write_off 2018 2018 0 0.00 0 3033 18 0 0
v_max_id_clo_ord 5532 5532 0 0.00 0 753 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 7672 22 0 0
v_max_id_ord_sup 5596 5596 0 0.00 0 6624 6 0 0
v_max_non_paid_invoice 1801 1801 0 0.00 0 1069 3 0 0
v_max_non_paid_realizn 3847 3847 0 0.00 0 8276 7 0 0
v_min_id_clo_ord 5532 5532 0 0.00 0 6075 8 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 5034 23 0 0
v_min_id_ord_sup 5596 5596 0 0.00 0 9011 12 0 0
v_min_non_paid_invoice 5864 5864 0 0.00 0 10652 18 0 0
v_min_non_paid_realizn 7342 7342 0 0.00 0 7020 15 0 0
v_our_firm 10424 10424 0 0.00 0 47 1 0 0
v_reserve_write_off 20368 20368 0 0.00 0 3827 6 0 0
x_lock_depdocs_on_canc_invoice 10635 10635 0 0.00 0 17416 136 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 9722 360 0 0
x_make_qstorno_1000_1200 5534 5534 0 0.00 2 69972 1381 0 0
x_make_qstorno_1200_2000 5603 5603 0 0.00 3 104184 3637 0 0
x_make_qstorno_2100_3300 467008 467008 0 0.00 0 56053 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2021 07:38:32 to 27-09-2021 07:38:36.

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
05:07:10 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:11 21160 8192000000 9715815136 10013589504 799389120 8.890 100 84 169 0 827864944 7799664 20676160
05:09:35 20430 8192000000 9754855648 10060550144 831682864 9.216 100 88 176 0 864211040 11391904 21136272
05:11:04 16140 8192000000 9796503888 10113257472 866105872 9.561 100 89 178 0 906330752 19298480 20926400
05:12:25 12765 8192000000 9857426544 10192625664 910096992 9.998 100 75 150 0 966793040 40273840 16422208
05:13:41 9647 8192000000 9912436960 10264092672 965572048 10.543 100 74 147 0 1028008816 47362960 15073808
05:14:54 5207 8192000000 9969064960 10323804160 1007236880 10.949 100 81 161 0 1089487200 65989088 16261232
05:16:01 2568 8192000000 10006257088 10369421312 1030318144 11.172 100 92 184 2 1130744464 81885792 18540528
05:17:05 3562 8192000000 10021061568 10391666688 1052659376 11.386 100 91 183 0 1134856640 63470608 18726656
05:18:10 1287 8192000000 10022498960 10397233152 1067536208 11.529 100 95 191 0 1139312800 52587136 19189456
05:19:12 4201 8192000000 10009933888 10404433920 1071769072 11.569 100 39 78 0 1124804400 45530848 7504480
05:20:17 5021 8192000000 9982129488 10380193792 1077032320 11.619 100 36 70 1 1099646672 14927696 7686656
05:21:28 60196 8192000000 10016839792 10426531840 1087449872 11.718 100 37 74 0 1112788416 16985520 8353024
05:23:29 4140 8192000000 10022047248 10423599104 1096222544 11.802 100 55 113 1 1135900032 27601488 12076000
05:24:35 6924 8192000000 10025911296 10431455232 1102166080 11.858 100 37 74 0 1138254240 27314688 8773472
05:25:43 8851 8192000000 10010132096 10426355712 1095084144 11.791 99 26 52 0 1120112096 19163888 5864064
05:26:55 22327 8192000000 10022390688 10413924352 1090260032 11.745 98 61 122 4 1113106528 8418688 14427808
05:28:22 6649 8192000000 10032855072 10432204800 1092927456 11.770 100 47 93 0 1143679328 41013024 9738848
05:29:31 8566 8192000000 9975194800 10375794688 1032151072 11.189 98 42 83 0 1071443552 30378608 8913872
05:30:40 5949 8192000000 9946934400 10327756800 1010308800 10.978 98 41 82 1 1036593376 16987280 9297296
05:31:49 47356 8192000000 9942108240 10342277120 1011401072 10.989 98 33 64 3 1056897216 38111136 7385008
05:33:36 8689 8192000000 9971196896 10372624384 1053249968 11.392 100 52 103 2 1087766720 21889136 12627616
05:34:47 8505 8192000000 9981134672 10378973184 1066212592 11.516 100 27 54 0 1099821328 26902352 6706384
05:35:58 10234 8192000000 9992839504 10399948800 1075670112 11.606 100 30 58 2 1103643920 21760080 6213728
05:37:15 76745 8192000000 10058043328 10455937024 1095981712 11.799 100 44 88 0 1146136000 41561360 8592928
05:39:34 23159 8192000000 10074449760 10481876992 1118453776 12.012 100 46 89 0 1192143040 64641248 9048016
05:40:59 28762 8192000000 10157977872 10574774272 1130299680 12.124 100 48 96 2 1271113664 130825104 9988880
05:42:32 4592 8192000000 10187588528 10591764480 1144190736 12.255 100 65 129 1 1286996496 128433040 14372720
05:43:44 8555 8192000000 10242716640 10651013120 1148191056 12.293 100 50 100 1 1342224864 182932272 11101536
05:44:55 3660 8192000000 10226568784 10638331904 1154757072 12.354 100 67 133 2 1325544720 156255456 14532192
05:46:04 10113 8192000000 10223200160 10636914688 1164909808 12.449 100 53 106 0 1326273936 150611872 10752256
05:47:21 1572 8192000000 10243378784 10654441472 1164415520 12.445 100 82 163 0 1345921648 163075664 18430464
05:48:27 10142 8192000000 10249929440 10661298176 1165767184 12.457 99 74 145 1 1341454000 159822416 15864400
05:49:40 4953 8192000000 10273661744 10677735424 1160552880 12.408 100 83 165 1 1366655568 188309968 17792720
05:50:48 2897 8192000000 10289052320 10696949760 1163230608 12.434 100 65 127 2 1389093760 211758160 14104992
05:51:58 5177 8192000000 10331678384 10744016896 1190039440 12.684 100 75 150 0 1423893504 216319984 17534080
05:53:08 1724 8192000000 10353291536 10755031040 1149813168 12.308 100 71 140 1 1446640608 280334688 16492752
05:54:16 935 8192000000 10398476048 10796544000 1156735936 12.373 100 91 182 0 1493672912 315811888 21125088
05:55:21 2520 8192000000 10429609024 10831347712 1170225184 12.499 100 87 173 2 1524360672 334619792 19515696
05:56:27 4289 8192000000 10434139120 10847526912 1171988816 12.515 100 49 98 1 1534367680 350099936 12278928
05:57:34 16060 8192000000 10470967136 10905997312 1185668496 12.643 100 45 90 2 1566100000 368967808 11463696
05:58:55 5888 8192000000 10491621248 10928529408 1148449712 12.295 98 59 117 0 1587109680 423736208 14923760
06:00:06 17729 8192000000 10504468624 10953990144 1171471696 12.511 100 33 68 0 1632516864 452069408 8975760
06:01:28 9548 8192000000 10530550704 10967650304 1146431168 12.276 99 41 82 1 1615578896 458383024 10764704
06:02:48 27760 8192000000 10558291600 11005976576 1132713728 12.147 100 28 56 0 1642094576 500659616 8721232
06:04:18 11196 8192000000 10378482608 10854551552 1108985920 11.923 99 30 56 2 1494647920 377550848 8111152
06:05:36 29198 8192000000 10099756832 10527285248 1119874848 12.026 100 28 52 2 1225267568 99198672 6194048
06:07:16 63549 8192000000 10111507440 10584432640 1128238768 12.105 100 21 42 1 1214029136 81587008 4203360
06:09:24 47725 8192000000 10056311952 10510835712 1117426704 12.003 99 12 24 0 1166276800 46688096 2162000
06:11:17 73313 8192000000 10076817424 10516897792 1140206944 12.217 100 29 58 1 1173987536 28357728 5422864
06:13:33 44899 8192000000 10099759184 10550267904 1121385888 12.040 98 29 58 2 1171504048 43762768 6355392
06:15:20 64331 8192000000 10117807712 10565505024 1139452304 12.210 99 17 35 0 1197625280 54090784 4082192
06:17:32 100631 8192000000 10066544704 10503962624 1134340272 12.162 99 28 56 3 1170671968 30283152 6048544
06:20:24 59630 8192000000 10092836976 10538180608 1120481680 12.032 98 42 82 0 1167626064 38218896 8925488
06:22:56 36755 8192000000 10065754736 10514976768 1132993744 12.150 99 26 54 0 1178307056 40268688 5044624
06:24:36 62352 8192000000 10058776768 10539917312 1103372048 11.870 97 12 24 0 1150966432 44531248 3063136
06:26:38 107941 8192000000 10112911296 10570080256 1134997072 12.168 99 22 44 0 1192961120 52744896 5219152
06:29:27 55086 8192000000 10069327168 10514022400 1140713088 12.222 100 25 50 1 1177079920 30690528 5676304
06:31:26 76051 8192000000 10101273632 10555957248 1118320288 12.011 97 14 28 0 1167467232 46221232 2925712
06:33:46 55682 8192000000 10084951920 10543349760 1125421248 12.078 98 14 29 1 1161709280 33125232 3162800
06:35:42 46548 8192000000 10096969792 10548854784 1115639712 11.986 97 30 59 0 1199789728 78184800 5965216
06:37:31 101686 8192000000 10058407760 10496618496 1096947680 11.809 96 13 26 0 1150167376 50659968 2559728
06:40:17 100688 8192000000 10046061104 10503696384 1079548224 11.643 95 4 8 0 1130289072 50012240 728608
06:42:59 85635 8192000000 10123078352 10611863552 1134047696 12.160 99 32 64 0 1235754896 95044672 6662528
06:45:45 59495 8192000000 10095614560 10579087360 1120914464 12.036 99 27 54 0 1195055472 68345872 5795136
06:47:49 46657 8192000000 10036918304 10502283264 1115376960 11.983 99 13 26 0 1160343968 42136416 2830592
06:49:36 82162 8192000000 10066605664 10534387712 1107116496 11.905 98 22 43 0 1169814768 57941472 4756800
06:52:10 34226 8192000000 10032199456 10523054080 1091184400 11.754 97 9 17 0 1137756496 44918464 1653632
06:53:47 63237 8192000000 10056237552 10518642688 1078380144 11.632 96 18 36 1 1135034816 52607040 4047632
06:55:51 34887 8192000000 10084347632 10527903744 1085607824 11.701 98 24 48 0 1146813088 55880608 5324656
06:57:26 66860 8192000000 10063209840 10522750976 1091676016 11.759 98 21 42 0 1150923744 54422512 4825216
06:59:36 42274 8192000000 10089460160 10517635072 1109891872 11.931 99 39 78 1 1159229776 41222928 8114976
07:01:30 84815 8192000000 10127224288 10607824896 1087671120 11.721 97 16 32 2 1213190368 121763984 3755264
07:04:00 39301 8192000000 10056274896 10536902656 1110787840 11.940 100 12 24 1 1170962272 57874688 2299744
07:05:41 102131 8192000000 10082790176 10557566976 1123743264 12.062 100 30 60 2 1202220784 71964912 6512608
07:08:32 4302 8192000000 10054565088 10530500608 1126633232 12.090 100 18 36 2 1163548128 33546928 3367968
07:09:38 36739 8192000000 10049474096 10519601152 1124428112 12.069 100 6 12 0 1166584096 40654320 1501664
07:11:21 54971 8192000000 10051278496 10498220032 1119310608 12.020 99 9 18 0 1159201248 38372656 1517984
07:13:16 50300 8192000000 10097907952 10548928512 1126261488 12.086 99 17 34 0 1187341456 57553168 3526800
07:15:22 55698 8192000000 10100825840 10593091584 1131878752 12.139 99 35 70 0 1204010880 64756096 7376032
07:17:48 41678 8192000000 10095534384 10538500096 1136959008 12.187 99 30 60 0 1174912192 31505520 6447664
07:19:31 45327 8192000000 10054730384 10494992384 1115560560 11.985 98 21 42 1 1152399536 33091632 3747344
07:21:17 12277 8192000000 10100590960 10527645696 1108084608 11.914 98 48 96 1 1179025360 61486368 9454384
07:22:30 66313 8192000000 10124985360 10576953344 1053857952 11.398 93 27 54 1 1148615072 89101904 5655216
07:26:02 77679 8192000000 10060385504 10547875840 1121471312 12.041 100 14 28 0 1155432816 31434480 2527024
07:28:33 35476 8192000000 10059234784 10496016384 1109252112 11.925 99 26 52 1 1150170704 35748032 5170560
07:30:09 52058 8192000000 10037496384 10488565760 1116982608 11.998 99 11 21 1 1141736304 22300688 2453008
07:32:06 54382 8192000000 10069617152 10534670336 1117438704 12.003 99 18 34 0 1184094032 63032320 3623008
07:34:09 49941 8192000000 10075460496 10538717184 1118686320 12.015 99 10 20 0 1162962608 42092144 2184144
07:36:18 28228 8192000000 10109042784 10550677504 1121366592 12.040 98 32 65 0 1175625456 47653248 6605616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-09-2021 07:38:38 to 27-09-2021 07:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2021 07:38:54 to 27-09-2021 07:38:56.

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          235049
MON$OLDEST_ACTIVE               237669
MON$OLDEST_SNAPSHOT             237669
MON$NEXT_TRANSACTION            237669
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                       2067872
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Mon Sep 27 07:37:13 2021
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Mon Sep 27 07:37:13 2021
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
--- end of diff output ---

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

27.09.21 07:38:57. 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-09-27 07:39:00 No severe PSQL-related problems occured
27.09.21 07:39:01. End of report.