Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.09.2021 16: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 16078704 kB
MemAvailable 30884680 kB
Buffers 0 kB
Cached 15580704 kB
SwapCached 336 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-09-01 16:37:48
DTS_END                         2021-09-01 16:37:48.0000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45862
TRN_ID                          242884
ATT_ID                          728
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: 9600.41 24006 1152049
customer order: creation 170.85 342 20502
customer order: refuse 14.04 334 1685
order to supplier: creation 46.73 1317 5607
order to supplier: removal 3.43 109195 412
invoice (draft): creation 46.24 3137 5549
invoice (draft): removal 54.67 845 6560
invoice accept: apply 42.48 18300 5098
invoice accept: cancel 48.92 883 5870
customer reserve: creation 4022.54 177 482705
customer reserve: removal 4844.12 60 581294
realization accept: apply 84.09 182 10091
realization accept: cancel 92.47 114 11096
payment from customer: creation 59.38 103 7126
payment from customer: removal 5.89 85 707
payment to supplier: creation 49.02 121 5882
payment to supplier: removal 3.35 89 402
service: total inventory turnovers 4.71 1090 565
service: total monetary turnovers 6.54 25 785
service: aggregate perf. data 0.91 19266 109
service: refresh index statistics 0.03 324458 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-09-2021 16:38:58 to 01-09-2021 16:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2119
TEST_TIME 2 2208
TEST_TIME 3 3961
TEST_TIME 4 4585
TEST_TIME 5 5031
TEST_TIME 6 5449
TEST_TIME 7 5219
TEST_TIME 8 5383
TEST_TIME 9 5336
TEST_TIME 10 5622
TEST_TIME 11 6057
TEST_TIME 12 6190
TEST_TIME 13 6509
TEST_TIME 14 6795
TEST_TIME 15 7108
TEST_TIME 16 7498
TEST_TIME 17 7371
TEST_TIME 18 7382
TEST_TIME 19 7536
TEST_TIME 20 7702
TEST_TIME 21 8653
TEST_TIME 22 8820
TEST_TIME 23 8953
TEST_TIME 24 9110
TEST_TIME 25 9293
TEST_TIME 26 9504
TEST_TIME 27 10042
TEST_TIME 28 11142
TEST_TIME 29 11056
TEST_TIME 30 11103
TEST_TIME 31 11065
TEST_TIME 32 11077
TEST_TIME 33 10821
TEST_TIME 34 10548
TEST_TIME 35 10572
TEST_TIME 36 10493
TEST_TIME 37 10377
TEST_TIME 38 10265
TEST_TIME 39 10208
TEST_TIME 40 10147
TEST_TIME 41 10109
TEST_TIME 42 10297
TEST_TIME 43 10244
TEST_TIME 44 10210
TEST_TIME 45 10129
TEST_TIME 46 10334
TEST_TIME 47 10181
TEST_TIME 48 10158
TEST_TIME 49 10098
TEST_TIME 50 10197
TEST_TIME 51 10106
TEST_TIME 52 10149
TEST_TIME 53 10140
TEST_TIME 54 10125
TEST_TIME 55 10078
TEST_TIME 56 10012
TEST_TIME 57 9999
TEST_TIME 58 10172
TEST_TIME 59 10254
TEST_TIME 60 10231
TEST_TIME 61 10203
TEST_TIME 62 10189
TEST_TIME 63 10038
TEST_TIME 64 9981
TEST_TIME 65 9887
TEST_TIME 66 9858
TEST_TIME 67 9849
TEST_TIME 68 9775
TEST_TIME 69 9697
TEST_TIME 70 9630
TEST_TIME 71 9565
TEST_TIME 72 9526
TEST_TIME 73 9605
TEST_TIME 74 9735
TEST_TIME 75 9716
TEST_TIME 76 9675
TEST_TIME 77 9746
TEST_TIME 78 9731
TEST_TIME 79 9734
TEST_TIME 80 9818
TEST_TIME 81 9830
TEST_TIME 82 9894
TEST_TIME 83 9925
TEST_TIME 84 9903
TEST_TIME 85 9882
TEST_TIME 86 9987
TEST_TIME 87 9993
TEST_TIME 88 9899
TEST_TIME 89 9858
TEST_TIME 90 9800
TEST_TIME 91 9806
TEST_TIME 92 9824
TEST_TIME 93 9776
TEST_TIME 94 9720
TEST_TIME 95 9682
TEST_TIME 96 9659
TEST_TIME 97 9672
TEST_TIME 98 9682
TEST_TIME 99 9705
TEST_TIME 100 9734
TEST_TIME 101 9761
TEST_TIME 102 9734
TEST_TIME 103 9752
TEST_TIME 104 9742
TEST_TIME 105 9743
TEST_TIME 106 9800
TEST_TIME 107 9807
TEST_TIME 108 9821
TEST_TIME 109 9807
TEST_TIME 110 9738
TEST_TIME 111 9727
TEST_TIME 112 9682
TEST_TIME 113 9651
TEST_TIME 114 9632
TEST_TIME 115 9613
TEST_TIME 116 9660
TEST_TIME 117 9627
TEST_TIME 118 9597
TEST_TIME 119 9581
TEST_TIME 120 9601

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-09-2021 16:39:01 to 01-09-2021 16:39:05.

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 1595078 1595078 0 0.00 0 40319 6 0 0
doc_list_biud 1595078 1595078 0 0.00 0 21340 28 0 0
sp_add_invoice_to_stock 5240 5098 142 2.70 37 222589 18300 0 142
sp_cancel_adding_invoice 5870 5870 0 0.00 4 94068 884 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 11041 334 0 0
sp_cancel_customer_reserve 581294 581294 0 0.00 1 17179 61 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 7673 85 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 5900 90 0 0
sp_cancel_supplier_invoice 6570 6560 10 0.15 1 17686 845 0 10
sp_cancel_supplier_order 412 412 0 0.00 7 1162632 109195 0 0
sp_cancel_write_off 11096 11096 0 0.00 1 20235 115 0 0
sp_client_order 20502 20502 0 0.00 3 19086 343 0 0
sp_customer_reserve 482705 482705 0 0.00 0 46038 177 0 0
sp_fill_shopping_cart 31643 31643 0 0.00 1 11944 37 0 0
sp_fill_shopping_cart_clo_res 482704 482704 0 0.00 0 12726 12 0 0
sp_get_clo_for_invoice 5097 5097 0 0.00 0 30574 305 0 0
sp_kill_cost_storno 18075 18075 0 0.00 0 6222 59 0 0
sp_kill_qstorno_ret_qs2qd 1180656 1180656 0 0.00 0 17408 33 0 0
sp_kill_qty_storno 622104 622104 0 0.00 0 21336 71 0 0
sp_lock_selected_doc 20945 20945 0 0.00 0 8812 6 0 0
sp_make_cost_storno 28195 28195 0 0.00 0 40315 54 0 0
sp_multiply_rows_for_pdistr 28195 28195 0 0.00 0 6625 29 0 0
sp_multiply_rows_for_qdistr 20502 20502 0 0.00 0 12250 152 0 0
sp_pay_from_customer 7126 7126 0 0.00 4 11341 103 0 0
sp_pay_to_supplier 5882 5882 0 0.00 4 9992 122 0 0
sp_payment_common 13008 13008 0 0.00 1 11330 27 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 11035 308 0 0
sp_qd_handle_on_invoice_upd_sts 10966 10966 0 0.00 0 21328 335 0 0
sp_qd_handle_on_reserve_upd_sts 10091 10091 0 0.00 0 12283 58 0 0
sp_reserve_write_off 10091 10091 0 0.00 6 40328 183 0 0
sp_supplier_invoice 5549 5549 0 0.00 7 69161 3137 0 0
sp_supplier_order 5607 5607 0 0.00 5 49518 1318 0 0
srv_aggregate_perf_data 109 109 0 0.00 2084 287338 19266 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1761 135516 46149 0 0
srv_find_qd_qs_mism 1101540 1101540 0 0.00 0 20225 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 26 32835 1090 0 0
srv_make_money_saldo 785 785 0 0.00 0 590 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 205375 511212 324459 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5098 5098 0 0.00 0 10929 183 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 8537 134 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 1388 15 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 2901 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 3115 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 188 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 4575 33 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 44569 1756 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 2327 7 0 0
t$perf-norm:sp_client_order 20502 20502 0 0.00 0 12765 23 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 4779 19 0 0
t$perf-norm:sp_pay_from_customer 7126 7126 0 0.00 0 3998 10 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 11144 16 0 0
t$perf-norm:sp_reserve_write_off 10091 10091 0 0.00 0 13130 12 0 0
t$perf-norm:sp_supplier_invoice 5549 5549 0 0.00 0 17727 23 0 0
t$perf-norm:sp_supplier_order 5607 5607 0 0.00 0 12560 22 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 345 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 25 1 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 24808 71 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 6087 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 1728 436 0 0
v_add_invoice_to_stock 10476 10192 284 2.71 0 5985 19 0 284
v_all_customers 37436 37436 0 0.00 0 22 1 0 0
v_all_suppliers 30926 30926 0 0.00 0 23 1 0 0
v_all_wares 41002 41002 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 1767 11 0 0
v_cancel_client_order 3370 3370 0 0.00 0 3679 6 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 1955 7 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 983 4 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2072 11 0 0
v_cancel_supplier_invoice 896 876 20 2.23 0 4008 23 0 20
v_cancel_supplier_order 824 824 0 0.00 0 3161 9 0 0
v_cancel_write_off 1974 1974 0 0.00 0 5648 16 0 0
v_max_id_clo_ord 5607 5607 0 0.00 0 3982 3 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 2822 12 0 0
v_max_id_ord_sup 5535 5535 0 0.00 0 6240 8 0 0
v_max_non_paid_invoice 1561 1561 0 0.00 0 2291 5 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 1411 3 0 0
v_min_id_clo_ord 5607 5607 0 0.00 0 3857 8 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 7626 13 0 0
v_min_id_ord_sup 5535 5535 0 0.00 0 4161 4 0 0
v_min_non_paid_invoice 5882 5882 0 0.00 0 6605 14 0 0
v_min_non_paid_realizn 7126 7126 0 0.00 0 5346 17 0 0
v_our_firm 10162 10162 0 0.00 0 19 1 0 0
v_reserve_write_off 20182 20182 0 0.00 0 6150 6 0 0
x_lock_depdocs_on_canc_invoice 11992 11992 0 0.00 0 18088 123 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 3194 327 0 0
x_make_qstorno_1000_1200 5607 5607 0 0.00 2 45510 1232 0 0
x_make_qstorno_1200_2000 5549 5549 0 0.00 3 69141 3009 0 0
x_make_qstorno_2100_3300 482705 482705 0 0.00 0 46035 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-09-2021 16:39:06 to 01-09-2021 16:39:10.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:52 30567 8192000000 10402248464 10727645184 1443738352 14.983 100 84 168 0 1477142944 6457088 26947504
14:10:26 20198 8192000000 10453156544 10788855808 1502534512 15.498 100 83 166 3 1537678128 8776544 26367072
14:11:51 27923 8192000000 10497893712 10840158208 1550922832 15.918 100 87 174 0 1587789408 9790576 27076000
14:13:23 11412 8192000000 10556030432 10916528128 1618687616 16.499 100 78 156 1 1652776576 10255248 23833712
14:14:38 7177 8192000000 10637846080 11008225280 1697961168 17.168 100 83 167 1 1734883088 12764640 24157280
14:15:49 4339 8192000000 10692315616 11069976576 1757398640 17.663 100 74 141 1 1789210592 11975632 19836320
14:16:55 2212 8192000000 10756131728 11136286720 1812731424 18.118 100 91 181 1 1851541456 13421792 25388240
14:18:00 2353 8192000000 10804385232 11191713792 1854931568 18.462 100 90 178 2 1893087936 13032480 25123888
14:19:03 26032 8192000000 10791495152 11204243456 1869647040 18.581 100 47 94 0 1893426208 10529840 13249328
14:20:32 7093 8192000000 10797566560 11214245888 1878512128 18.653 100 24 48 1 1891962016 6816272 6633616
14:21:40 9284 8192000000 10789221040 11225137152 1886388304 18.717 100 19 38 0 1898294784 6263584 5642896
14:22:51 6963 8192000000 10811016000 11250675712 1910630816 18.912 100 21 42 3 1923247280 6177632 6438832
14:23:58 30639 8192000000 10863175072 11272974336 1920544400 18.991 100 55 113 4 1941899936 5157408 16198128
14:25:29 8247 8192000000 10840628800 11277905920 1925583376 19.032 100 21 42 0 1937013968 5422368 6008224
14:26:40 8130 8192000000 10821887152 11257823232 1891353424 18.757 99 17 34 3 1901423680 5674000 4396256
14:27:50 63018 8192000000 10758958832 11196604416 1748235488 17.587 92 28 55 2 1761335632 5037040 8063104
14:29:57 6117 8192000000 10735728496 11156107264 1810283664 18.098 99 28 57 0 1824261216 6114016 7863536
14:31:03 9206 8192000000 10706203472 11124137984 1724495296 17.390 95 25 49 1 1737919632 5699184 7725152
14:32:16 56199 8192000000 10715496224 11135717376 1783541824 17.879 98 27 54 2 1796207920 4954272 7711824
14:34:17 8861 8192000000 10762032832 11186397184 1845795472 18.388 100 24 47 0 1858429696 5895504 6738720
14:35:28 6608 8192000000 10774851712 11210113024 1867333696 18.563 100 15 30 0 1876670896 5125568 4211632
14:36:36 11148 8192000000 10789497280 11225964544 1880455088 18.669 100 20 38 3 1891844704 5646832 5742784
14:37:49 60330 8192000000 10855249920 11273101312 1915112976 18.948 100 41 80 2 1932075632 5958240 11004416
14:39:54 9729 8192000000 10882989904 11304456192 1941340192 19.157 100 62 123 1 1971400048 12571120 17488736
14:41:10 15720 8192000000 10914097296 11336949760 1963357072 19.333 100 55 110 1 1999797104 19993216 16446816
14:42:31 10214 8192000000 10929614400 11360489472 1979183536 19.458 100 48 93 1 2017694464 25078464 13432464
14:43:45 5008 8192000000 10945193280 11374878720 1976707008 19.439 99 57 114 0 2019651200 25776448 17167744
14:44:51 14671 8192000000 10965836256 11393785856 1992240032 19.561 99 68 134 1 2039880496 28356352 19284112
14:46:14 7040 8192000000 10954603968 11389222912 2001148096 19.632 100 56 110 1 2047972944 30476848 16348000
14:47:24 2018 8192000000 11001298448 11421700096 2021654576 19.793 100 82 164 3 2084411104 38123792 24632736
14:48:29 4009 8192000000 10989736992 11401687040 2006759632 19.676 100 78 154 3 2068758480 38071968 23926880
14:49:38 1761 8192000000 10995450480 11417485312 2017886208 19.764 100 76 153 5 2079080400 37683584 23510608
14:50:43 4810 8192000000 10987981808 11413848064 2007523168 19.682 100 67 134 0 2073491440 44586560 21381712
14:51:54 2360 8192000000 10952438816 11368296448 1947637472 19.208 98 82 164 2 2021112608 48377488 25097648
14:53:04 8854 8192000000 10983908064 11402919936 1992104272 19.560 100 66 131 1 2059422624 46575472 20742880
14:54:20 2002 8192000000 10954720928 11363127296 1954343840 19.261 99 89 173 2 2023521792 43086416 26091536
14:55:30 16393 8192000000 10961228928 11388358656 1970981072 19.393 100 63 126 0 2045740640 54581440 20178128
14:56:51 2130 8192000000 10967290768 11373199360 1955730000 19.272 99 88 173 0 2047156704 63744864 27681840
14:58:00 2114 8192000000 10978522192 11391954944 1962769120 19.328 100 89 178 1 2060215680 68798320 28648240
14:59:06 24364 8192000000 10943580512 11387600896 1946021520 19.195 99 37 73 2 2016839792 58710592 12107680
15:00:33 28057 8192000000 10938078320 11383029760 1954775392 19.264 100 27 54 1 2016663392 52791120 9096880
15:02:17 16954 8192000000 10931388592 11388014592 1950814256 19.233 100 25 48 1 2019530880 59917168 8799456
15:03:35 35297 8192000000 10929105872 11382452224 1916292256 18.957 99 17 34 0 1988273344 65050768 6930320
15:05:23 31223 8192000000 10871129648 11333726208 1910021216 18.907 98 12 24 1 1932792848 18575120 4196512
15:07:20 37476 8192000000 10870076064 11326754816 1880821712 18.672 96 36 65 5 1903816128 13046416 9948000
15:08:59 135516 8192000000 10879133440 11329536000 1864320160 18.538 95 29 59 3 1881274400 9249888 7704352
15:12:24 91844 8192000000 10899390640 11357429760 1946507344 19.199 99 17 32 0 1960188256 9624544 4056368
15:14:57 32942 8192000000 10900581168 11362463744 1952552304 19.247 99 16 32 0 1966515328 9666768 4296256
15:17:08 39885 8192000000 10913472176 11370160128 1955964080 19.274 99 34 68 1 1979799488 12940528 10894880
15:18:51 26625 8192000000 10928828736 11386130432 1976215600 19.435 100 59 116 1 2016434592 22198432 18020560
15:20:19 57566 8192000000 10906308512 11377786880 1963910896 19.337 99 35 70 2 1991414032 17318384 10184752
15:22:26 42399 8192000000 10907234720 11384774656 1942962160 19.170 98 11 22 0 1972025632 25286544 3776928
15:24:51 27079 8192000000 10911795920 11390476288 1977480720 19.445 100 19 38 0 2000272464 17506976 5284768
15:26:18 87552 8192000000 10905855856 11399225344 1953176432 19.252 98 19 38 0 1979640368 21143712 5320224
15:29:14 57662 8192000000 10927689200 11400519680 1975845184 19.432 99 17 34 0 1991996784 11006224 5145376
15:31:22 33433 8192000000 10902222912 11367325696 1938379568 19.134 98 23 46 2 1956943408 11810240 6753600
15:33:49 66388 8192000000 10921113056 11376861184 1907900352 18.890 96 17 34 1 1933906288 20608400 5397536
15:36:03 69633 8192000000 10880886592 11350315008 1890107552 18.747 96 10 21 1 1904707472 12377152 2222768
15:38:22 129679 8192000000 10879188752 11359989760 1864033360 18.536 95 11 22 0 1882639024 16231040 2374624
15:41:47 39884 8192000000 10854274512 11293204480 1807969056 18.079 94 37 74 1 1827132064 8311984 10851024
15:43:29 44988 8192000000 10825323952 11304624128 1899961216 18.826 99 26 52 0 1916147024 9431264 6754544
15:45:15 64658 8192000000 10853944096 11316404224 1895958256 18.794 98 17 33 3 1911572912 10935744 4678912
15:47:21 66346 8192000000 10869311824 11342741504 1910472640 18.910 98 20 40 2 1935355488 18717280 6165568
15:49:27 66527 8192000000 10882400400 11347546112 1905902848 18.874 98 24 48 3 1940892336 27472640 7516848
15:51:54 84262 8192000000 10883180672 11357315072 1908152896 18.892 98 18 36 0 1927600128 14518960 4928272
15:54:18 65832 8192000000 10900225008 11362725888 1945087168 19.187 99 25 50 0 1964952464 12214144 7651152
15:56:24 69281 8192000000 10915328832 11381211136 1967394608 19.365 100 11 21 1 1992050368 21349040 3306720
15:58:33 77408 8192000000 10902412912 11390795776 1936976288 19.123 98 26 52 0 1966905648 22531168 7398192
16:00:52 72211 8192000000 10927307024 11384803328 1916992736 18.963 97 14 27 1 1932735008 12060208 3682064
16:03:07 72171 8192000000 10906652464 11397255168 1956301840 19.277 99 27 54 2 1983758656 19832832 7623984
16:05:20 56076 8192000000 10909269904 11378143232 1976785232 19.439 100 15 30 2 1991016992 9800864 4430896
16:07:17 68822 8192000000 10900394832 11391901696 1973363008 19.412 100 14 28 0 1995345232 18412672 3569552
16:09:31 47803 8192000000 10889035776 11368951808 1941317648 19.157 98 15 30 0 1953397360 8268944 3810768
16:11:19 81910 8192000000 10908244896 11397242880 1986464304 19.516 100 11 22 0 1999748304 9932336 3351664
16:13:41 48907 8192000000 10931863936 11391692800 1955347008 19.269 98 28 56 0 1976533232 13614016 7572208
16:15:36 57873 8192000000 10915803632 11402297344 1972200704 19.403 99 20 40 0 1992199712 14390320 5608688
16:17:56 87251 8192000000 10919816368 11408928768 1963889776 19.337 98 24 48 0 1984917680 13891840 7136064
16:20:24 44017 8192000000 10935305168 11404722176 1978625248 19.454 99 22 44 1 1997670768 13027344 6018176
16:22:19 41253 8192000000 10893009824 11350511616 1847857392 18.405 93 28 54 2 1866166816 10246880 8062544
16:24:14 41372 8192000000 10880905952 11352326144 1922311792 19.005 98 11 22 1 1942467072 16836032 3319248
16:25:59 59483 8192000000 10893762288 11372535808 1931948752 19.082 98 19 38 0 1948907200 12061696 4896752
16:27:59 73713 8192000000 10852598336 11330531328 1861603824 18.516 95 19 38 0 1877866288 10871984 5390480
16:31:00 74536 8192000000 10837758480 11319672832 1846666528 18.395 95 10 20 1 1863716480 13996416 3053536
16:33:15 64849 8192000000 10840531920 11315884032 1874595056 18.621 97 18 36 0 1894431968 14107792 5729120
16:35:45 95107 8192000000 10858291584 11334963200 1849904832 18.421 95 17 34 2 1868419472 14398128 4116512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-09-2021 16:39:11 to 01-09-2021 16:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-09-2021 16:39:27 to 01-09-2021 16:39:29.

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          241725
MON$OLDEST_ACTIVE               243019
MON$OLDEST_SNAPSHOT             243019
MON$NEXT_TRANSACTION            243019
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                       2041696
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.33490 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33490 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33490 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33490 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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Wed Sep  1 16:37:52 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Wed Sep  1 16:37:52 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Wed Sep  1 16:37:52 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Wed Sep  1 16:37:52 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Wed Sep  1 16:37:52 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Wed Sep  1 16:37:52 2021
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
--- end of diff output ---

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

01.09.21 16:39:30. 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-09-01 16:39:32 No severe PSQL-related problems occured
01.09.21 16:39:34. End of report.