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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-14 13:26:58
DTS_END                         2021-03-14 13:26:58.6190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42146
TRN_ID                          228912
ATT_ID                          665
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: 8914.83 22870 1069779
customer order: creation 160.41 397 19249
customer order: refuse 14.07 313 1688
order to supplier: creation 41.83 1379 5020
order to supplier: removal 3.13 122897 376
invoice (draft): creation 43.11 3712 5173
invoice (draft): removal 51.72 939 6206
invoice accept: apply 39.05 17491 4686
invoice accept: cancel 46.79 886 5615
customer reserve: creation 3640.56 168 436867
customer reserve: removal 4597.45 66 551694
realization accept: apply 77.56 178 9307
realization accept: cancel 78.43 119 9411
payment from customer: creation 55.12 119 6614
payment from customer: removal 5.39 76 647
payment to supplier: creation 46.69 137 5603
payment to supplier: removal 3.32 60 398
service: total inventory turnovers 3.59 3169 431
service: total monetary turnovers 5.75 496 690
service: aggregate perf. data 0.83 14180 99
service: refresh index statistics 0.04 290620 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-03-2021 13:27:56 to 14-03-2021 13:27:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2516
TEST_TIME 2 4003
TEST_TIME 3 2929
TEST_TIME 4 3395
TEST_TIME 5 3748
TEST_TIME 6 3756
TEST_TIME 7 4189
TEST_TIME 8 4864
TEST_TIME 9 5098
TEST_TIME 10 5323
TEST_TIME 11 5487
TEST_TIME 12 5733
TEST_TIME 13 5730
TEST_TIME 14 5913
TEST_TIME 15 6197
TEST_TIME 16 6629
TEST_TIME 17 6815
TEST_TIME 18 7317
TEST_TIME 19 8142
TEST_TIME 20 8173
TEST_TIME 21 8545
TEST_TIME 22 8821
TEST_TIME 23 8813
TEST_TIME 24 9385
TEST_TIME 25 9905
TEST_TIME 26 10873
TEST_TIME 27 10512
TEST_TIME 28 10216
TEST_TIME 29 10062
TEST_TIME 30 10086
TEST_TIME 31 9995
TEST_TIME 32 9770
TEST_TIME 33 9586
TEST_TIME 34 9723
TEST_TIME 35 9678
TEST_TIME 36 9576
TEST_TIME 37 9419
TEST_TIME 38 9396
TEST_TIME 39 9434
TEST_TIME 40 9284
TEST_TIME 41 9184
TEST_TIME 42 9163
TEST_TIME 43 9261
TEST_TIME 44 9206
TEST_TIME 45 9241
TEST_TIME 46 9301
TEST_TIME 47 9267
TEST_TIME 48 9215
TEST_TIME 49 9536
TEST_TIME 50 9505
TEST_TIME 51 9591
TEST_TIME 52 9526
TEST_TIME 53 9623
TEST_TIME 54 9656
TEST_TIME 55 9485
TEST_TIME 56 9381
TEST_TIME 57 9293
TEST_TIME 58 9194
TEST_TIME 59 9099
TEST_TIME 60 9100
TEST_TIME 61 9183
TEST_TIME 62 9211
TEST_TIME 63 9159
TEST_TIME 64 9089
TEST_TIME 65 9174
TEST_TIME 66 9144
TEST_TIME 67 9117
TEST_TIME 68 9250
TEST_TIME 69 9245
TEST_TIME 70 9329
TEST_TIME 71 9311
TEST_TIME 72 9442
TEST_TIME 73 9343
TEST_TIME 74 9226
TEST_TIME 75 9187
TEST_TIME 76 9106
TEST_TIME 77 9040
TEST_TIME 78 9037
TEST_TIME 79 9017
TEST_TIME 80 9008
TEST_TIME 81 8998
TEST_TIME 82 8964
TEST_TIME 83 8942
TEST_TIME 84 8939
TEST_TIME 85 8956
TEST_TIME 86 9004
TEST_TIME 87 8972
TEST_TIME 88 9055
TEST_TIME 89 9063
TEST_TIME 90 9093
TEST_TIME 91 9090
TEST_TIME 92 9000
TEST_TIME 93 8987
TEST_TIME 94 8907
TEST_TIME 95 8870
TEST_TIME 96 8855
TEST_TIME 97 8889
TEST_TIME 98 8890
TEST_TIME 99 8895
TEST_TIME 100 8895
TEST_TIME 101 8907
TEST_TIME 102 8874
TEST_TIME 103 8925
TEST_TIME 104 8906
TEST_TIME 105 8981
TEST_TIME 106 9023
TEST_TIME 107 9075
TEST_TIME 108 9058
TEST_TIME 109 9052
TEST_TIME 110 9026
TEST_TIME 111 8985
TEST_TIME 112 8938
TEST_TIME 113 8877
TEST_TIME 114 8896
TEST_TIME 115 8902
TEST_TIME 116 8897
TEST_TIME 117 8910
TEST_TIME 118 8888
TEST_TIME 119 8863
TEST_TIME 120 8901
TEST_TIME 121 8845

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-03-2021 13:27:59 to 14-03-2021 13:28:03.

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 1474459 1474459 0 0.00 0 19627 6 0 0
doc_list_biud 1474457 1474457 0 0.00 0 44407 31 0 0
sp_add_invoice_to_stock 4801 4686 115 2.39 46 164066 17491 0 115
sp_cancel_adding_invoice 5615 5615 0 0.00 5 68701 887 0 0
sp_cancel_client_order 1688 1688 0 0.00 4 17208 314 0 0
sp_cancel_customer_reserve 551694 551694 0 0.00 1 31497 66 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 2652 76 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2251 60 0 0
sp_cancel_supplier_invoice 6218 6206 12 0.19 2 21893 940 0 12
sp_cancel_supplier_order 376 376 0 0.00 11 1090909 122898 0 0
sp_cancel_write_off 9411 9411 0 0.00 1 14091 120 0 0
sp_client_order 19249 19249 0 0.00 4 20807 397 0 0
sp_customer_reserve 436867 436867 0 0.00 0 91867 168 0 0
sp_fill_shopping_cart 29426 29426 0 0.00 1 27567 53 0 0
sp_fill_shopping_cart_clo_res 436865 436865 0 0.00 0 24889 19 0 0
sp_get_clo_for_invoice 4678 4678 0 0.00 0 39776 464 0 0
sp_kill_cost_storno 16071 16071 0 0.00 0 6248 56 0 0
sp_kill_qstorno_ret_qs2qd 1119381 1119381 0 0.00 0 31457 36 0 0
sp_kill_qty_storno 588973 588973 0 0.00 0 44407 78 0 0
sp_lock_selected_doc 19368 19368 0 0.00 0 8664 7 0 0
sp_make_cost_storno 26202 26202 0 0.00 0 14801 64 0 0
sp_multiply_rows_for_pdistr 26201 26201 0 0.00 0 7681 34 0 0
sp_multiply_rows_for_qdistr 19249 19249 0 0.00 0 20796 235 0 0
sp_pay_from_customer 6614 6614 0 0.00 4 18109 120 0 0
sp_pay_to_supplier 5603 5603 0 0.00 4 15618 138 0 0
sp_payment_common 12217 12217 0 0.00 1 6593 23 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 17152 293 0 0
sp_qd_handle_on_invoice_upd_sts 10293 10293 0 0.00 0 44406 389 0 0
sp_qd_handle_on_reserve_upd_sts 9305 9305 0 0.00 0 7619 44 0 0
sp_reserve_write_off 9307 9307 0 0.00 7 18266 179 0 0
sp_supplier_invoice 5173 5173 0 0.00 6 86188 3712 0 0
sp_supplier_order 5020 5020 0 0.00 5 25428 1379 0 0
srv_aggregate_perf_data 99 99 0 0.00 2850 112296 14180 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 977 196512 49628 0 0
srv_find_qd_qs_mism 1025092 1025092 0 0.00 0 19625 5 0 0
srv_make_invnt_saldo 431 431 0 0.00 64 69665 3169 0 0
srv_make_money_saldo 690 690 0 0.00 1 13886 497 0 0
srv_recalc_idx_stat 5 5 0 0.00 174870 456230 290620 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4686 4686 0 0.00 0 9393 170 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 0 2319 101 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 8104 18 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 3019 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 6316 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 536 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 385 7 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 31070 1431 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 1409 7 0 0
t$perf-norm:sp_client_order 19249 19249 0 0.00 0 7717 17 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1390 6 0 0
t$perf-norm:sp_pay_from_customer 6614 6614 0 0.00 0 5588 7 0 0
t$perf-norm:sp_pay_to_supplier 5603 5603 0 0.00 0 6259 8 0 0
t$perf-norm:sp_reserve_write_off 9307 9307 0 0.00 0 5239 9 0 0
t$perf-norm:sp_supplier_invoice 5173 5173 0 0.00 0 3970 16 0 0
t$perf-norm:sp_supplier_order 5020 5020 0 0.00 0 9250 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 376 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 81 2 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 2578 13 0 0
t$perf-norm:srv_make_money_saldo 690 690 0 0.00 0 1648 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 12 4 0 0
v_add_invoice_to_stock 9586 9356 230 2.39 0 7490 19 0 230
v_all_customers 35386 35386 0 0.00 0 113 1 0 0
v_all_suppliers 28740 28740 0 0.00 0 21 1 0 0
v_all_wares 38498 38498 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 6410 13 0 0
v_cancel_client_order 3376 3376 0 0.00 0 1511 3 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 2642 10 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 910 3 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 458 4 0 0
v_cancel_supplier_invoice 740 716 24 3.24 0 7964 26 0 24
v_cancel_supplier_order 752 752 0 0.00 0 4667 10 0 0
v_cancel_write_off 1818 1818 0 0.00 0 7655 16 0 0
v_max_id_clo_ord 5019 5019 0 0.00 0 4040 2 0 0
v_max_id_clo_res 984 984 0 0.00 0 5291 25 0 0
v_max_id_ord_sup 5156 5156 0 0.00 0 2494 3 0 0
v_max_non_paid_invoice 1408 1408 0 0.00 0 1149 2 0 0
v_max_non_paid_realizn 3496 3496 0 0.00 0 3326 4 0 0
v_min_id_clo_ord 5019 5019 0 0.00 0 9676 16 0 0
v_min_id_clo_res 984 984 0 0.00 0 4834 10 0 0
v_min_id_ord_sup 5156 5156 0 0.00 0 5200 13 0 0
v_min_non_paid_invoice 5603 5603 0 0.00 0 6553 16 0 0
v_min_non_paid_realizn 6614 6614 0 0.00 0 6589 15 0 0
v_our_firm 9348 9348 0 0.00 0 17 1 0 0
v_reserve_write_off 18608 18608 0 0.00 0 4854 5 0 0
x_lock_depdocs_on_canc_invoice 11463 11463 0 0.00 0 41748 124 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 11286 330 0 0
x_make_qstorno_1000_1200 5020 5020 0 0.00 2 25421 1299 0 0
x_make_qstorno_1200_2000 5173 5173 0 0.00 3 85525 3501 0 0
x_make_qstorno_2100_3300 436867 436867 0 0.00 0 91865 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2021 13:28:04 to 14-03-2021 13:28:08.

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
10:57:02 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:03 44889 8192000000 9732575136 10030080000 799744704 8.894 100 89 178 0 830094448 8111968 22237776
10:59:54 25044 8192000000 9771451696 10080145408 840338992 9.303 100 88 176 0 875165120 14029856 20796272
11:01:23 14265 8192000000 9816302976 10135982080 882243424 9.722 100 83 166 0 926064912 25117696 18703792
11:02:47 7939 8192000000 9889259408 10227236864 929907408 10.194 100 76 152 0 996832224 50117504 16807312
11:03:59 6513 8192000000 9938338864 10283757568 967262400 10.560 100 79 156 1 1050820128 67093696 16464032
11:05:10 4792 8192000000 9955360224 10310549504 997674800 10.856 100 77 154 1 1065909616 52554016 15680800
11:06:16 3097 8192000000 10001739376 10365366272 1035390992 11.220 100 83 166 0 1112369584 60013824 16964768
11:07:21 2339 8192000000 10021864960 10391859200 1058619472 11.443 100 88 175 1 1131976512 55675136 17681904
11:08:26 18697 8192000000 10046484272 10431066112 1069226912 11.545 100 65 128 1 1160570848 78589296 12754640
11:09:45 5492 8192000000 9993339056 10390745088 1078029568 11.629 100 34 68 0 1122550864 37728592 6792704
11:10:53 8031 8192000000 9998878192 10391433216 1086204128 11.707 100 33 64 1 1121721328 28703744 6813456
11:12:02 6699 8192000000 10004688240 10402447360 1095314400 11.793 100 36 72 0 1136181872 33137152 7730320
11:13:10 63496 8192000000 10011352256 10408669184 1095996544 11.800 100 26 52 1 1118112704 15876976 6239184
11:15:15 7574 8192000000 10022361104 10429005824 1108166160 11.915 100 36 72 1 1150527856 34337328 8024368
11:16:25 7293 8192000000 10020890336 10432675840 1092420128 11.766 99 36 74 1 1126002464 25338144 8244192
11:17:35 13208 8192000000 9973572624 10376699904 1003885984 10.916 92 17 34 1 1022798288 15169648 3742656
11:18:49 17375 8192000000 9963705872 10359377920 996246608 10.842 94 31 61 1 1025015024 22316352 6452064
11:20:08 8994 8192000000 9933383552 10316804096 1014195440 11.016 99 42 83 4 1051301792 27110880 9995472
11:21:19 8523 8192000000 9966123456 10363092992 1030777168 11.176 100 28 56 2 1070231888 34192624 5262096
11:22:28 64920 8192000000 9952557184 10352898048 1020537088 11.077 98 27 54 1 1055767776 29764592 5466096
11:24:36 6159 8192000000 9968668960 10360242176 1054187536 11.401 100 41 84 1 1080793120 17744944 8860640
11:25:44 6689 8192000000 9991506352 10384683008 1066393728 11.518 100 34 68 0 1106937888 33467376 7076784
11:26:52 18743 8192000000 9990512864 10395779072 1074741360 11.597 100 35 70 1 1101177168 17922880 8512928
11:28:28 99258 8192000000 10081050128 10486517760 1113081296 11.962 100 41 81 1 1162362256 40250176 9030784
11:31:22 30778 8192000000 10114183744 10537316352 1133461776 12.154 100 31 62 2 1226689568 86223136 7004656
11:33:04 8196 8192000000 10172191040 10588708864 1142693456 12.241 100 51 102 2 1284729200 130929904 11105840
11:34:21 14795 8192000000 10209390496 10622787584 1149306048 12.303 100 48 95 1 1319956096 160520176 10129872
11:35:38 2186 8192000000 10232458528 10639212544 1156723392 12.373 100 65 129 0 1333401232 163050800 13627040
11:36:45 3740 8192000000 10236069088 10645737472 1156921632 12.374 99 59 118 0 1338984384 169357232 12705520
11:37:52 4205 8192000000 10315834672 10725765120 1182854800 12.617 100 71 142 0 1417377744 218090352 16432592
11:39:07 4943 8192000000 10323801200 10742681600 1178579168 12.577 100 47 94 0 1422158288 232230128 11348992
11:40:18 2694 8192000000 10376072368 10783735808 1175477248 12.548 99 81 158 0 1469357984 275592352 18288384
11:41:26 2181 8192000000 10443170496 10846294016 1169218496 12.490 100 93 184 2 1536832320 346367488 21246336
11:42:33 4872 8192000000 10420474816 10855907328 1160666368 12.410 100 71 140 0 1520965728 343076640 17222720
11:43:41 974 8192000000 10478275248 10880733184 1160927936 12.412 100 92 182 1 1567657312 385551024 21178352
11:44:46 3625 8192000000 10443649344 10884804608 1169866464 12.496 100 64 125 3 1539900752 354525776 15508512
11:45:58 8222 8192000000 10363526208 10796605440 1129658496 12.118 98 41 82 0 1442251552 302356864 10236192
11:47:25 21665 8192000000 10370569920 10844405760 1151016672 12.319 100 47 94 1 1516341536 353290640 12034224
11:49:06 10826 8192000000 10356102160 10785656832 1134636736 12.165 99 34 67 1 1443868032 300804384 8426912
11:50:25 23151 8192000000 10304362672 10778959872 1105872080 11.893 98 31 62 0 1466288736 351790288 8626368
11:51:50 43259 8192000000 10095377600 10550865920 1106376528 11.898 99 16 32 1 1302063712 191828064 3859120
11:53:50 196511 8192000000 10082422224 10557853696 1103187888 11.868 98 38 76 1 1167695424 57135040 7372496
11:58:29 39961 8192000000 10093071488 10534490112 1089033504 11.733 97 19 38 2 1187774816 94544432 4196880
12:00:16 44539 8192000000 10053519760 10498662400 1082246128 11.669 97 25 50 2 1128051440 39945072 5860240
12:02:02 25502 8192000000 10073235728 10519371776 1127161008 12.095 100 19 38 0 1171378448 40009936 4207504
12:03:29 98201 8192000000 10127882000 10610622464 1126914784 12.092 99 18 36 2 1220347792 90009568 3423440
12:06:08 63037 8192000000 10112132592 10565165056 1143230368 12.246 100 13 25 0 1221078448 75398032 2450048
12:08:11 68778 8192000000 10098922896 10568081408 1111705888 11.949 97 20 40 0 1184791328 68753344 4332096
12:10:21 97958 8192000000 10133829824 10619006976 1149452288 12.304 100 20 40 2 1234442944 80614064 4376592
12:13:11 21479 8192000000 10129796080 10554306560 1139558304 12.211 99 43 82 4 1202658640 54777744 8322592
12:14:36 59224 8192000000 10175066816 10674012160 1134503264 12.164 99 14 29 0 1310865344 172869456 3492624
12:16:38 67407 8192000000 10082895824 10578391040 1151218080 12.321 100 6 12 0 1204880128 52416944 1245104
12:18:47 38383 8192000000 10138384336 10589106176 1140707152 12.222 98 33 65 3 1210510576 62830144 6973280
12:20:29 131301 8192000000 10098931680 10546905088 1149425376 12.304 99 19 38 0 1196316384 42695392 4195616
12:23:50 67743 8192000000 10115198800 10585415680 1151546704 12.324 99 10 19 1 1196295424 42671088 2077632
12:25:58 70309 8192000000 10121780192 10604929024 1125168560 12.076 97 14 26 0 1204475728 76430624 2876544
12:28:09 58429 8192000000 10089460464 10528456704 1159666272 12.400 100 22 45 0 1204217568 39565120 4986176
12:30:22 32105 8192000000 10184061936 10630221824 1150047136 12.310 98 31 62 1 1243590368 86377520 7165712
12:32:01 79399 8192000000 10158095248 10643558400 1144206144 12.255 98 21 42 1 1270778000 122282016 4289840
12:35:14 54843 8192000000 10093406128 10562297856 1107905488 11.913 97 18 36 0 1160054992 48684496 3465008
12:37:41 80911 8192000000 10090021680 10604929024 1114855152 11.978 98 22 44 2 1170683168 51265152 4562864
12:40:32 61013 8192000000 10072751936 10548662272 1134633984 12.165 100 19 38 1 1180144800 41546640 3964176
12:44:29 56532 8192000000 10084900624 10565988352 1110216128 11.934 98 14 28 0 1165097424 52006016 2875280
12:46:27 59780 8192000000 10056926800 10507726848 1102811024 11.864 98 12 24 1 1135556736 30424032 2321680
12:48:35 38878 8192000000 10068829376 10506731520 1098015280 11.819 98 20 40 1 1157666944 55575776 4075888
12:50:15 37175 8192000000 10127323984 10584510464 1122991008 12.055 100 37 74 0 1219809888 89017936 7800944
12:51:52 68439 8192000000 10060139216 10513874944 1076685776 11.616 96 29 58 1 1142408464 60018288 5704400
12:54:01 47667 8192000000 10106051232 10611064832 1102127120 11.858 98 28 56 1 1186079840 78157200 5795520
12:56:23 34843 8192000000 10090123344 10523394048 1117987312 12.008 100 18 36 0 1164102000 42589520 3525168
12:58:13 70105 8192000000 10083783600 10548838400 1125199456 12.076 100 19 38 1 1185809552 56830608 3779488
13:00:31 67056 8192000000 10063678368 10538430464 1060188720 11.458 94 8 16 0 1110944064 48945648 1809696
13:02:38 91882 8192000000 10043387488 10487717888 1082417248 11.671 97 10 20 0 1149323024 65079024 1826752
13:05:10 59229 8192000000 10078398128 10541727744 1135594448 12.174 100 15 30 0 1178759104 40046080 3118576
13:07:10 61124 8192000000 10100689104 10544324608 1118368112 12.012 98 27 54 1 1181190560 56868880 5953568
13:09:11 77425 8192000000 10126681632 10584268800 1141012000 12.225 100 23 46 1 1215626688 69871296 4743392
13:11:29 51946 8192000000 10109831008 10666598400 1135732416 12.175 99 22 44 1 1226514000 86023808 4757776
13:13:30 118608 8192000000 10118510128 10596663296 1127124192 12.094 98 11 22 0 1166280320 37080672 2075456
13:16:31 96275 8192000000 10083301152 10559561728 1127000064 12.093 98 11 22 0 1183972320 54780368 2191888
13:20:56 47101 8192000000 10072372048 10550476800 1121155392 12.038 98 13 26 0 1168695904 44929936 2610576
13:22:47 48908 8192000000 10069634688 10532306944 1118753360 12.015 99 16 32 1 1160061632 37911136 3397136
13:24:49 45392 8192000000 10096844144 10544746496 1129829216 12.120 99 31 62 1 1173962560 36779248 7354096
13:26:37 33897 8192000000 9041955696 9276588032 195188128 2.327 18 3 6 1 205269424 9354704 726592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-03-2021 13:28:10 to 14-03-2021 13:28:24.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-03-2021 13:28:25 to 14-03-2021 13:28:27.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229581
MON$OLDEST_ACTIVE               229582
MON$OLDEST_SNAPSHOT             229582
MON$NEXT_TRANSACTION            229582
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2084832
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

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