Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.08.2021 22:37
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 15937476 kB
MemAvailable 30990256 kB
Buffers 0 kB
Cached 15690632 kB
SwapCached 2592 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-09 22:37:44
DTS_END                         2021-08-09 22:37:44.3160
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/53380
TRN_ID                          241073
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: 9760.88 22742 1171306
customer order: creation 169.81 347 20377
customer order: refuse 14.82 353 1778
order to supplier: creation 44.04 1342 5285
order to supplier: removal 3.59 120823 431
invoice (draft): creation 45.00 3379 5400
invoice (draft): removal 57.03 769 6843
invoice accept: apply 41.53 20458 4983
invoice accept: cancel 52.03 768 6243
customer reserve: creation 3899.68 200 467961
customer reserve: removal 5129.94 67 615593
realization accept: apply 82.62 202 9914
realization accept: cancel 91.95 111 11034
payment from customer: creation 57.99 150 6959
payment from customer: removal 6.02 85 722
payment to supplier: creation 48.33 135 5799
payment to supplier: removal 3.65 96 438
service: total inventory turnovers 4.97 1355 597
service: total monetary turnovers 6.97 41 836
service: aggregate perf. data 0.91 17891 109
service: refresh index statistics 0.03 286262 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-08-2021 22:38:22 to 09-08-2021 22:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1929
TEST_TIME 2 2664
TEST_TIME 3 3448
TEST_TIME 4 4218
TEST_TIME 5 4375
TEST_TIME 6 4584
TEST_TIME 7 5159
TEST_TIME 8 5471
TEST_TIME 9 6158
TEST_TIME 10 6605
TEST_TIME 11 6439
TEST_TIME 12 7214
TEST_TIME 13 7096
TEST_TIME 14 7167
TEST_TIME 15 7194
TEST_TIME 16 7264
TEST_TIME 17 7445
TEST_TIME 18 7564
TEST_TIME 19 7636
TEST_TIME 20 7632
TEST_TIME 21 7782
TEST_TIME 22 8000
TEST_TIME 23 8130
TEST_TIME 24 8473
TEST_TIME 25 8685
TEST_TIME 26 8799
TEST_TIME 27 8797
TEST_TIME 28 9422
TEST_TIME 29 9539
TEST_TIME 30 9937
TEST_TIME 31 10437
TEST_TIME 32 11473
TEST_TIME 33 11377
TEST_TIME 34 11236
TEST_TIME 35 11088
TEST_TIME 36 10953
TEST_TIME 37 10920
TEST_TIME 38 10763
TEST_TIME 39 10823
TEST_TIME 40 10699
TEST_TIME 41 10743
TEST_TIME 42 10626
TEST_TIME 43 10452
TEST_TIME 44 10380
TEST_TIME 45 10264
TEST_TIME 46 10206
TEST_TIME 47 10407
TEST_TIME 48 10352
TEST_TIME 49 10260
TEST_TIME 50 10260
TEST_TIME 51 10197
TEST_TIME 52 10087
TEST_TIME 53 10024
TEST_TIME 54 10008
TEST_TIME 55 9992
TEST_TIME 56 10016
TEST_TIME 57 9986
TEST_TIME 58 10089
TEST_TIME 59 10089
TEST_TIME 60 10060
TEST_TIME 61 10036
TEST_TIME 62 10054
TEST_TIME 63 10121
TEST_TIME 64 10112
TEST_TIME 65 10175
TEST_TIME 66 10153
TEST_TIME 67 10176
TEST_TIME 68 10127
TEST_TIME 69 10148
TEST_TIME 70 10185
TEST_TIME 71 10158
TEST_TIME 72 10095
TEST_TIME 73 10024
TEST_TIME 74 10003
TEST_TIME 75 9921
TEST_TIME 76 9987
TEST_TIME 77 9899
TEST_TIME 78 9827
TEST_TIME 79 9800
TEST_TIME 80 9732
TEST_TIME 81 9754
TEST_TIME 82 9716
TEST_TIME 83 9664
TEST_TIME 84 9708
TEST_TIME 85 9752
TEST_TIME 86 9755
TEST_TIME 87 9766
TEST_TIME 88 9774
TEST_TIME 89 9742
TEST_TIME 90 9776
TEST_TIME 91 9767
TEST_TIME 92 9829
TEST_TIME 93 9824
TEST_TIME 94 9833
TEST_TIME 95 9899
TEST_TIME 96 9921
TEST_TIME 97 9882
TEST_TIME 98 9851
TEST_TIME 99 9817
TEST_TIME 100 9779
TEST_TIME 101 9759
TEST_TIME 102 9722
TEST_TIME 103 9735
TEST_TIME 104 9702
TEST_TIME 105 9684
TEST_TIME 106 9671
TEST_TIME 107 9644
TEST_TIME 108 9619
TEST_TIME 109 9661
TEST_TIME 110 9651
TEST_TIME 111 9689
TEST_TIME 112 9664
TEST_TIME 113 9656
TEST_TIME 114 9682
TEST_TIME 115 9697
TEST_TIME 116 9710
TEST_TIME 117 9700
TEST_TIME 118 9694
TEST_TIME 119 9715
TEST_TIME 120 9762

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2021 22:38:26 to 09-08-2021 22:38:29.

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 1605366 1605366 0 0.00 0 23857 7 0 0
doc_list_biud 1605364 1605364 0 0.00 0 48119 31 0 0
sp_add_invoice_to_stock 5170 4983 187 3.61 41 190841 20458 0 187
sp_cancel_adding_invoice 6243 6243 0 0.00 4 67722 769 0 0
sp_cancel_client_order 1778 1778 0 0.00 4 9534 354 0 0
sp_cancel_customer_reserve 615593 615593 0 0.00 1 31209 67 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 6243 85 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 8246 97 0 0
sp_cancel_supplier_invoice 6854 6843 11 0.16 2 15445 770 0 11
sp_cancel_supplier_order 431 431 0 0.00 6 1156576 120824 0 0
sp_cancel_write_off 11034 11034 0 0.00 1 12137 111 0 0
sp_client_order 20377 20377 0 0.00 4 29651 348 0 0
sp_customer_reserve 467961 467961 0 0.00 0 64809 200 0 0
sp_fill_shopping_cart 31052 31052 0 0.00 1 25168 37 0 0
sp_fill_shopping_cart_clo_res 467957 467957 0 0.00 0 18350 13 0 0
sp_get_clo_for_invoice 4979 4979 0 0.00 0 25220 302 0 0
sp_kill_cost_storno 18437 18437 0 0.00 0 12085 60 0 0
sp_kill_qstorno_ret_qs2qd 1249494 1249494 0 0.00 0 35397 35 0 0
sp_kill_qty_storno 656815 656815 0 0.00 0 48116 76 0 0
sp_lock_selected_doc 20675 20675 0 0.00 0 5634 4 0 0
sp_make_cost_storno 27651 27651 0 0.00 0 23846 65 0 0
sp_multiply_rows_for_pdistr 27651 27651 0 0.00 0 14961 35 0 0
sp_multiply_rows_for_qdistr 20377 20377 0 0.00 0 21471 141 0 0
sp_pay_from_customer 6959 6959 0 0.00 4 23306 150 0 0
sp_pay_to_supplier 5799 5799 0 0.00 5 15104 135 0 0
sp_payment_common 12758 12758 0 0.00 1 21148 38 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 9508 321 0 0
sp_qd_handle_on_invoice_upd_sts 11222 11222 0 0.00 0 48110 362 0 0
sp_qd_handle_on_reserve_upd_sts 9914 9914 0 0.00 0 17961 64 0 0
sp_reserve_write_off 9914 9914 0 0.00 7 24558 203 0 0
sp_supplier_invoice 5400 5400 0 0.00 5 107620 3379 0 0
sp_supplier_order 5285 5285 0 0.00 5 31123 1342 0 0
srv_aggregate_perf_data 109 109 0 0.00 2954 237693 17891 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1898 120659 46740 0 0
srv_find_qd_qs_mism 1124138 1124138 0 0.00 0 14676 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 47 163181 1356 0 0
srv_make_money_saldo 836 836 0 0.00 1 5679 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 180221 349300 286263 0 0
t$perf-abend:sp_add_invoice_to_stock 187 187 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 24417 217 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 2769 74 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 6325 24 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 4516 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1350 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 2617 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 12064 51 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 23801 1723 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 972 8 0 0
t$perf-norm:sp_client_order 20377 20377 0 0.00 0 9779 20 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 3028 10 0 0
t$perf-norm:sp_pay_from_customer 6959 6959 0 0.00 0 6010 11 0 0
t$perf-norm:sp_pay_to_supplier 5799 5799 0 0.00 0 4986 8 0 0
t$perf-norm:sp_reserve_write_off 9914 9914 0 0.00 0 6315 12 0 0
t$perf-norm:sp_supplier_invoice 5400 5400 0 0.00 0 6363 20 0 0
t$perf-norm:sp_supplier_order 5285 5285 0 0.00 0 7753 19 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 319 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 992 29 0 0
t$perf-norm:srv_make_money_saldo 836 836 0 0.00 0 1649 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10330 9956 374 3.62 0 5623 20 0 374
v_all_customers 36896 36896 0 0.00 0 16 1 0 0
v_all_suppliers 29760 29760 0 0.00 0 19 1 0 0
v_all_wares 40754 40754 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1566 9 0 0
v_cancel_client_order 3556 3556 0 0.00 0 7452 8 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 2979 6 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 3157 6 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 862 4 0 0
v_cancel_supplier_invoice 816 794 22 2.69 0 993 20 0 22
v_cancel_supplier_order 862 862 0 0.00 0 1634 5 0 0
v_cancel_write_off 1972 1972 0 0.00 0 1765 13 0 0
v_max_id_clo_ord 5279 5279 0 0.00 0 2354 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3280 10 0 0
v_max_id_ord_sup 5396 5396 0 0.00 0 12987 4 0 0
v_max_non_paid_invoice 1594 1594 0 0.00 0 1414 4 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 2448 4 0 0
v_min_id_clo_ord 5279 5279 0 0.00 0 14102 19 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 4008 11 0 0
v_min_id_ord_sup 5396 5396 0 0.00 0 4158 5 0 0
v_min_non_paid_invoice 5799 5799 0 0.00 0 14923 23 0 0
v_min_non_paid_realizn 6959 6959 0 0.00 0 14717 28 0 0
v_our_firm 10180 10180 0 0.00 0 21 1 0 0
v_reserve_write_off 19826 19826 0 0.00 0 4449 6 0 0
x_lock_depdocs_on_canc_invoice 12689 12689 0 0.00 0 37907 131 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 6141 375 0 0
x_make_qstorno_1000_1200 5285 5285 0 0.00 2 31115 1240 0 0
x_make_qstorno_1200_2000 5400 5400 0 0.00 2 100442 3268 0 0
x_make_qstorno_2100_3300 467961 467961 0 0.00 0 64805 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2021 22:38:30 to 09-08-2021 22:38:34.

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:48 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:49 35774 8192000000 10380170384 10699210752 1423748704 14.806 100 87 173 1 1458116608 5984512 28383392
20:10:28 22256 8192000000 10446940480 10780319744 1494973824 15.432 100 87 174 0 1531335024 8770608 27590592
20:11:53 8751 8192000000 10507184064 10851049472 1562608224 16.019 100 80 160 0 1596527872 9576880 24342768
20:13:08 16167 8192000000 10576604448 10934685696 1638160256 16.664 100 85 168 2 1674914816 11616288 25138272
20:14:29 9217 8192000000 10645328368 11011596288 1698169264 17.170 100 78 152 6 1732834064 13075072 21589728
20:15:40 3451 8192000000 10707625264 11085336576 1768296032 17.753 100 82 158 2 1802914864 11834592 22784240
20:16:45 8227 8192000000 10764079776 11152863232 1825829520 18.225 100 80 160 0 1861418912 13673488 21915904
20:17:56 1547 8192000000 10819972816 11207745536 1875189584 18.626 100 92 184 1 1914979376 13583376 26206416
20:18:58 8077 8192000000 10813621696 11213758464 1880847120 18.672 100 84 164 1 1913416800 7917744 24651936
20:20:07 9184 8192000000 10793310800 11218563072 1881104928 18.674 100 25 50 2 1895223040 6840032 7278080
20:21:17 7721 8192000000 10806971760 11231305728 1890394400 18.749 100 21 42 2 1901758592 5335472 6028720
20:22:29 9514 8192000000 10806090784 11242143744 1901442048 18.838 100 21 42 2 1913876032 6490304 5943680
20:23:38 66286 8192000000 10821198720 11259461632 1911479152 18.919 100 38 73 1 1927204176 4882992 10842032
20:25:45 6887 8192000000 10830247328 11262521344 1915296864 18.949 100 37 74 0 1933945728 8251504 10397360
20:26:52 13942 8192000000 10817781472 11258228736 1893452176 18.774 99 22 44 2 1905943744 5978048 6513520
20:28:07 62325 8192000000 10713079072 11118673920 1673182416 16.960 89 24 49 0 1685637728 4493392 7961920
20:30:10 4999 8192000000 10740311232 11155873792 1793048608 17.957 97 37 72 2 1810123184 7351120 9723456
20:31:15 6576 8192000000 10676832944 11087757312 1685905216 17.067 94 28 57 0 1699487856 5056432 8526208
20:32:24 4054 8192000000 10734817920 11151114240 1821850960 18.193 100 37 73 1 1839295168 6781568 10662640
20:33:29 38784 8192000000 10761405776 11187269632 1850323136 18.425 100 62 123 3 1875379616 6779424 18277056
20:35:12 8515 8192000000 10770773152 11191693312 1854158512 18.456 100 21 42 1 1865763344 5823072 5781760
20:36:21 8825 8192000000 10781487840 11209527296 1868732176 18.574 100 19 38 1 1879512144 5650368 5129600
20:37:33 9798 8192000000 10804773376 11238387712 1895379968 18.789 100 16 32 1 1908338256 8707840 4250448
20:38:52 73748 8192000000 10852612896 11279343616 1919710672 18.985 100 41 81 1 1939815648 9457168 10647808
20:41:12 20054 8192000000 10887462400 11308167168 1948386352 19.214 100 58 116 2 1977956864 13154560 16415952
20:42:35 3984 8192000000 10910397728 11331518464 1966215312 19.355 100 56 112 3 1997611520 15771904 15624304
20:43:47 3565 8192000000 10931011232 11354718208 1982756096 19.487 100 71 140 2 2022787968 19551104 20480768
20:44:56 13503 8192000000 10921410816 11354972160 1980751648 19.471 100 46 92 0 2011115760 15929456 14434656
20:46:21 2823 8192000000 10962582944 11372867584 1992058896 19.560 100 80 159 1 2039286688 24351296 22876496
20:47:30 1895 8192000000 10954939104 11375562752 1995044112 19.584 100 81 160 3 2044899280 25440608 24414560
20:48:35 5322 8192000000 10944376080 11359928320 1970047072 19.386 100 75 150 1 2023867936 31592624 22228240
20:49:47 7618 8192000000 10927621136 11354632192 1966749600 19.360 99 61 121 2 2009168656 23481872 18937184
20:50:59 36010 8192000000 10905040768 11346268160 1932385888 19.086 98 62 122 1 1979927536 29173408 18368240
20:52:47 2305 8192000000 10941622080 11353010176 1962906848 19.329 100 79 158 0 2020579216 33630880 24041488
20:53:55 5184 8192000000 10943397568 11360649216 1968398944 19.373 100 69 138 0 2027830768 38591344 20840480
20:55:07 2210 8192000000 10913833040 11330187264 1929985808 19.067 99 72 144 1 1991005072 38695424 22323840
20:56:15 7590 8192000000 10913213504 11325542400 1928297936 19.053 100 64 128 1 1994763712 46805456 19660320
20:57:37 4516 8192000000 10965878496 11379593216 1967583808 19.366 100 70 141 0 2043211232 54043024 21584400
20:58:46 2469 8192000000 11021077376 11432935424 2007953408 19.685 100 89 177 0 2101390736 64750640 28686688
20:59:53 4281 8192000000 11010647440 11434979328 2008378384 19.689 100 62 123 1 2093425616 64806976 20240256
21:01:05 2630 8192000000 10994915216 11416543232 1973206752 19.411 99 70 139 1 2064382992 68106592 23069648
21:02:19 6326 8192000000 11008644912 11439611904 2005388288 19.665 100 52 104 2 2089001968 65894560 17719120
21:03:37 13060 8192000000 11024892864 11463479296 2007596400 19.683 99 44 88 0 2093536688 70764304 15175984
21:04:56 13763 8192000000 11019497056 11471589376 2007851952 19.685 100 45 90 2 2108523216 84089168 16582096
21:06:43 17710 8192000000 10980678976 11446902784 1978746768 19.455 100 29 58 1 2065624192 75316208 11561216
21:08:30 93886 8192000000 10923503952 11413184512 1953622528 19.255 99 14 28 0 2028934528 70244448 5067552
21:11:05 60508 8192000000 10926761712 11364102144 1941538736 19.159 98 28 56 0 1959303744 9918256 7846752
21:13:09 94852 8192000000 10919376272 11387236352 1982238848 19.482 100 27 54 2 2001925888 10978320 8708720
21:15:50 73762 8192000000 10928765904 11397177344 1976863584 19.440 99 29 58 3 1999911264 15365072 7682608
21:18:16 71006 8192000000 10906968336 11354365952 1915999664 18.955 97 22 44 1 1928797296 6559728 6237904
21:20:32 13385 8192000000 10899075584 11351658496 1956518176 19.278 99 22 44 0 1974931136 12161728 6251232
21:21:50 29534 8192000000 10917478176 11387662336 1984696784 19.502 100 16 32 0 2001520608 12115776 4708048
21:23:23 35872 8192000000 10957452208 11408789504 2000075360 19.623 100 46 90 2 2029525088 15682784 13766944
21:25:03 20511 8192000000 10933193424 11430940672 1998437280 19.610 100 12 24 2 2028485472 26818784 3229408
21:26:24 22539 8192000000 10930551856 11406135296 1997374592 19.602 100 12 23 1 2016942240 15852064 3715584
21:27:47 113981 8192000000 10926584688 11418574848 2008313536 19.688 100 16 32 1 2027325488 14210000 4801952
21:30:42 65596 8192000000 10935934576 11419975680 2002849632 19.645 100 16 32 1 2017255344 10245520 4160192
21:34:08 83946 8192000000 10930386736 11415285760 1988666048 19.533 99 6 12 0 2005005488 14723840 1615600
21:36:37 36642 8192000000 10897802144 11372822528 1946003984 19.195 98 20 39 1 1957998240 7128912 4865344
21:38:16 84967 8192000000 10919531216 11387195392 1923883232 19.018 96 27 54 3 1951902384 20590160 7428992
21:40:41 40091 8192000000 10891776816 11359395840 1948065968 19.211 99 13 25 1 1963389840 10601200 4722672
21:42:33 95801 8192000000 10892428976 11361214464 1862652064 18.525 94 23 46 2 1890410448 21281600 6476784
21:45:10 28685 8192000000 10869202112 11320459264 1869505584 18.580 96 25 50 0 1884189584 8047920 6636080
21:46:43 101162 8192000000 10900160880 11349098496 1869108336 18.577 96 22 44 1 1893244896 18339152 5797408
21:50:31 105682 8192000000 10872911200 11344723968 1902675760 18.848 97 25 50 2 1919114048 9843616 6594672
21:53:29 69129 8192000000 10850889152 11336454144 1933373792 19.094 100 12 25 0 1946004480 9508080 3122608
21:55:38 87128 8192000000 10845571952 11317821440 1897097760 18.803 97 24 48 0 1919269792 15573600 6598432
21:58:05 42263 8192000000 10847669232 11333087232 1933131168 19.092 100 7 14 0 1948615904 13484432 2000304
22:00:09 75662 8192000000 10876611600 11369340928 1934262208 19.101 99 12 24 1 1957324784 19688448 3374128
22:02:25 86790 8192000000 10855751104 11332960256 1885334384 18.708 96 11 22 1 1900196592 12079808 2782400
22:04:54 66280 8192000000 10893875120 11371499520 1951656976 19.240 99 11 22 0 1964682976 10254944 2771056
22:07:03 60781 8192000000 10925114720 11400052736 1990778992 19.550 100 34 66 1 2013625968 13942912 8904064
22:09:06 75908 8192000000 10908585344 11385638912 1963926448 19.337 99 23 42 4 1986006032 15299968 6779616
22:11:23 75528 8192000000 10923449152 11409072128 1948523536 19.215 98 11 22 0 1969727456 17651600 3552320
22:13:41 48431 8192000000 10955678384 11411161088 1983802448 19.495 99 24 46 2 1996101680 7016032 5283200
22:15:32 109420 8192000000 10941881088 11409219584 1973069376 19.410 98 40 80 2 1993992336 9573680 11349280
22:18:26 38841 8192000000 10909579280 11374399488 1976404768 19.436 99 16 30 0 1991905392 11094432 4406192
22:20:06 120659 8192000000 10920724688 11414859776 1953276704 19.253 97 11 22 1 1972650576 16174224 3199648
22:23:07 58576 8192000000 10921342352 11418066944 1961846976 19.321 98 8 16 1 1976823872 12460336 2516560
22:25:33 62891 8192000000 10918400768 11398856704 1991145888 19.553 100 20 40 0 2006838832 10084928 5608016
22:27:45 62565 8192000000 10884073568 11355295744 1861607584 18.516 94 11 22 0 1877120896 12944624 2568688
22:29:54 79874 8192000000 10893424016 11360563200 1923803952 19.017 98 17 34 1 1941750320 13281088 4665280
22:32:19 64070 8192000000 10900437632 11377307648 1915567184 18.951 97 16 32 1 1931147680 11222896 4357600
22:34:49 33514 8192000000 10907256720 11362136064 1917895888 18.970 97 39 78 0 1939718448 11134144 10688416
22:36:46 56483 8192000000 10877179760 11368783872 1899879152 18.825 97 8 16 0 1923771648 21421216 2471280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-08-2021 22:38:36 to 09-08-2021 22:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 187 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2021 22:38:52 to 09-08-2021 22:38:54.

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          241072
MON$OLDEST_ACTIVE               241289
MON$OLDEST_SNAPSHOT             241289
MON$NEXT_TRANSACTION            241289
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                       2069472
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:460: 
:461: mscs-test-161.budzdorov.ru    Mon Aug  9 22:37:49 2021
:462:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:463: 
:464: 
:465: mscs-test-161.budzdorov.ru    Mon Aug  9 22:37:52 2021
:466:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:467: 
--- end of diff output ---

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

09.08.21 22:38:55. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-09 22:38:57 No severe PSQL-related problems occured
09.08.21 22:38:58. End of report.