Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2021 10: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 16035088 kB
MemAvailable 30759120 kB
Buffers 0 kB
Cached 15640780 kB
SwapCached 1628 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.1
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-09-25 10:37:10
DTS_END                         2021-09-25 10:37:10.4540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57426
TRN_ID                          225849
ATT_ID                          677
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: 8433.95 22314 1012074
customer order: creation 163.48 422 19617
customer order: refuse 14.20 395 1704
order to supplier: creation 42.83 1558 5139
order to supplier: removal 3.13 128061 376
invoice (draft): creation 43.76 4036 5251
invoice (draft): removal 47.48 1120 5698
invoice accept: apply 39.21 21557 4705
invoice accept: cancel 42.47 1000 5096
customer reserve: creation 3596.45 213 431574
customer reserve: removal 4170.99 74 500519
realization accept: apply 77.27 203 9272
realization accept: cancel 72.80 149 8736
payment from customer: creation 55.43 127 6652
payment from customer: removal 5.19 98 623
payment to supplier: creation 45.90 156 5508
payment to supplier: removal 3.34 84 401
service: total inventory turnovers 3.18 4208 382
service: total monetary turnovers 5.96 631 715
service: aggregate perf. data 0.85 16576 102
service: refresh index statistics 0.03 265604 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2021 10:38:21 to 25-09-2021 10:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3530
TEST_TIME 2 2889
TEST_TIME 3 3746
TEST_TIME 4 3697
TEST_TIME 5 4326
TEST_TIME 6 4970
TEST_TIME 7 5959
TEST_TIME 8 6181
TEST_TIME 9 6388
TEST_TIME 10 6656
TEST_TIME 11 6656
TEST_TIME 12 6471
TEST_TIME 13 6543
TEST_TIME 14 6680
TEST_TIME 15 7112
TEST_TIME 16 7020
TEST_TIME 17 7138
TEST_TIME 18 7197
TEST_TIME 19 7322
TEST_TIME 20 7777
TEST_TIME 21 7780
TEST_TIME 22 8134
TEST_TIME 23 8268
TEST_TIME 24 8601
TEST_TIME 25 8706
TEST_TIME 26 9317
TEST_TIME 27 9479
TEST_TIME 28 9886
TEST_TIME 29 10202
TEST_TIME 30 9874
TEST_TIME 31 9908
TEST_TIME 32 9872
TEST_TIME 33 9764
TEST_TIME 34 9569
TEST_TIME 35 9326
TEST_TIME 36 9118
TEST_TIME 37 9215
TEST_TIME 38 9175
TEST_TIME 39 9130
TEST_TIME 40 9112
TEST_TIME 41 8997
TEST_TIME 42 9010
TEST_TIME 43 8898
TEST_TIME 44 9021
TEST_TIME 45 8858
TEST_TIME 46 8792
TEST_TIME 47 8711
TEST_TIME 48 8658
TEST_TIME 49 8562
TEST_TIME 50 8592
TEST_TIME 51 8522
TEST_TIME 52 8434
TEST_TIME 53 8378
TEST_TIME 54 8283
TEST_TIME 55 8287
TEST_TIME 56 8280
TEST_TIME 57 8264
TEST_TIME 58 8273
TEST_TIME 59 8229
TEST_TIME 60 8335
TEST_TIME 61 8350
TEST_TIME 62 8302
TEST_TIME 63 8268
TEST_TIME 64 8424
TEST_TIME 65 8600
TEST_TIME 66 8623
TEST_TIME 67 8653
TEST_TIME 68 8591
TEST_TIME 69 8768
TEST_TIME 70 8832
TEST_TIME 71 8772
TEST_TIME 72 8690
TEST_TIME 73 8655
TEST_TIME 74 8637
TEST_TIME 75 8634
TEST_TIME 76 8604
TEST_TIME 77 8553
TEST_TIME 78 8544
TEST_TIME 79 8468
TEST_TIME 80 8404
TEST_TIME 81 8459
TEST_TIME 82 8450
TEST_TIME 83 8536
TEST_TIME 84 8514
TEST_TIME 85 8492
TEST_TIME 86 8574
TEST_TIME 87 8579
TEST_TIME 88 8619
TEST_TIME 89 8634
TEST_TIME 90 8575
TEST_TIME 91 8658
TEST_TIME 92 8649
TEST_TIME 93 8664
TEST_TIME 94 8582
TEST_TIME 95 8549
TEST_TIME 96 8536
TEST_TIME 97 8515
TEST_TIME 98 8466
TEST_TIME 99 8445
TEST_TIME 100 8409
TEST_TIME 101 8378
TEST_TIME 102 8368
TEST_TIME 103 8405
TEST_TIME 104 8453
TEST_TIME 105 8455
TEST_TIME 106 8443
TEST_TIME 107 8599
TEST_TIME 108 8612
TEST_TIME 109 8626
TEST_TIME 110 8615
TEST_TIME 111 8600
TEST_TIME 112 8601
TEST_TIME 113 8553
TEST_TIME 114 8570
TEST_TIME 115 8538
TEST_TIME 116 8512
TEST_TIME 117 8486
TEST_TIME 118 8494
TEST_TIME 119 8498
TEST_TIME 120 8436

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-09-2021 10:38:24 to 25-09-2021 10:38:28.

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 1410934 1410934 0 0.00 0 34386 8 0 0
doc_list_biud 1410932 1410932 0 0.00 0 39239 34 0 0
sp_add_invoice_to_stock 4795 4705 90 1.87 35 261836 21557 0 90
sp_cancel_adding_invoice 5096 5096 0 0.00 5 57214 1001 0 0
sp_cancel_client_order 1704 1704 0 0.00 5 8858 396 0 0
sp_cancel_customer_reserve 500519 500519 0 0.00 1 37709 75 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 2 5074 99 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 2738 85 0 0
sp_cancel_supplier_invoice 5703 5698 5 0.08 2 39247 1120 0 5
sp_cancel_supplier_order 376 376 0 0.00 7 1150231 128061 0 0
sp_cancel_write_off 8736 8736 0 0.00 2 27351 150 0 0
sp_client_order 19617 19617 0 0.00 4 38771 422 0 0
sp_customer_reserve 431574 431574 0 0.00 0 60206 213 0 0
sp_fill_shopping_cart 29993 29993 0 0.00 1 22403 56 0 0
sp_fill_shopping_cart_clo_res 431568 431568 0 0.00 0 28464 20 0 0
sp_get_clo_for_invoice 4699 4699 0 0.00 0 29333 433 0 0
sp_kill_cost_storno 14856 14856 0 0.00 0 24452 79 0 0
sp_kill_qstorno_ret_qs2qd 1015848 1015848 0 0.00 0 39236 41 0 0
sp_kill_qty_storno 536098 536098 0 0.00 0 39237 88 0 0
sp_lock_selected_doc 19455 19455 0 0.00 0 6388 4 0 0
sp_make_cost_storno 26131 26131 0 0.00 0 34364 62 0 0
sp_multiply_rows_for_pdistr 26130 26130 0 0.00 0 11021 33 0 0
sp_multiply_rows_for_qdistr 19617 19617 0 0.00 0 38760 221 0 0
sp_pay_from_customer 6652 6652 0 0.00 4 11205 128 0 0
sp_pay_to_supplier 5508 5508 0 0.00 5 25985 157 0 0
sp_payment_common 12160 12160 0 0.00 1 25775 33 0 0
sp_qd_handle_on_cancel_clo 1704 1704 0 0.00 0 8846 360 0 0
sp_qd_handle_on_invoice_upd_sts 9793 9793 0 0.00 0 21418 419 0 0
sp_qd_handle_on_reserve_upd_sts 9272 9272 0 0.00 0 17386 67 0 0
sp_reserve_write_off 9272 9272 0 0.00 7 17518 204 0 0
sp_supplier_invoice 5251 5251 0 0.00 8 90690 4036 0 0
sp_supplier_order 5139 5139 0 0.00 6 52677 1559 0 0
srv_aggregate_perf_data 102 102 0 0.00 2866 155472 16576 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1107 136275 42725 0 0
srv_find_qd_qs_mism 966932 966932 0 0.00 0 12302 7 0 0
srv_make_invnt_saldo 382 382 0 0.00 41 116363 4209 0 0
srv_make_money_saldo 715 715 0 0.00 1 22167 632 0 0
srv_recalc_idx_stat 4 4 0 0.00 140716 473445 265605 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4705 4705 0 0.00 0 19322 220 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 7312 135 0 0
t$perf-norm:sp_cancel_client_order 1704 1704 0 0.00 0 3013 15 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1164 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 2015 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1546 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 590 14 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 25506 1822 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 4688 16 0 0
t$perf-norm:sp_client_order 19618 19618 0 0.00 0 5970 22 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 2498 6 0 0
t$perf-norm:sp_pay_from_customer 6652 6652 0 0.00 0 6557 10 0 0
t$perf-norm:sp_pay_to_supplier 5508 5508 0 0.00 0 4128 6 0 0
t$perf-norm:sp_reserve_write_off 9272 9272 0 0.00 0 5222 9 0 0
t$perf-norm:sp_supplier_invoice 5251 5251 0 0.00 0 14108 20 0 0
t$perf-norm:sp_supplier_order 5139 5139 0 0.00 0 3518 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 391 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 57 3 0 0
t$perf-norm:srv_make_invnt_saldo 382 382 0 0.00 0 5950 22 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 270 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 296 91 0 0
v_add_invoice_to_stock 9572 9392 180 1.88 0 6532 19 0 180
v_all_customers 35782 35782 0 0.00 0 207 1 0 0
v_all_suppliers 28588 28588 0 0.00 0 18 1 0 0
v_all_wares 39228 39228 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 3947 14 0 0
v_cancel_client_order 3408 3408 0 0.00 0 6557 9 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 5030 9 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 2732 7 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1219 7 0 0
v_cancel_supplier_invoice 750 740 10 1.33 0 6299 26 0 10
v_cancel_supplier_order 752 752 0 0.00 0 3709 16 0 0
v_cancel_write_off 1978 1978 0 0.00 0 8792 21 0 0
v_max_id_clo_ord 5135 5135 0 0.00 0 2274 2 0 0
v_max_id_clo_res 983 983 0 0.00 0 1902 14 0 0
v_max_id_ord_sup 5244 5244 0 0.00 0 4530 5 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 138 2 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 3394 5 0 0
v_min_id_clo_ord 5135 5135 0 0.00 0 6970 14 0 0
v_min_id_clo_res 983 983 0 0.00 0 4828 16 0 0
v_min_id_ord_sup 5244 5244 0 0.00 0 8670 12 0 0
v_min_non_paid_invoice 5508 5508 0 0.00 0 25771 29 0 0
v_min_non_paid_realizn 6652 6652 0 0.00 0 9174 23 0 0
v_our_firm 9668 9668 0 0.00 0 22 1 0 0
v_reserve_write_off 18544 18544 0 0.00 0 6559 6 0 0
x_lock_depdocs_on_canc_invoice 10424 10424 0 0.00 0 9067 128 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 7314 431 0 0
x_make_qstorno_1000_1200 5139 5139 0 0.00 2 42235 1453 0 0
x_make_qstorno_1200_2000 5251 5251 0 0.00 3 87036 3827 0 0
x_make_qstorno_2100_3300 431574 431574 0 0.00 0 60204 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2021 10:38:29 to 25-09-2021 10:38:33.

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
08:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 30597 8192000000 9716563120 10014806016 788236240 8.777 100 86 170 2 817052720 7581104 21235376
08:09:47 20724 8192000000 9763927072 10070528000 836247904 9.262 100 86 172 0 868192416 11531504 20413008
08:11:14 18490 8192000000 9794773056 10109743104 865060192 9.551 100 88 176 0 905784912 20138176 20586544
08:12:36 13314 8192000000 9840634880 10168127488 897643200 9.875 100 77 154 0 944912384 29897136 17372048
08:13:54 10828 8192000000 9898216320 10236932096 942444384 10.317 100 73 146 0 1005618272 47911696 15262192
08:15:08 7165 8192000000 9949040320 10296680448 979210496 10.677 100 87 174 1 1054032784 56739472 18082816
08:16:17 4221 8192000000 10010845584 10370260992 1008903744 10.965 100 85 168 1 1121827472 96165632 16758096
08:17:24 4895 8192000000 10010502592 10372431872 1032759856 11.195 100 83 165 0 1114666624 65522288 16384480
08:18:31 3472 8192000000 10020934800 10395041792 1056645104 11.424 100 80 160 0 1141660880 68369472 16646304
08:19:36 7583 8192000000 10032188256 10411331584 1071278224 11.564 100 86 172 1 1147633680 59756752 16598704
08:20:45 5277 8192000000 10012649216 10404937728 1079438896 11.642 100 47 94 0 1136780048 47673440 9667712
08:21:52 4939 8192000000 10007929728 10397261824 1083429008 11.680 100 37 73 1 1121192032 29702768 8060256
08:22:59 60420 8192000000 10013063248 10404970496 1091070272 11.753 100 55 108 2 1143304592 40499008 11735312
08:25:00 4518 8192000000 10029862592 10428391424 1093767504 11.778 100 43 85 0 1137179792 34717984 8694304
08:26:07 7995 8192000000 10012148432 10426155008 1102633488 11.863 100 45 89 1 1129491952 16993520 9864944
08:27:21 7144 8192000000 10002432048 10411077632 1092014560 11.762 100 18 34 2 1108511184 12737568 3759056
08:28:29 22962 8192000000 9985117056 10376478720 1068420752 11.537 99 54 108 0 1093577984 13362112 11795120
08:29:53 6840 8192000000 9981212144 10370113536 1055707984 11.415 99 40 79 0 1087152096 22598016 8846096
08:31:03 6580 8192000000 9957019248 10343841792 1021510800 11.087 98 23 46 0 1064524384 38635520 4378064
08:32:11 7603 8192000000 9930670480 10316222464 998352400 10.863 99 38 74 1 1024333920 17227984 8753536
08:33:20 31913 8192000000 9949050800 10351181824 1012201920 10.997 98 39 78 1 1051884224 31851280 7831024
08:34:58 8402 8192000000 9941564416 10339897344 1045387216 11.316 100 38 76 2 1067300576 13152464 8760896
08:36:12 8393 8192000000 9977309824 10382622720 1057225696 11.430 100 40 80 1 1087338496 19906304 10206496
08:37:26 4791 8192000000 10010536896 10403962880 1082114256 11.668 100 63 123 1 1123214672 27442640 13657776
08:38:39 128456 8192000000 10047059136 10463985664 1098717600 11.825 100 27 53 1 1148689584 44639152 5332832
08:41:56 3775 8192000000 10103369552 10503213056 1117126032 12.000 100 60 118 1 1209542320 79424144 12992144
08:43:08 12787 8192000000 10140792400 10545598464 1126191632 12.085 100 62 120 0 1237056032 98409488 12454912
08:44:23 9809 8192000000 10154767712 10564349952 1140867264 12.224 100 66 132 2 1268907696 113754480 14285952
08:45:37 3539 8192000000 10180913568 10583097344 1150470704 12.314 100 79 155 2 1280061168 113297408 16293056
08:46:45 3367 8192000000 10209075584 10620366848 1150623872 12.315 100 63 125 1 1313922720 149742656 13556192
08:47:51 4898 8192000000 10209733824 10623299584 1158701888 12.391 100 61 119 1 1317952736 145128800 14122048
08:49:02 6448 8192000000 10229029344 10647220224 1173596992 12.530 100 54 104 0 1329448976 144043376 11808608
08:50:15 1792 8192000000 10299974576 10702929920 1169991728 12.497 100 89 174 0 1404241984 215305056 18945200
08:51:21 3271 8192000000 10332160464 10742657024 1166754000 12.466 100 82 163 1 1435752416 249340400 19658016
08:52:31 5485 8192000000 10313165392 10718765056 1148202848 12.293 99 75 150 1 1411666064 246149184 17314032
08:53:42 13710 8192000000 10330926400 10754625536 1167414736 12.473 100 56 112 0 1430399392 248701760 14282896
08:55:01 2875 8192000000 10365006928 10771193856 1172053456 12.516 99 84 166 1 1465816656 274435504 19327696
08:56:09 5380 8192000000 10393277776 10800926720 1171788480 12.514 100 83 165 0 1488323392 296473968 20060944
08:57:21 1103 8192000000 10406531520 10808741888 1169960064 12.496 100 90 179 0 1501345424 311103456 20281904
08:58:26 6965 8192000000 10381043264 10797830144 1170786192 12.504 100 53 105 1 1478660560 295362432 12511936
08:59:38 10389 8192000000 10373275120 10804195328 1146559968 12.277 100 40 78 1 1477957936 321323248 10074720
09:00:57 11988 8192000000 10338315920 10779484160 1121641872 12.042 98 33 66 0 1445156496 314765424 8749200
09:02:22 26181 8192000000 10333441776 10785050624 1125375360 12.078 100 30 60 0 1430871136 297604080 7891696
09:03:51 26121 8192000000 10242850608 10676195328 1121649488 12.043 99 30 60 1 1337004160 208387392 6967280
09:05:20 19995 8192000000 10156243968 10617528320 1107132688 11.905 98 18 36 1 1261563024 149959968 4470368
09:06:44 64091 8192000000 10097981968 10518876160 1127233776 12.095 99 39 70 0 1191772640 57998352 6540512
09:08:50 45132 8192000000 10120164672 10590986240 1138856000 12.205 100 14 28 0 1231955952 90839168 2260784
09:12:36 64675 8192000000 10139392080 10574626816 1139238512 12.208 99 33 66 1 1229600736 83285600 7076624
09:15:08 38120 8192000000 10125155360 10576084992 1128484304 12.107 98 34 68 2 1205045024 69044240 7516480
09:16:47 38821 8192000000 10115050448 10559741952 1114371040 11.974 98 21 42 1 1206244064 86872128 5000896
09:18:36 54553 8192000000 10111752960 10579996672 1120557248 12.032 98 14 28 0 1226854960 103095600 3202112
09:20:33 52770 8192000000 10104759648 10567540736 1128490336 12.107 99 18 36 1 1208652064 76013120 4148608
09:22:31 62220 8192000000 10061021936 10503839744 1131112448 12.132 99 26 53 0 1168544080 31914608 5517024
09:24:38 13157 8192000000 10066532576 10506788864 1134342304 12.162 99 32 60 2 1167256336 26915232 5998800
09:25:52 48518 8192000000 10081887360 10521104384 1133508560 12.154 99 23 45 0 1195055840 57135088 4412192
09:27:49 44324 8192000000 10056062464 10529054720 1137507840 12.192 99 3 6 0 1161151664 23147536 496288
09:29:44 92982 8192000000 10105858288 10560167936 1162446240 12.426 100 13 26 1 1211486288 46438304 2601744
09:32:17 50756 8192000000 10109027472 10607329280 1167827632 12.477 100 17 34 0 1246519888 74709136 3983120
09:34:54 71842 8192000000 10115212304 10563510272 1165471216 12.454 100 9 18 1 1206312064 38411088 2429760
09:37:22 54507 8192000000 10078909936 10569244672 1131507328 12.136 96 13 26 0 1213549520 79327616 2714576
09:39:18 36603 8192000000 10138721792 10583633920 1115248032 11.982 96 30 60 0 1216438416 95143360 6047024
09:41:00 63404 8192000000 10161484064 10715676672 1137932560 12.196 99 15 30 3 1274306144 133506464 2867120
09:43:13 80104 8192000000 10064484864 10513309696 1063178400 11.487 93 16 31 0 1104754640 38623968 2952272
09:45:39 83479 8192000000 10098526608 10600419328 1120195952 12.029 98 11 22 0 1180861328 58315328 2350048
09:48:03 25159 8192000000 10066618224 10509918208 1127592240 12.099 99 43 85 2 1157884192 21657984 8633968
09:49:31 136275 8192000000 10069353216 10550685696 1127758208 12.100 99 27 54 0 1171857664 37904480 6194976
09:53:00 35910 8192000000 10035567552 10467905536 1071206416 11.564 95 27 54 1 1110126768 33475024 5445328
09:54:47 49801 8192000000 10084417488 10541834240 1117176576 12.000 99 15 30 1 1182625056 61930176 3518304
09:56:53 61743 8192000000 10109487184 10555973632 1109045360 11.923 99 22 44 0 1196537680 82644960 4847360
09:59:05 68713 8192000000 10101549136 10549415936 1122413136 12.050 100 20 39 0 1210172240 83481216 4277888
10:01:23 43155 8192000000 10080646480 10517323776 1107886320 11.912 98 24 48 0 1164141040 51345312 4909408
10:03:15 64746 8192000000 10080571600 10524852224 1120644688 12.033 99 23 46 0 1153951184 28392480 4914016
10:05:26 89002 8192000000 10082678304 10550358016 1126454672 12.088 99 26 52 1 1214676544 83024432 5197440
10:07:55 38933 8192000000 10126108480 10586062848 1110501072 11.937 98 37 74 0 1219961568 101786432 7674064
10:09:38 33315 8192000000 10106606880 10542632960 1131405440 12.135 100 32 64 0 1199064736 61518160 6141136
10:11:17 77247 8192000000 10072300800 10501709824 1129285184 12.115 100 39 77 1 1184667568 47659792 7722592
10:13:34 55995 8192000000 10044421408 10537734144 1118192384 12.010 99 15 31 0 1168175104 46425856 3556864
10:15:33 82714 8192000000 10080339264 10549796864 1123413184 12.059 99 9 18 0 1158586848 33022928 2150736
10:18:08 52117 8192000000 10159119936 10602065920 1136155888 12.179 99 26 52 2 1250028256 108083312 5789056
10:20:09 50027 8192000000 10143490112 10569883648 1137332384 12.190 99 36 72 1 1233148720 88520656 7295680
10:21:59 39732 8192000000 10143945104 10581557248 1090026032 11.743 96 34 68 3 1190878224 93220640 7631552
10:23:53 54113 8192000000 10180111856 10671120384 1128656928 12.109 100 15 30 0 1267640000 135504112 3478960
10:25:49 64935 8192000000 10059227424 10505048064 1075597536 11.606 95 23 44 1 1137148240 57106048 4444656
10:28:00 21766 8192000000 10130698512 10560225280 1126501776 12.088 100 50 100 0 1232176592 95384800 10290016
10:29:26 84500 8192000000 10071989312 10525601792 1118875536 12.016 99 35 71 0 1185349808 58698832 7775440
10:31:51 68980 8192000000 10080521328 10555912192 1137874976 12.196 100 9 18 1 1172595520 32985568 1734976
10:34:00 34881 8192000000 10064177616 10529525760 1130958720 12.130 99 4 8 0 1161401264 29878608 563936
10:35:36 70034 8192000000 10096468416 10558304256 1141067744 12.226 100 15 30 0 1190280560 45709232 3503584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-09-2021 10:38:35 to 25-09-2021 10:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2021 10:38:51 to 25-09-2021 10:38:53.

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          225577
MON$OLDEST_ACTIVE               225986
MON$OLDEST_SNAPSHOT             225986
MON$NEXT_TRANSACTION            225986
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                       2037216
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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'

25.09.21 10:38:54. 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-09-25 10:38:56 No severe PSQL-related problems occured
25.09.21 10:38:58. End of report.