Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2021 22:39
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 16000212 kB
MemAvailable 30650104 kB
Buffers 0 kB
Cached 15723500 kB
SwapCached 3700 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-10-20 22:37:49
DTS_END                         2021-10-20 22:37:49.2110
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(57:17)
IP                              ::1/52884
TRN_ID                          231799
ATT_ID                          687
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: 8801.63 21060 1056195
customer order: creation 166.44 372 19973
customer order: refuse 14.23 331 1707
order to supplier: creation 43.31 1266 5197
order to supplier: removal 3.24 107970 389
invoice (draft): creation 43.68 3388 5241
invoice (draft): removal 50.52 862 6062
invoice accept: apply 39.50 19328 4740
invoice accept: cancel 45.68 846 5482
customer reserve: creation 3656.42 190 438770
customer reserve: removal 4458.17 61 534980
realization accept: apply 78.12 171 9374
realization accept: cancel 78.89 105 9467
payment from customer: creation 56.03 104 6723
payment from customer: removal 5.56 87 667
payment to supplier: creation 46.71 110 5605
payment to supplier: removal 3.19 57 383
service: total inventory turnovers 4.55 1095 546
service: total monetary turnovers 6.58 30 789
service: aggregate perf. data 0.80 25187 96
service: refresh index statistics 0.03 259632 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-10-2021 22:39:36 to 20-10-2021 22:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3038
TEST_TIME 2 3272
TEST_TIME 3 4223
TEST_TIME 4 4628
TEST_TIME 5 5054
TEST_TIME 6 5645
TEST_TIME 7 5802
TEST_TIME 8 5811
TEST_TIME 9 5944
TEST_TIME 10 6433
TEST_TIME 11 6316
TEST_TIME 12 6335
TEST_TIME 13 6354
TEST_TIME 14 6279
TEST_TIME 15 6721
TEST_TIME 16 7095
TEST_TIME 17 7043
TEST_TIME 18 7042
TEST_TIME 19 7635
TEST_TIME 20 8148
TEST_TIME 21 8320
TEST_TIME 22 8439
TEST_TIME 23 8505
TEST_TIME 24 8766
TEST_TIME 25 9185
TEST_TIME 26 9118
TEST_TIME 27 9509
TEST_TIME 28 10082
TEST_TIME 29 10644
TEST_TIME 30 10716
TEST_TIME 31 10406
TEST_TIME 32 10325
TEST_TIME 33 10115
TEST_TIME 34 9927
TEST_TIME 35 9773
TEST_TIME 36 9701
TEST_TIME 37 9658
TEST_TIME 38 9743
TEST_TIME 39 9600
TEST_TIME 40 9538
TEST_TIME 41 9401
TEST_TIME 42 9283
TEST_TIME 43 9276
TEST_TIME 44 9196
TEST_TIME 45 9062
TEST_TIME 46 9009
TEST_TIME 47 8936
TEST_TIME 48 8934
TEST_TIME 49 8912
TEST_TIME 50 8811
TEST_TIME 51 8803
TEST_TIME 52 8846
TEST_TIME 53 8896
TEST_TIME 54 8914
TEST_TIME 55 8922
TEST_TIME 56 8897
TEST_TIME 57 8933
TEST_TIME 58 8937
TEST_TIME 59 9022
TEST_TIME 60 9039
TEST_TIME 61 9018
TEST_TIME 62 9169
TEST_TIME 63 9208
TEST_TIME 64 9241
TEST_TIME 65 9275
TEST_TIME 66 9226
TEST_TIME 67 9169
TEST_TIME 68 9111
TEST_TIME 69 9054
TEST_TIME 70 9096
TEST_TIME 71 9046
TEST_TIME 72 9071
TEST_TIME 73 9065
TEST_TIME 74 9073
TEST_TIME 75 9016
TEST_TIME 76 8980
TEST_TIME 77 8955
TEST_TIME 78 8913
TEST_TIME 79 8886
TEST_TIME 80 8858
TEST_TIME 81 8883
TEST_TIME 82 8851
TEST_TIME 83 8856
TEST_TIME 84 8985
TEST_TIME 85 8959
TEST_TIME 86 9029
TEST_TIME 87 9011
TEST_TIME 88 9015
TEST_TIME 89 9011
TEST_TIME 90 9041
TEST_TIME 91 9069
TEST_TIME 92 9061
TEST_TIME 93 9027
TEST_TIME 94 8977
TEST_TIME 95 8993
TEST_TIME 96 8987
TEST_TIME 97 8957
TEST_TIME 98 8921
TEST_TIME 99 8862
TEST_TIME 100 8865
TEST_TIME 101 8868
TEST_TIME 102 8845
TEST_TIME 103 8884
TEST_TIME 104 8885
TEST_TIME 105 8875
TEST_TIME 106 8904
TEST_TIME 107 8930
TEST_TIME 108 8900
TEST_TIME 109 8943
TEST_TIME 110 8997
TEST_TIME 111 9006
TEST_TIME 112 9016
TEST_TIME 113 9028
TEST_TIME 114 9011
TEST_TIME 115 8943
TEST_TIME 116 8961
TEST_TIME 117 8922
TEST_TIME 118 8868
TEST_TIME 119 8854
TEST_TIME 120 8806

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2021 22:39:40 to 20-10-2021 22:39:43.

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 1462239 1462239 0 0.00 0 23272 6 0 0
doc_list_biud 1462237 1462237 0 0.00 0 55957 29 0 0
sp_add_invoice_to_stock 4846 4740 106 2.18 54 185770 19328 0 106
sp_cancel_adding_invoice 5482 5482 0 0.00 6 74097 846 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 14246 332 0 0
sp_cancel_customer_reserve 534980 534980 0 0.00 1 55959 62 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 5225 87 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 2169 58 0 0
sp_cancel_supplier_invoice 6070 6062 8 0.13 2 19582 862 0 8
sp_cancel_supplier_order 389 389 0 0.00 8 1076734 107970 0 0
sp_cancel_write_off 9467 9467 0 0.00 1 9913 106 0 0
sp_client_order 19973 19973 0 0.00 3 18764 373 0 0
sp_customer_reserve 438770 438770 0 0.00 0 66835 191 0 0
sp_fill_shopping_cart 30403 30403 0 0.00 1 12545 38 0 0
sp_fill_shopping_cart_clo_res 438762 438762 0 0.00 0 18106 14 0 0
sp_get_clo_for_invoice 4731 4731 0 0.00 0 58952 381 0 0
sp_kill_cost_storno 15999 15999 0 0.00 0 5946 54 0 0
sp_kill_qstorno_ret_qs2qd 1085878 1085878 0 0.00 0 52747 33 0 0
sp_kill_qty_storno 572192 572192 0 0.00 0 55957 72 0 0
sp_lock_selected_doc 19654 19654 0 0.00 0 6786 5 0 0
sp_make_cost_storno 26433 26433 0 0.00 0 19908 48 0 0
sp_multiply_rows_for_pdistr 26433 26433 0 0.00 0 6359 27 0 0
sp_multiply_rows_for_qdistr 19973 19973 0 0.00 0 13539 168 0 0
sp_pay_from_customer 6723 6723 0 0.00 4 12052 105 0 0
sp_pay_to_supplier 5605 5605 0 0.00 4 8680 111 0 0
sp_payment_common 12328 12328 0 0.00 1 12018 25 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 14237 308 0 0
sp_qd_handle_on_invoice_upd_sts 10213 10213 0 0.00 0 28953 345 0 0
sp_qd_handle_on_reserve_upd_sts 9374 9374 0 0.00 0 17453 64 0 0
sp_reserve_write_off 9374 9374 0 0.00 6 17484 172 0 0
sp_supplier_invoice 5241 5241 0 0.00 6 72760 3388 0 0
sp_supplier_order 5197 5197 0 0.00 5 41465 1267 0 0
srv_aggregate_perf_data 96 96 0 0.00 2708 641874 25188 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2474 120480 48936 0 0
srv_find_qd_qs_mism 1010531 1010531 0 0.00 0 23272 6 0 0
srv_make_invnt_saldo 546 546 0 0.00 36 40049 1095 0 0
srv_make_money_saldo 789 789 0 0.00 1 4197 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 114380 603855 259633 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4740 4740 0 0.00 0 33215 208 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 33348 260 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 8791 23 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 2261 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 5779 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 1288 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 807 11 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 34458 1719 0 0
t$perf-norm:sp_cancel_write_off 888 888 0 0.00 0 3175 13 0 0
t$perf-norm:sp_client_order 19973 19973 0 0.00 0 25685 19 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 2987 11 0 0
t$perf-norm:sp_pay_from_customer 6723 6723 0 0.00 0 4475 9 0 0
t$perf-norm:sp_pay_to_supplier 5605 5605 0 0.00 0 14646 15 0 0
t$perf-norm:sp_reserve_write_off 9374 9374 0 0.00 0 20828 15 0 0
t$perf-norm:sp_supplier_invoice 5241 5241 0 0.00 0 11202 21 0 0
t$perf-norm:sp_supplier_order 5197 5197 0 0.00 0 7850 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 545 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 452 9 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 3842 27 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 3012 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9674 9462 212 2.19 0 4069 17 0 212
v_all_customers 36222 36222 0 0.00 0 28 1 0 0
v_all_suppliers 28868 28868 0 0.00 0 20 1 0 0
v_all_wares 39946 39946 0 0.00 0 115 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1641 10 0 0
v_cancel_client_order 3414 3414 0 0.00 0 1236 4 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 4901 12 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2115 6 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 198 3 0 0
v_cancel_supplier_invoice 838 822 16 1.90 0 557 13 0 16
v_cancel_supplier_order 778 778 0 0.00 0 3415 9 0 0
v_cancel_write_off 1776 1776 0 0.00 0 4135 19 0 0
v_max_id_clo_ord 5197 5197 0 0.00 0 979 2 0 0
v_max_id_clo_res 937 937 0 0.00 0 1224 10 0 0
v_max_id_ord_sup 5233 5233 0 0.00 0 5086 5 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 552 2 0 0
v_max_non_paid_realizn 3632 3632 0 0.00 0 1150 4 0 0
v_min_id_clo_ord 5197 5197 0 0.00 0 5954 6 0 0
v_min_id_clo_res 937 937 0 0.00 0 6840 20 0 0
v_min_id_ord_sup 5233 5233 0 0.00 0 6226 7 0 0
v_min_non_paid_invoice 5605 5605 0 0.00 0 6976 14 0 0
v_min_non_paid_realizn 6723 6723 0 0.00 0 12015 21 0 0
v_our_firm 9906 9906 0 0.00 0 18 1 0 0
v_reserve_write_off 18748 18748 0 0.00 0 4119 5 0 0
x_lock_depdocs_on_canc_invoice 11133 11133 0 0.00 0 24859 130 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 19113 471 0 0
x_make_qstorno_1000_1200 5197 5197 0 0.00 1 41460 1190 0 0
x_make_qstorno_1200_2000 5241 5241 0 0.00 3 70446 3249 0 0
x_make_qstorno_2100_3300 438770 438770 0 0.00 0 66806 174 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 20-10-2021 22:39:44 to 20-10-2021 22:39:49.

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
20:07:53 23 8192000000 8806873200 8820592640 12042320 0.146 3 3 6 0 12260848 89392 129136
20:08:53 21032 8192000000 9886332432 10194886656 969887456 10.586 100 88 176 0 999055984 5693536 23474992
20:10:29 15988 8192000000 9936813088 10258239488 1021085664 11.082 100 91 182 0 1052620256 7793696 23740896
20:11:50 23825 8192000000 9987104096 10323361792 1064698800 11.501 100 77 154 0 1092466368 8383040 19384528
20:13:19 13872 8192000000 10039894592 10390159360 1129804032 12.120 100 83 166 0 1160072672 10372544 19896096
20:14:37 6463 8192000000 10094325104 10457407488 1185768272 12.644 100 82 164 1 1216977920 12552160 18657488
20:15:51 6995 8192000000 10150257776 10519044096 1229073552 13.046 100 85 170 1 1261387040 12938816 19374672
20:16:58 9809 8192000000 10176801904 10557292544 1262290112 13.351 100 77 154 0 1292690720 13501248 16899360
20:18:09 2365 8192000000 10201865648 10584944640 1287846400 13.585 100 86 172 0 1320407920 12552416 20009104
20:19:13 1619 8192000000 10211008608 10598166528 1296872016 13.667 100 95 188 4 1331445680 12502416 22071248
20:20:15 6898 8192000000 10189547760 10599174144 1300678032 13.701 100 37 75 0 1316097680 7514640 7905008
20:21:23 4934 8192000000 10197256896 10609680384 1309133632 13.778 100 25 50 0 1321978752 7451712 5393408
20:22:31 11273 8192000000 10197851088 10617630720 1314926608 13.831 100 16 32 0 1324124880 5903936 3294336
20:23:47 43806 8192000000 10206501456 10631606272 1318918992 13.867 100 26 52 1 1330749200 5459024 6371184
20:25:32 6873 8192000000 10220418128 10638139392 1326767280 13.938 100 15 30 0 1335082272 5241072 3073920
20:26:39 5973 8192000000 10215958384 10640830464 1331892976 13.984 100 22 42 0 1341431344 4487728 5050640
20:27:45 4628 8192000000 10222911088 10638209024 1326831344 13.939 100 29 58 0 1339485632 6015744 6638544
20:28:53 19140 8192000000 10186949216 10605699072 1264750592 13.374 96 12 24 0 1272798144 4909200 3138352
20:30:13 7784 8192000000 10127255360 10527580160 1208135200 12.852 96 18 36 0 1218169600 5803216 4231184
20:31:21 5267 8192000000 10131474304 10520358912 1211701392 12.885 98 28 55 2 1224309328 6027328 6580608
20:32:31 65769 8192000000 10165183616 10567262208 1245843840 13.200 98 20 40 0 1255053456 4776240 4433376
20:34:46 10947 8192000000 10195061584 10605666304 1293404240 13.635 100 32 63 1 1311286752 10167552 7714960
20:36:00 11318 8192000000 10197830736 10613829632 1308997344 13.777 100 26 52 0 1320977296 5717536 6262416
20:37:12 33014 8192000000 10219273216 10642898944 1333141552 13.996 100 29 59 0 1344711760 4931808 6638400
20:39:13 13935 8192000000 10253076992 10665115648 1346725872 14.118 100 54 108 1 1368113216 9633248 11754096
20:40:34 11819 8192000000 10279943152 10693246976 1364564880 14.278 100 55 110 1 1389953936 12968432 12420624
20:42:03 10757 8192000000 10301673536 10716143616 1383628976 14.449 100 64 127 0 1415175344 16893360 14653008
20:43:18 2704 8192000000 10311265952 10730946560 1389759040 14.504 100 59 117 2 1420485968 16960784 13766144
20:44:24 5200 8192000000 10318248128 10735046656 1386250512 14.472 99 66 129 1 1421691392 20331856 15109024
20:45:34 13807 8192000000 10322885648 10747203584 1389341632 14.500 99 47 93 1 1422861600 21914880 11605088
20:46:57 10033 8192000000 10355743168 10770460672 1414403872 14.723 100 76 152 4 1458646080 26920768 17321440
20:48:11 2473 8192000000 10346738928 10767405056 1414751376 14.726 100 65 124 1 1452118160 23390688 13976096
20:49:18 3807 8192000000 10334579440 10749771776 1395263312 14.553 100 63 122 1 1438486400 28967808 14255280
20:50:25 5290 8192000000 10338656144 10749411328 1377949984 14.398 99 73 144 1 1440040256 43913472 18176800
20:51:36 6921 8192000000 10358884192 10773295104 1388300976 14.491 99 74 145 1 1453459200 47695952 17462272
20:52:47 4637 8192000000 10338182704 10749960192 1377406240 14.393 100 69 137 2 1445540864 50304112 17830512
20:53:56 3303 8192000000 10306806528 10707898368 1342895488 14.084 98 82 164 3 1406946624 42569840 21481296
20:55:06 4224 8192000000 10309841200 10714222592 1350432624 14.151 100 70 140 1 1419458784 50808160 18218000
20:56:15 3809 8192000000 10334612576 10743287808 1367512064 14.305 100 74 148 1 1436716992 49473600 19731328
20:57:26 4352 8192000000 10357527248 10766974976 1389747600 14.504 100 77 152 0 1459434160 51039424 18647136
20:58:34 3327 8192000000 10360888512 10769674240 1393431600 14.536 100 74 149 2 1458333440 45301280 19600560
20:59:44 3690 8192000000 10346646688 10770538496 1388910976 14.496 99 52 104 1 1444402368 42072064 13419328
21:00:52 7306 8192000000 10329547216 10747658240 1360717632 14.244 98 42 84 1 1418894960 46724720 11452608
21:02:03 14125 8192000000 10326571968 10751754240 1363080160 14.265 99 40 81 0 1419800912 45515104 11205648
21:03:24 29344 8192000000 10317933008 10755006464 1369603040 14.323 100 21 42 2 1423737536 48056752 6077744
21:05:09 26427 8192000000 10304053328 10747629568 1356274544 14.204 99 24 48 0 1412141728 48720480 7146704
21:06:36 72561 8192000000 10309003280 10754252800 1374633184 14.369 100 17 32 1 1398577056 19915296 4028576
21:08:51 63482 8192000000 10274720336 10729562112 1337580368 14.036 97 22 45 0 1352248032 10148496 4519168
21:10:55 72336 8192000000 10284588768 10736259072 1345079200 14.103 98 8 16 1 1358775152 12025312 1670640
21:13:26 97883 8192000000 10301263056 10761490432 1386010544 14.470 100 17 34 0 1416088176 26170832 3906800
21:16:11 73495 8192000000 10281677616 10735595520 1356360384 14.205 98 9 18 0 1372556544 14032944 2163216
21:18:28 95026 8192000000 10292756896 10745679872 1387832336 14.487 100 20 40 1 1405475776 13442640 4200800
21:21:07 77076 8192000000 10288646000 10756730880 1382432688 14.438 99 7 14 0 1396043136 12080416 1530032
21:23:24 40266 8192000000 10307252176 10769563648 1407108848 14.658 100 19 38 0 1422595168 11130768 4355552
21:25:31 77657 8192000000 10320434800 10787852288 1406268464 14.651 100 25 50 2 1426810960 14283664 6258832
21:27:57 60622 8192000000 10297552160 10768547840 1371464976 14.340 97 18 35 1 1391877600 15996448 4416176
21:29:58 120480 8192000000 10302767824 10775711744 1361050848 14.247 97 10 20 1 1381697280 18421584 2224848
21:32:58 67586 8192000000 10304234656 10761224192 1380776384 14.423 98 31 62 0 1400290896 11731072 7783440
21:35:06 53555 8192000000 10313146128 10771378176 1405198880 14.641 100 16 32 1 1423757040 14842352 3715808
21:37:09 54132 8192000000 10308813760 10766106624 1396228832 14.561 99 11 22 0 1405635632 7575600 1831200
21:39:13 87645 8192000000 10297585568 10758778880 1364196000 14.275 97 10 19 0 1386498576 20072736 2229840
21:41:41 72454 8192000000 10302528480 10765852672 1387601760 14.484 99 15 30 0 1406188224 15538848 3047616
21:43:56 114218 8192000000 10282298944 10732355584 1360137568 14.239 98 36 72 1 1382654640 14192944 8324128
21:47:01 37715 8192000000 10273396560 10733510656 1357069744 14.211 99 18 36 0 1380451776 19493008 3889024
21:48:40 90927 8192000000 10287724272 10751037440 1390628928 14.511 100 12 24 0 1405753840 12028272 3096640
21:51:11 54434 8192000000 10265381600 10727727104 1346794384 14.119 97 18 36 0 1360834160 9653024 4386752
21:53:42 54966 8192000000 10256237712 10711293952 1363273392 14.267 100 12 24 0 1376053632 9906256 2873984
21:56:00 102976 8192000000 10270863648 10730151936 1334234048 14.005 97 4 8 0 1348467744 13331216 902480
21:58:43 34977 8192000000 10266378944 10711015424 1326621376 13.937 97 31 62 1 1346410832 12627344 7162112
22:00:21 54824 8192000000 10292859488 10742820864 1374303616 14.366 100 21 42 2 1394470944 15502432 4664896
22:02:17 67078 8192000000 10278528208 10721468416 1333231056 13.996 97 16 32 1 1352971328 15954384 3785888
22:04:33 36264 8192000000 10281387056 10724245504 1359194032 14.230 99 36 72 2 1376182816 9579472 7409312
22:06:17 64667 8192000000 10273434320 10723078144 1348116432 14.131 99 32 64 0 1368393072 13354576 6922064
22:08:23 83149 8192000000 10310550560 10751090688 1353494752 14.179 97 27 55 0 1370290592 11232656 5563184
22:10:56 117253 8192000000 10307441504 10759311360 1396469712 14.564 100 32 62 2 1422342800 18189408 7683680
22:13:54 63378 8192000000 10283573872 10748825600 1391738048 14.521 100 10 20 0 1402016240 8508448 1769744
22:15:58 56904 8192000000 10285892176 10748628992 1382992192 14.443 99 11 22 1 1396738176 10997616 2748368
22:18:10 92950 8192000000 10290260208 10751184896 1326245888 13.933 95 8 16 0 1339887600 11975296 1666416
22:20:48 76508 8192000000 10303908816 10751217664 1353570080 14.180 97 18 36 0 1369765488 12040848 4154560
22:23:07 99797 8192000000 10301050544 10765533184 1370129392 14.328 98 12 24 0 1385673168 12766480 2777296
22:26:22 36812 8192000000 10301210608 10748076032 1382293728 14.437 99 35 70 2 1408543760 18356976 7893056
22:28:17 58294 8192000000 10317499312 10768748544 1405979008 14.648 100 30 59 1 1421696448 9197152 6520288
22:30:18 117682 8192000000 10293551264 10755710976 1339734752 14.055 96 12 24 0 1356366704 14066368 2565584
22:33:18 25182 8192000000 10285750432 10735726592 1350155280 14.149 98 36 72 1 1372974688 14795504 8023904
22:34:45 53115 8192000000 10269634608 10731196416 1358374624 14.223 99 8 16 0 1377965872 17504736 2086512
22:36:50 62359 8192000000 9918216160 10321526784 1024772608 11.118 74 16 32 0 1043302096 15254880 3274608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2021 22:39:50 to 20-10-2021 22:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2021 22:40:06 to 20-10-2021 22:40:08.

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          230016
MON$OLDEST_ACTIVE               232927
MON$OLDEST_SNAPSHOT             232927
MON$NEXT_TRANSACTION            232927
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                       2018816
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:704: 
:705: mscs-test-161.budzdorov.ru    Wed Oct 20 22:37:56 2021
:706:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:707: 
:708: 
:709: mscs-test-161.budzdorov.ru    Wed Oct 20 22:37:57 2021
:710:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:711: 
:712: 
:713: mscs-test-161.budzdorov.ru    Wed Oct 20 22:37:58 2021
:714:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:715: 
:716: 
:717: mscs-test-161.budzdorov.ru    Wed Oct 20 22:38:10 2021
:718:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:719: 
--- end of diff output ---

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

20.10.21 22:40:09. 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-10-20 22:40:11 No severe PSQL-related problems occured
20.10.21 22:40:13. End of report.