Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2021 19: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 15961080 kB
MemAvailable 30906028 kB
Buffers 0 kB
Cached 15752872 kB
SwapCached 4404 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-24 19:37:50
DTS_END                         2021-10-24 19:37:50.3010
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/57152
TRN_ID                          240839
ATT_ID                          650
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: 9422.03 19656 1130643
customer order: creation 170.04 347 20405
customer order: refuse 14.55 374 1746
order to supplier: creation 45.36 1295 5443
order to supplier: removal 3.37 113414 404
invoice (draft): creation 46.86 3614 5623
invoice (draft): removal 54.59 864 6551
invoice accept: apply 41.13 17829 4935
invoice accept: cancel 49.05 754 5886
customer reserve: creation 3882.95 171 465954
customer reserve: removal 4817.54 62 578105
realization accept: apply 85.13 188 10216
realization accept: cancel 82.66 115 9919
payment from customer: creation 58.23 116 6987
payment from customer: removal 6.21 68 745
payment to supplier: creation 48.55 151 5826
payment to supplier: removal 3.76 75 451
service: total inventory turnovers 4.84 1240 581
service: total monetary turnovers 6.47 28 776
service: aggregate perf. data 0.72 24384 86
service: refresh index statistics 0.03 228024 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2021 19:39:54 to 24-10-2021 19:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4251
TEST_TIME 2 3212
TEST_TIME 3 4597
TEST_TIME 4 4117
TEST_TIME 5 4247
TEST_TIME 6 4623
TEST_TIME 7 4499
TEST_TIME 8 4460
TEST_TIME 9 4638
TEST_TIME 10 5396
TEST_TIME 11 5445
TEST_TIME 12 5689
TEST_TIME 13 5557
TEST_TIME 14 6053
TEST_TIME 15 6123
TEST_TIME 16 6113
TEST_TIME 17 6195
TEST_TIME 18 6331
TEST_TIME 19 6542
TEST_TIME 20 6841
TEST_TIME 21 6769
TEST_TIME 22 7383
TEST_TIME 23 7264
TEST_TIME 24 7557
TEST_TIME 25 8006
TEST_TIME 26 8322
TEST_TIME 27 8247
TEST_TIME 28 8505
TEST_TIME 29 8800
TEST_TIME 30 10400
TEST_TIME 31 10465
TEST_TIME 32 10527
TEST_TIME 33 10512
TEST_TIME 34 10349
TEST_TIME 35 10387
TEST_TIME 36 10209
TEST_TIME 37 10028
TEST_TIME 38 10060
TEST_TIME 39 10035
TEST_TIME 40 9931
TEST_TIME 41 9824
TEST_TIME 42 9818
TEST_TIME 43 9812
TEST_TIME 44 9807
TEST_TIME 45 9826
TEST_TIME 46 9726
TEST_TIME 47 9708
TEST_TIME 48 9700
TEST_TIME 49 9697
TEST_TIME 50 9586
TEST_TIME 51 9634
TEST_TIME 52 9620
TEST_TIME 53 9562
TEST_TIME 54 9422
TEST_TIME 55 9361
TEST_TIME 56 9394
TEST_TIME 57 9388
TEST_TIME 58 9462
TEST_TIME 59 9462
TEST_TIME 60 9560
TEST_TIME 61 9602
TEST_TIME 62 9670
TEST_TIME 63 9698
TEST_TIME 64 9754
TEST_TIME 65 9666
TEST_TIME 66 9882
TEST_TIME 67 9987
TEST_TIME 68 9887
TEST_TIME 69 9984
TEST_TIME 70 9861
TEST_TIME 71 9781
TEST_TIME 72 9746
TEST_TIME 73 9683
TEST_TIME 74 9715
TEST_TIME 75 9661
TEST_TIME 76 9608
TEST_TIME 77 9593
TEST_TIME 78 9496
TEST_TIME 79 9505
TEST_TIME 80 9544
TEST_TIME 81 9553
TEST_TIME 82 9524
TEST_TIME 83 9590
TEST_TIME 84 9561
TEST_TIME 85 9605
TEST_TIME 86 9618
TEST_TIME 87 9622
TEST_TIME 88 9610
TEST_TIME 89 9644
TEST_TIME 90 9702
TEST_TIME 91 9707
TEST_TIME 92 9643
TEST_TIME 93 9568
TEST_TIME 94 9536
TEST_TIME 95 9507
TEST_TIME 96 9495
TEST_TIME 97 9425
TEST_TIME 98 9427
TEST_TIME 99 9456
TEST_TIME 100 9469
TEST_TIME 101 9424
TEST_TIME 102 9450
TEST_TIME 103 9442
TEST_TIME 104 9557
TEST_TIME 105 9568
TEST_TIME 106 9600
TEST_TIME 107 9601
TEST_TIME 108 9605
TEST_TIME 109 9627
TEST_TIME 110 9706
TEST_TIME 111 9683
TEST_TIME 112 9616
TEST_TIME 113 9591
TEST_TIME 114 9544
TEST_TIME 115 9538
TEST_TIME 116 9530
TEST_TIME 117 9512
TEST_TIME 118 9493
TEST_TIME 119 9447
TEST_TIME 120 9428

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-10-2021 19:39:57 to 24-10-2021 19:40:01.

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 1561973 1561973 0 0.00 0 25887 6 0 0
doc_list_biud 1561973 1561973 0 0.00 0 24396 29 0 0
sp_add_invoice_to_stock 5047 4935 112 2.21 39 211167 17830 0 112
sp_cancel_adding_invoice 5886 5886 0 0.00 5 87657 754 0 0
sp_cancel_client_order 1746 1746 0 0.00 5 12776 374 0 0
sp_cancel_customer_reserve 578105 578105 0 0.00 1 24399 62 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 2 3698 68 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 4803 75 0 0
sp_cancel_supplier_invoice 6559 6551 8 0.12 2 13681 864 0 8
sp_cancel_supplier_order 404 404 0 0.00 7 1129811 113414 0 0
sp_cancel_write_off 9919 9919 0 0.00 1 8563 116 0 0
sp_client_order 20405 20405 0 0.00 3 22206 348 0 0
sp_customer_reserve 465954 465954 0 0.00 0 43937 172 0 0
sp_fill_shopping_cart 31462 31462 0 0.00 1 15947 40 0 0
sp_fill_shopping_cart_clo_res 465947 465947 0 0.00 0 17444 14 0 0
sp_get_clo_for_invoice 4927 4927 0 0.00 0 17951 306 0 0
sp_kill_cost_storno 17001 17001 0 0.00 0 13667 57 0 0
sp_kill_qstorno_ret_qs2qd 1173084 1173084 0 0.00 0 24357 34 0 0
sp_kill_qty_storno 617754 617754 0 0.00 0 24395 73 0 0
sp_lock_selected_doc 21038 21038 0 0.00 0 7794 7 0 0
sp_make_cost_storno 27956 27956 0 0.00 0 25873 59 0 0
sp_multiply_rows_for_pdistr 27956 27956 0 0.00 0 13193 30 0 0
sp_multiply_rows_for_qdistr 20405 20405 0 0.00 0 22198 171 0 0
sp_pay_from_customer 6987 6987 0 0.00 4 18843 117 0 0
sp_pay_to_supplier 5826 5826 0 0.00 4 17576 152 0 0
sp_payment_common 12813 12813 0 0.00 1 18838 28 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 12757 335 0 0
sp_qd_handle_on_invoice_upd_sts 10813 10813 0 0.00 0 14224 335 0 0
sp_qd_handle_on_reserve_upd_sts 10216 10216 0 0.00 0 20310 63 0 0
sp_reserve_write_off 10216 10216 0 0.00 7 20363 189 0 0
sp_supplier_invoice 5623 5623 0 0.00 7 90066 3615 0 0
sp_supplier_order 5443 5443 0 0.00 6 31989 1295 0 0
srv_aggregate_perf_data 86 86 0 0.00 2214 443236 24384 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2142 138878 44978 0 0
srv_find_qd_qs_mism 1082867 1082867 0 0.00 0 17358 5 0 0
srv_make_invnt_saldo 581 581 0 0.00 39 78667 1240 0 0
srv_make_money_saldo 776 776 0 0.00 1 4219 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 65894 301882 228025 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 4936 4936 0 0.00 0 15867 167 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 18237 123 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 2366 17 0 0
t$perf-norm:sp_cancel_customer_reserve 763 763 0 0.00 0 4807 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 769 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 2144 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1608 16 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 23965 1576 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 3826 14 0 0
t$perf-norm:sp_client_order 20405 20405 0 0.00 0 6994 19 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 1511 6 0 0
t$perf-norm:sp_pay_from_customer 6987 6987 0 0.00 0 3860 8 0 0
t$perf-norm:sp_pay_to_supplier 5826 5826 0 0.00 0 10036 13 0 0
t$perf-norm:sp_reserve_write_off 10216 10216 0 0.00 0 11888 15 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 3540 17 0 0
t$perf-norm:sp_supplier_order 5443 5443 0 0.00 0 10007 20 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 226 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 114 4 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 997 25 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 1633 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10078 9854 224 2.22 0 7497 22 0 224
v_all_customers 36676 36676 0 0.00 0 112 1 0 0
v_all_suppliers 30534 30534 0 0.00 0 102 1 0 0
v_all_wares 40810 40810 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 1473 9 0 0
v_cancel_client_order 3492 3492 0 0.00 0 5219 9 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 3683 9 0 0
v_cancel_customer_reserve 1526 1526 0 0.00 0 2805 6 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 4794 12 0 0
v_cancel_supplier_invoice 800 784 16 2.00 0 2337 19 0 16
v_cancel_supplier_order 808 808 0 0.00 0 996 5 0 0
v_cancel_write_off 2014 2014 0 0.00 0 3035 22 0 0
v_max_id_clo_ord 5442 5442 0 0.00 0 869 1 0 0
v_max_id_clo_res 973 973 0 0.00 0 1594 11 0 0
v_max_id_ord_sup 5615 5615 0 0.00 0 3387 6 0 0
v_max_non_paid_invoice 1616 1616 0 0.00 0 9748 9 0 0
v_max_non_paid_realizn 3899 3899 0 0.00 0 18835 10 0 0
v_min_id_clo_ord 5442 5442 0 0.00 0 10594 15 0 0
v_min_id_clo_res 973 973 0 0.00 0 2008 6 0 0
v_min_id_ord_sup 5615 5615 0 0.00 0 6974 7 0 0
v_min_non_paid_invoice 5826 5826 0 0.00 0 8767 20 0 0
v_min_non_paid_realizn 6987 6987 0 0.00 0 3942 17 0 0
v_our_firm 10310 10310 0 0.00 0 16 1 0 0
v_reserve_write_off 20432 20432 0 0.00 0 6258 7 0 0
x_lock_depdocs_on_canc_invoice 12045 12045 0 0.00 0 12885 109 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 10428 369 0 0
x_make_qstorno_1000_1200 5443 5443 0 0.00 2 31984 1204 0 0
x_make_qstorno_1200_2000 5623 5623 0 0.00 3 89996 3480 0 0
x_make_qstorno_2100_3300 465954 465954 0 0.00 0 43932 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2021 19:40:02 to 24-10-2021 19:40:06.

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
17:07:54 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:55 27727 8192000000 9905432464 10220331008 989600112 10.778 100 91 182 0 1020016512 6552336 23864064
17:10:28 26749 8192000000 9967703328 10294411264 1045803792 11.320 100 93 186 1 1078766000 9095248 23866960
17:12:07 16656 8192000000 9996491952 10331348992 1078043648 11.629 100 85 170 1 1110088816 11091312 20953856
17:13:35 8053 8192000000 10027931600 10376708096 1116629600 11.995 100 81 163 3 1147465168 11586896 19248672
17:14:46 7721 8192000000 10077032224 10437193728 1166607632 12.465 100 81 162 2 1198322512 13288016 18426864
17:16:00 4429 8192000000 10128112640 10497630208 1210398048 12.873 100 82 164 0 1242359136 13624128 18336960
17:17:07 4868 8192000000 10152568592 10529861632 1243090016 13.175 100 82 164 0 1274174400 13295760 17788624
17:18:14 2134 8192000000 10188601168 10570526720 1274741984 13.465 100 96 191 1 1309030784 13383152 20905648
17:19:18 3365 8192000000 10186367104 10585210880 1284398432 13.553 100 54 108 1 1307130784 10778352 11954000
17:20:28 8804 8192000000 10187078752 10595532800 1292178160 13.624 100 27 55 1 1304598064 6170560 6249344
17:21:39 6555 8192000000 10200506240 10614185984 1307120256 13.760 100 29 58 0 1320625120 6864992 6639872
17:22:47 12374 8192000000 10201947600 10623246336 1317838640 13.857 100 10 20 2 1325268768 5455280 1974848
17:24:00 77068 8192000000 10213638640 10641465344 1325290448 13.925 100 29 58 0 1337822512 5573888 6958176
17:26:19 5980 8192000000 10228102784 10647973888 1330158448 13.969 100 31 62 1 1344064592 6594304 7311840
17:27:26 4677 8192000000 10219761664 10642542592 1310179680 13.788 98 21 42 2 1321269424 6131952 4957792
17:28:32 69447 8192000000 10141136496 10545106944 1137729776 12.194 86 28 56 2 1154720800 9955344 7035680
17:30:43 6336 8192000000 10140200016 10533408768 1195519312 12.735 95 32 66 2 1209349984 6215760 7614912
17:31:51 2681 8192000000 10150753600 10545709056 1242108784 13.166 99 36 73 2 1256166656 5419424 8638448
17:32:55 16260 8192000000 10177247264 10572779520 1265507904 13.380 99 30 61 1 1277664400 4548544 7607952
17:34:45 9320 8192000000 10179234128 10593333248 1294482000 13.645 100 23 44 2 1304520384 5528352 4510032
17:35:55 7915 8192000000 10206922960 10614697984 1304444016 13.736 100 21 42 0 1315342320 5643360 5254944
17:37:08 8938 8192000000 10221435008 10633981952 1323011136 13.904 100 24 46 1 1334139056 5911760 5216160
17:38:28 25130 8192000000 10241617248 10659803136 1338519616 14.044 100 36 71 2 1353619504 5717600 9382288
17:40:01 25327 8192000000 10258435232 10679549952 1347985888 14.129 100 28 56 0 1363178240 8944864 6247488
17:41:35 22367 8192000000 10276910064 10698600448 1366101376 14.292 100 42 81 1 1384302064 9015200 9185488
17:43:04 19667 8192000000 10280779616 10704117760 1372538944 14.350 100 53 104 0 1397972704 12938160 12495600
17:44:47 15595 8192000000 10302398208 10730283008 1379260592 14.410 99 42 85 2 1409729872 20246544 10222736
17:46:10 4571 8192000000 10326096192 10750976000 1400928592 14.603 100 54 108 1 1438405136 24210864 13265680
17:47:25 7246 8192000000 10332576464 10759495680 1407548768 14.662 100 53 106 1 1445197440 24919120 12729552
17:48:34 34737 8192000000 10329612592 10758639616 1379492304 14.412 98 52 104 1 1421108928 29703872 11912752
17:50:17 10476 8192000000 10344941104 10778779648 1412803760 14.709 100 46 90 4 1454499296 29666336 12029200
17:51:32 2794 8192000000 10352900544 10776956928 1411618992 14.698 100 72 140 2 1460651040 31577888 17454160
17:52:43 3596 8192000000 10341680752 10759577600 1386753312 14.477 99 68 136 0 1445008384 40927024 17328048
17:53:54 7330 8192000000 10347013536 10765369344 1394893936 14.550 99 67 132 1 1454583104 43350208 16338960
17:55:05 5143 8192000000 10361455168 10776133632 1397688976 14.574 100 70 139 2 1465895568 50336112 17870480
17:56:15 3379 8192000000 10356933536 10760146944 1378326224 14.402 99 84 166 0 1452242800 53255552 20661024
17:57:24 3288 8192000000 10332528000 10746712064 1369249376 14.320 100 64 128 1 1442460944 56976272 16235296
17:58:37 2140 8192000000 10382379424 10786918400 1393887760 14.541 100 90 179 1 1480423632 63379600 23156272
17:59:43 13506 8192000000 10339877888 10764058624 1374613616 14.368 99 35 68 1 1443062016 58698064 9750336
18:00:59 7390 8192000000 10362186304 10774384640 1384202416 14.454 100 56 109 0 1461692656 62243408 15246832
18:02:11 24011 8192000000 10357684512 10783682560 1388245408 14.490 100 38 76 3 1465495008 66199728 11049872
18:03:50 20936 8192000000 10346264256 10766094336 1380148960 14.418 100 35 70 2 1443782176 53560384 10072832
18:05:14 36848 8192000000 10335602000 10777427968 1373946912 14.362 100 24 48 0 1440699392 59365584 7386896
18:07:06 19300 8192000000 10319918944 10783965184 1365662736 14.288 100 26 52 0 1431201776 58197184 7341856
18:08:48 69485 8192000000 10288218352 10741841920 1337357216 14.034 97 8 16 0 1353788608 14996528 1434864
18:11:00 61807 8192000000 10295005200 10727260160 1360741504 14.244 99 23 46 1 1377966560 11956048 5269008
18:13:02 104564 8192000000 10291831568 10735779840 1378425248 14.402 100 26 52 0 1395376208 11311376 5639584
18:16:02 55396 8192000000 10306972032 10754428928 1375735376 14.378 99 34 68 1 1393452688 10062576 7654736
18:18:03 50583 8192000000 10303868096 10755772416 1393584752 14.538 100 22 42 1 1407529808 9054064 4890992
18:19:56 30491 8192000000 10302091424 10755145728 1394269392 14.544 100 16 32 0 1408596464 10467312 3859760
18:21:27 10096 8192000000 10332580384 10763038720 1398163552 14.579 100 66 130 1 1426127456 10486208 17477696
18:22:37 54060 8192000000 10313825408 10777387008 1396906176 14.567 99 17 32 2 1413132464 12444800 3781488
18:24:32 50388 8192000000 10314318816 10784219136 1409240496 14.677 100 11 22 0 1429139488 16345456 3553536
18:26:22 32274 8192000000 10311539536 10768613376 1384830016 14.460 98 11 22 0 1397440432 9956560 2653856
18:27:58 93843 8192000000 10303814032 10789421056 1405786240 14.646 100 16 32 0 1423551056 13284896 4479920
18:30:40 34648 8192000000 10282104176 10748321792 1368882176 14.317 98 13 26 1 1378740720 7242800 2615744
18:32:35 48268 8192000000 10289689392 10762452992 1390376480 14.509 100 9 18 0 1405556160 13072976 2106704
18:35:14 53266 8192000000 10288852192 10748538880 1359712864 14.235 98 19 38 0 1378713152 14540560 4459728
18:37:08 53875 8192000000 10296706928 10759618560 1385197888 14.463 100 20 40 0 1402492704 12513344 4781472
18:39:03 78215 8192000000 10277357040 10735087616 1327983152 13.949 96 16 32 0 1347361040 15784704 3593184
18:41:23 55551 8192000000 10288747328 10735378432 1321118080 13.887 96 17 34 1 1339773024 14932960 3721984
18:43:19 76297 8192000000 10284377344 10745569280 1348906848 14.138 98 10 20 0 1374758224 23080320 2771056
18:46:30 85999 8192000000 10262780832 10708795392 1353688656 14.181 99 26 52 1 1370634704 10908448 6037600
18:49:18 39268 8192000000 10282574560 10721906688 1333190080 13.996 97 39 78 0 1351170592 9329056 8651456
18:51:05 52019 8192000000 10259129616 10726465536 1361357280 14.250 99 38 76 2 1380921104 11316560 8247264
18:52:59 56611 8192000000 10258106704 10712326144 1335776048 14.019 98 13 26 1 1348189488 9496560 2916880
18:54:57 74246 8192000000 10264635712 10717446144 1354780336 14.190 99 11 22 1 1369902992 12205488 2917168
18:57:35 32279 8192000000 10253276672 10700304384 1304049712 13.732 96 22 44 1 1316355904 6909520 5396672
18:59:17 81492 8192000000 10261427568 10719051776 1358512480 14.224 100 27 54 0 1379756016 14437168 6806368
19:01:48 58905 8192000000 10267861376 10719985664 1323226720 13.906 97 22 44 1 1346135424 18121568 4787136
19:03:48 81870 8192000000 10267623088 10722504704 1322899776 13.903 97 15 30 0 1335688416 9549424 3239216
19:06:10 31497 8192000000 10283145584 10731036672 1365489984 14.287 100 30 60 0 1384258000 12350016 6418000
19:07:44 103713 8192000000 10291407872 10743742464 1354015952 14.184 98 9 17 0 1368493728 12760496 1717280
19:10:28 70470 8192000000 10292098400 10745409536 1375543408 14.377 99 35 70 1 1395571424 12429392 7598624
19:12:51 73524 8192000000 10285240352 10750128128 1377315360 14.393 99 11 22 1 1389981456 10434464 2231632
19:15:06 63944 8192000000 10297824128 10760867840 1391486128 14.519 100 16 34 0 1409624928 13754736 4384064
19:17:19 65796 8192000000 10282377856 10740576256 1327083552 13.941 96 9 18 0 1341730080 12723088 1923440
19:19:37 58978 8192000000 10309423616 10774478848 1389575744 14.502 100 21 42 1 1425449328 30899840 4973744
19:21:37 73617 8192000000 10288484272 10735132672 1298437728 13.681 94 10 20 0 1319280768 18410896 2432144
19:23:53 43976 8192000000 10277003456 10740301824 1346755632 14.118 98 18 36 0 1369492976 18761424 3975920
19:25:37 42229 8192000000 10277914160 10724823040 1345838160 14.110 99 26 50 1 1371531808 19773152 5920496
19:27:24 138878 8192000000 10272386160 10739331072 1312733344 13.811 95 6 12 0 1326477168 12470848 1272976
19:30:47 51371 8192000000 10283815248 10718572544 1345152384 14.104 98 26 52 0 1359580880 8234560 6193936
19:32:43 51408 8192000000 10255929456 10722635776 1359035856 14.229 99 14 28 1 1370563328 8560160 2967312
19:34:44 54204 8192000000 10232262832 10682281984 1292091872 13.623 95 10 20 0 1302545088 8397456 2055760
19:36:42 64352 8192000000 10271900880 10726854656 1352794480 14.173 99 17 34 0 1374819936 17947552 4077904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-10-2021 19:40:08 to 24-10-2021 19:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 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 24-10-2021 19:40:23 to 24-10-2021 19:40:25.

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          239332
MON$OLDEST_ACTIVE               241167
MON$OLDEST_SNAPSHOT             241167
MON$NEXT_TRANSACTION            241167
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                       2051296
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:143: 
:144: mscs-test-161.budzdorov.ru    Sun Oct 24 19:38:04 2021
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:146: 
:147: 
:148: mscs-test-161.budzdorov.ru    Sun Oct 24 19:38:16 2021
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:150: 
:151: 
:152: mscs-test-161.budzdorov.ru    Sun Oct 24 19:38:20 2021
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:154: 
--- end of diff output ---

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

24.10.21 19:40:26. 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-24 19:40:29 No severe PSQL-related problems occured
24.10.21 19:40:30. End of report.