Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.05.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16272992 kB
MemAvailable 31553916 kB
Buffers 0 kB
Cached 15459860 kB
SwapCached 3260 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.2
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 2022-05-03 10:37:13
DTS_END                         2022-05-03 10:37:13.3580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43278
TRN_ID                          221487
ATT_ID                          659
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: 9133.28 31232 1095994
customer order: creation 165.68 499 19881
customer order: refuse 14.81 491 1777
order to supplier: creation 44.33 1443 5319
order to supplier: removal 3.40 185962 408
invoice (draft): creation 44.29 3353 5315
invoice (draft): removal 52.44 994 6293
invoice accept: apply 41.55 27687 4986
invoice accept: cancel 47.91 1309 5749
customer reserve: creation 3797.03 265 455644
customer reserve: removal 4623.98 107 554878
realization accept: apply 81.76 313 9811
realization accept: cancel 87.88 170 10545
payment from customer: creation 58.80 188 7056
payment from customer: removal 5.47 134 656
payment to supplier: creation 47.81 211 5737
payment to supplier: removal 3.35 160 402
service: total inventory turnovers 3.86 4827 463
service: total monetary turnovers 8.01 371 961
service: aggregate perf. data 0.91 19227 109
service: refresh index statistics 0.03 376933 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-05-2022 10:39:18 to 03-05-2022 10:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5498
TEST_TIME 2 4395
TEST_TIME 3 5847
TEST_TIME 4 5552
TEST_TIME 5 5534
TEST_TIME 6 5888
TEST_TIME 7 5884
TEST_TIME 8 5866
TEST_TIME 9 5918
TEST_TIME 10 6118
TEST_TIME 11 6206
TEST_TIME 12 6268
TEST_TIME 13 6401
TEST_TIME 14 6501
TEST_TIME 15 6845
TEST_TIME 16 6727
TEST_TIME 17 6904
TEST_TIME 18 6849
TEST_TIME 19 7102
TEST_TIME 20 7166
TEST_TIME 21 7200
TEST_TIME 22 7257
TEST_TIME 23 7436
TEST_TIME 24 7563
TEST_TIME 25 7673
TEST_TIME 26 7711
TEST_TIME 27 8286
TEST_TIME 28 8327
TEST_TIME 29 8375
TEST_TIME 30 8345
TEST_TIME 31 8616
TEST_TIME 32 8615
TEST_TIME 33 9232
TEST_TIME 34 9305
TEST_TIME 35 9895
TEST_TIME 36 10535
TEST_TIME 37 10671
TEST_TIME 38 10485
TEST_TIME 39 10399
TEST_TIME 40 10334
TEST_TIME 41 10321
TEST_TIME 42 10242
TEST_TIME 43 10075
TEST_TIME 44 10008
TEST_TIME 45 9923
TEST_TIME 46 9946
TEST_TIME 47 9972
TEST_TIME 48 9818
TEST_TIME 49 9770
TEST_TIME 50 9729
TEST_TIME 51 9767
TEST_TIME 52 9724
TEST_TIME 53 9770
TEST_TIME 54 9750
TEST_TIME 55 9629
TEST_TIME 56 9724
TEST_TIME 57 9703
TEST_TIME 58 9602
TEST_TIME 59 9488
TEST_TIME 60 9387
TEST_TIME 61 9427
TEST_TIME 62 9382
TEST_TIME 63 9380
TEST_TIME 64 9307
TEST_TIME 65 9303
TEST_TIME 66 9297
TEST_TIME 67 9316
TEST_TIME 68 9335
TEST_TIME 69 9395
TEST_TIME 70 9470
TEST_TIME 71 9409
TEST_TIME 72 9436
TEST_TIME 73 9433
TEST_TIME 74 9668
TEST_TIME 75 9596
TEST_TIME 76 9693
TEST_TIME 77 9645
TEST_TIME 78 9555
TEST_TIME 79 9503
TEST_TIME 80 9483
TEST_TIME 81 9494
TEST_TIME 82 9476
TEST_TIME 83 9412
TEST_TIME 84 9409
TEST_TIME 85 9331
TEST_TIME 86 9343
TEST_TIME 87 9302
TEST_TIME 88 9257
TEST_TIME 89 9325
TEST_TIME 90 9340
TEST_TIME 91 9334
TEST_TIME 92 9383
TEST_TIME 93 9365
TEST_TIME 94 9319
TEST_TIME 95 9316
TEST_TIME 96 9351
TEST_TIME 97 9405
TEST_TIME 98 9416
TEST_TIME 99 9423
TEST_TIME 100 9345
TEST_TIME 101 9330
TEST_TIME 102 9287
TEST_TIME 103 9277
TEST_TIME 104 9224
TEST_TIME 105 9186
TEST_TIME 106 9210
TEST_TIME 107 9201
TEST_TIME 108 9220
TEST_TIME 109 9198
TEST_TIME 110 9181
TEST_TIME 111 9171
TEST_TIME 112 9185
TEST_TIME 113 9193
TEST_TIME 114 9206
TEST_TIME 115 9283
TEST_TIME 116 9260
TEST_TIME 117 9268
TEST_TIME 118 9254
TEST_TIME 119 9231
TEST_TIME 120 9154

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-05-2022 10:39:22 to 03-05-2022 10:39:25.

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 1516115 1516115 0 0.00 0 20530 15 0 0
doc_list_biud 1516103 1516103 0 0.00 0 27827 45 0 0
sp_add_invoice_to_stock 5196 4986 210 4.04 53 229919 27687 0 210
sp_cancel_adding_invoice 5749 5749 0 0.00 7 96680 1309 0 0
sp_cancel_client_order 1777 1777 0 0.00 6 11547 491 0 0
sp_cancel_customer_reserve 554878 554878 0 0.00 1 22151 107 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 4 8014 134 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 4 4230 161 0 0
sp_cancel_supplier_invoice 6316 6293 23 0.36 3 13454 995 0 23
sp_cancel_supplier_order 408 408 0 0.00 13 1875471 185963 0 0
sp_cancel_write_off 10545 10545 0 0.00 2 10125 171 0 0
sp_client_order 19881 19881 0 0.00 6 45439 499 0 0
sp_customer_reserve 455644 455644 0 0.00 1 111173 266 0 0
sp_fill_shopping_cart 30500 30500 0 0.00 1 19438 88 0 0
sp_fill_shopping_cart_clo_res 455615 455615 0 0.00 0 11491 20 0 0
sp_get_clo_for_invoice 4946 4946 0 0.00 0 21489 325 0 0
sp_kill_cost_storno 17352 17352 0 0.00 0 7997 79 0 0
sp_kill_qstorno_ret_qs2qd 1127002 1127002 0 0.00 0 22082 46 0 0
sp_kill_qty_storno 594402 594402 0 0.00 0 27827 106 0 0
sp_lock_selected_doc 20443 20443 0 0.00 0 9008 12 0 0
sp_make_cost_storno 27548 27548 0 0.00 0 12787 84 0 0
sp_multiply_rows_for_pdistr 27546 27546 0 0.00 0 8201 42 0 0
sp_multiply_rows_for_qdistr 19881 19881 0 0.00 0 45423 199 0 0
sp_pay_from_customer 7056 7056 0 0.00 6 14695 189 0 0
sp_pay_to_supplier 5737 5737 0 0.00 6 18159 212 0 0
sp_payment_common 12792 12792 0 0.00 2 9563 47 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 11523 406 0 0
sp_qd_handle_on_invoice_upd_sts 10694 10694 0 0.00 0 27827 589 0 0
sp_qd_handle_on_reserve_upd_sts 9807 9807 0 0.00 0 12542 65 0 0
sp_reserve_write_off 9811 9811 0 0.00 9 21357 313 0 0
sp_supplier_invoice 5315 5315 0 0.00 10 93197 3354 0 0
sp_supplier_order 5319 5319 0 0.00 8 29076 1444 0 0
srv_aggregate_perf_data 109 109 0 0.00 2589 206482 19228 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 732 145105 31726 0 0
srv_find_qd_qs_mism 1048344 1048344 0 0.00 0 20529 12 0 0
srv_make_invnt_saldo 463 463 0 0.00 70 123731 4828 0 0
srv_make_money_saldo 961 961 0 0.00 1 8733 372 0 0
srv_recalc_idx_stat 4 4 0 0.00 219408 495117 376934 0 0
t$perf-abend:sp_add_invoice_to_stock 210 210 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 23 23 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 0 14107 1071 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 6455 550 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 3520 33 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2369 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 887 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 1762 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 2049 52 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 125772 8308 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 7025 25 0 0
t$perf-norm:sp_client_order 19883 19883 0 0.00 0 7049 41 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 497 17 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 3365 22 0 0
t$perf-norm:sp_pay_to_supplier 5737 5737 0 0.00 0 5304 20 0 0
t$perf-norm:sp_reserve_write_off 9812 9812 0 0.00 0 12176 26 0 0
t$perf-norm:sp_supplier_invoice 5316 5316 0 0.00 0 3305 36 0 0
t$perf-norm:sp_supplier_order 5320 5320 0 0.00 0 4001 36 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 4445 73 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 940 25 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 5841 34 0 0
t$perf-norm:srv_make_money_saldo 961 961 0 0.00 0 1424 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 178 64 0 0
v_add_invoice_to_stock 10294 9874 420 4.08 0 7860 29 0 420
v_all_customers 36632 36632 0 0.00 0 252 4 0 0
v_all_suppliers 29842 29842 0 0.00 0 75 4 0 0
v_all_wares 39750 39750 0 0.00 0 145 4 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 748 16 0 0
v_cancel_client_order 3554 3554 0 0.00 0 2814 12 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 438 10 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 2981 14 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 1676 16 0 0
v_cancel_supplier_invoice 854 808 46 5.38 0 3101 36 0 46
v_cancel_supplier_order 816 816 0 0.00 0 508 12 0 0
v_cancel_write_off 1924 1924 0 0.00 0 4188 27 0 0
v_max_id_clo_ord 5312 5312 0 0.00 0 2232 6 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 1124 12 0 0
v_max_id_ord_sup 5307 5307 0 0.00 0 4529 8 0 0
v_max_non_paid_invoice 1435 1435 0 0.00 0 7844 13 0 0
v_max_non_paid_realizn 3669 3669 0 0.00 0 1493 8 0 0
v_min_id_clo_ord 5312 5312 0 0.00 0 3708 13 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 3530 12 0 0
v_min_id_ord_sup 5307 5307 0 0.00 0 6654 11 0 0
v_min_non_paid_invoice 5736 5736 0 0.00 0 2632 14 0 0
v_min_non_paid_realizn 7055 7055 0 0.00 0 7287 17 0 0
v_our_firm 9890 9890 0 0.00 0 53 5 0 0
v_reserve_write_off 19612 19612 0 0.00 0 3048 12 0 0
x_lock_depdocs_on_canc_invoice 11638 11638 0 0.00 0 29393 128 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 9784 419 0 0
x_make_qstorno_1000_1200 5319 5319 0 0.00 3 29060 1249 0 0
x_make_qstorno_1200_2000 5315 5315 0 0.00 6 93039 3114 0 0
x_make_qstorno_2100_3300 455643 455643 0 0.00 0 111088 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-05-2022 10:39:26 to 03-05-2022 10:39:30.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:16 25223 8192000000 9710788912 10007298048 784389488 8.738 100 86 172 0 812823472 7594560 20839424
08:09:47 18684 8192000000 9767234224 10078777344 839964608 9.299 100 83 166 0 871308368 11482368 19861392
08:11:21 18794 8192000000 9808395904 10129645568 875794576 9.658 100 87 174 1 919184224 23487904 19901744
08:12:43 8265 8192000000 9866851408 10199166976 909231344 9.990 100 88 175 0 979857520 51364400 19261776
08:13:58 7891 8192000000 9918731968 10261008384 953509456 10.425 100 84 168 0 1032101360 60753856 17838048
08:15:08 2225 8192000000 9962105760 10313617408 993522704 10.816 100 87 169 0 1079363440 68396864 17443872
08:16:13 1203 8192000000 9989307728 10351616000 1015062656 11.024 100 96 190 1 1113830832 79714144 19054032
08:17:16 1048 8192000000 10027446704 10396536832 1034253744 11.209 100 95 184 2 1146005120 92618144 19133232
08:18:18 1191 8192000000 10037955904 10415304704 1050222112 11.363 100 96 182 3 1165677872 96715920 18739840
08:19:21 881 8192000000 10033823040 10409369600 1067923200 11.532 100 94 175 0 1151981728 65205104 18853424
08:20:23 960 8192000000 10054210640 10442162176 1081054160 11.658 100 85 166 0 1172677264 75403216 16219888
08:21:25 1019 8192000000 10041242320 10424225792 1084622832 11.692 100 93 175 3 1161919024 59523200 17772992
08:22:27 1203 8192000000 10067808992 10453114880 1088334032 11.727 100 95 178 3 1186509520 80100256 18075232
08:23:29 1349 8192000000 10064822976 10453864448 1093003696 11.771 100 97 189 1 1186356464 74439024 18913744
08:24:31 1177 8192000000 10062360048 10451939328 1098323120 11.822 100 94 177 2 1181012576 64493296 18196160
08:25:33 1232 8192000000 10066852144 10457010176 1104510864 11.880 100 91 169 1 1185050688 63560496 16979328
08:26:36 1446 8192000000 10073494384 10461360128 1101319792 11.850 100 98 194 0 1196724736 75986816 19418128
08:27:38 1242 8192000000 10068936000 10459480064 1101685968 11.854 100 96 178 0 1190450688 69953456 18811264
08:28:40 1507 8192000000 10070181728 10460758016 1103051872 11.867 100 95 183 2 1191899600 69826032 19021696
08:29:42 1332 8192000000 10038870064 10421862400 1085991520 11.705 100 98 187 2 1158315968 52659088 19665360
08:30:44 1043 8192000000 10044336384 10428022784 1073459104 11.585 100 98 183 0 1167514096 75225824 18829168
08:31:46 1863 8192000000 10011819760 10405277696 1065330592 11.507 99 75 142 2 1132079952 52226144 14523216
08:32:49 5847 8192000000 10021496928 10403971072 1071434320 11.566 100 70 139 0 1138301600 53040960 13826320
08:33:57 1599 8192000000 9996602288 10378612736 1049590800 11.357 100 81 147 1 1120518096 54440448 16486848
08:34:59 855 8192000000 9983405648 10359427072 1038661408 11.252 100 81 155 1 1106842784 51259216 16922160
08:36:01 1110 8192000000 10023625248 10399191040 1046190736 11.324 100 96 184 0 1143740048 77276336 20272976
08:37:03 1336 8192000000 9981672272 10357161984 1047537392 11.337 100 89 169 2 1097879856 33002560 17339904
08:38:13 19338 8192000000 10015131360 10399793152 1055326880 11.412 100 62 124 0 1138597136 70843264 12426992
08:39:42 11476 8192000000 10028623040 10414727168 1070266896 11.555 100 65 129 0 1145206944 61741584 13198464
08:40:58 1028 8192000000 10069364864 10451542016 1087082048 11.715 100 90 168 1 1192167488 86740416 18345024
08:42:03 6075 8192000000 10104561376 10498301952 1100712048 11.844 100 68 129 1 1230886432 116474832 13699552
08:43:13 1097 8192000000 10149866144 10527887360 1103146288 11.867 100 96 183 0 1265856880 142876304 19834288
08:44:19 1881 8192000000 10170383472 10561843200 1111774912 11.949 100 86 159 3 1277393472 149033584 16584976
08:45:27 13764 8192000000 10212620704 10612482048 1123103936 12.056 100 67 132 1 1325719200 187834480 14780784
08:46:46 2003 8192000000 10236669504 10630152192 1141728096 12.232 100 84 161 0 1347876656 188561456 17587104
08:47:51 770 8192000000 10275468672 10668691456 1151983952 12.328 100 96 187 3 1387405968 214698784 20723232
08:48:55 1102 8192000000 10306117664 10700980224 1152586672 12.334 99 96 187 2 1420090224 245912016 21591536
08:50:01 858 8192000000 10327978720 10736799744 1165107088 12.451 100 99 191 0 1437810608 250785968 21917552
08:51:07 1909 8192000000 10315178016 10742120448 1159865312 12.402 100 86 165 1 1429247920 250565744 18816864
08:52:15 1413 8192000000 10337813344 10746564608 1162958432 12.431 100 95 188 2 1451801856 266963808 21879616
08:53:21 1047 8192000000 10340249504 10742382592 1166521456 12.464 100 92 176 1 1449273024 263523280 19228288
08:54:25 1192 8192000000 10405648784 10821263360 1178079136 12.572 100 96 183 2 1513969200 313555760 22334304
08:55:30 1094 8192000000 10441544640 10844635136 1188893872 12.673 100 99 197 0 1552811424 340911760 23005792
08:56:34 1031 8192000000 10434105472 10874605568 1182016144 12.609 100 95 185 0 1538017472 334831312 21170016
08:57:40 959 8192000000 10471833280 10882654208 1185174672 12.638 100 95 183 1 1578603184 371487712 21940800
08:58:43 2043 8192000000 10480716832 10909941760 1173468992 12.529 100 97 193 1 1590370608 393022752 23878864
08:59:50 727 8192000000 10504897568 10936483840 1177462544 12.567 100 97 190 0 1610912880 411274320 22176016
09:00:55 911 8192000000 10543005104 10968584192 1185444320 12.641 100 99 196 0 1649203232 439587776 24171136
09:02:00 1514 8192000000 10566674912 10996695040 1186187616 12.648 100 93 182 0 1675731488 466563488 22980384
09:03:06 861 8192000000 10506892496 10980233216 1166178352 12.461 99 97 194 1 1608544464 418474848 23891264
09:04:11 762 8192000000 10531683584 11017494528 1180390000 12.594 100 96 189 0 1632368048 428888848 23089200
09:05:15 1058 8192000000 10551854720 10980384768 1192116336 12.703 100 95 186 0 1653378320 439292528 21969456
09:06:18 3738 8192000000 10572499104 11021590528 1190968976 12.692 100 59 110 1 1677228880 471875728 14384176
09:07:34 3037 8192000000 10586389136 11060883456 1190047744 12.684 100 58 110 1 1691989600 488079680 13862176
09:08:57 15732 8192000000 10571618496 11046068224 1183448048 12.622 100 46 92 2 1674440048 478454448 12537552
09:10:15 11099 8192000000 10481983952 11061055488 1181294000 12.602 100 45 90 0 1611162032 417870896 11997136
09:11:29 12933 8192000000 10461871232 10922577920 1174020832 12.534 100 41 80 0 1560305824 375858544 10426448
09:13:02 28908 8192000000 10206965856 10733203456 1140537952 12.221 100 16 32 1 1318688768 174007984 4142832
09:14:48 11742 8192000000 10069923088 10512297984 1120294336 12.030 99 28 55 4 1159529728 33051776 6183616
09:16:04 25476 8192000000 10109452496 10562002944 1128358304 12.106 99 33 66 0 1214746192 79630912 6756976
09:17:36 97290 8192000000 10095891104 10566496256 1120669856 12.033 98 26 50 2 1203344960 77816032 4859072
09:20:15 46726 8192000000 10090993408 10519961600 1124005616 12.065 98 55 105 1 1158304880 23993760 10305504
09:22:05 89000 8192000000 10155787968 10637668352 1140774192 12.223 98 31 62 1 1242280912 94551472 6955248
09:24:38 56763 8192000000 10104360704 10545635328 1140174336 12.217 99 23 42 3 1191341040 46140096 5026608
09:26:39 13932 8192000000 10160275664 10605920256 1165336672 12.453 100 54 106 1 1274608880 97841424 11430784
09:28:16 14530 8192000000 10163602736 10605211648 1140086752 12.216 98 54 107 1 1249866912 97570752 12209408
09:29:42 33224 8192000000 10126159808 10581037056 1126093056 12.085 98 50 95 0 1222035600 85410368 10532176
09:31:30 26834 8192000000 10088929088 10542632960 1130396480 12.125 98 13 26 1 1172384608 38936176 3051952
09:33:30 54758 8192000000 10059362640 10521149440 1112200944 11.953 98 9 18 0 1172609184 58197152 2211088
09:35:34 85556 8192000000 10116515264 10587025408 1136526784 12.183 100 18 34 0 1196152352 56540928 3084640
09:38:02 44296 8192000000 10076034576 10572877824 1142542992 12.239 100 15 30 0 1199463984 53667488 3253504
09:39:47 53873 8192000000 10054452032 10525573120 1117665904 12.005 98 19 33 0 1149525648 28294784 3564960
09:41:41 57753 8192000000 10139926176 10589253632 1120550032 12.032 97 37 73 1 1196061744 68428128 7083584
09:43:39 62591 8192000000 10153841456 10596413440 1097557120 11.814 96 21 42 0 1165159168 63087568 4514480
09:45:42 64006 8192000000 10134345744 10597318656 1122506672 12.051 98 16 32 0 1202606640 76700544 3399424
09:47:48 22353 8192000000 10082732192 10515968000 1098614512 11.824 97 38 75 1 1143253472 37039872 7599088
09:49:11 14041 8192000000 10154140704 10596904960 1128911984 12.111 100 47 92 2 1248872240 110576944 9383312
09:50:29 46583 8192000000 10128993920 10607194112 1088479152 11.728 97 13 26 0 1235423168 143820624 3123392
09:52:19 82182 8192000000 10124017168 10600992768 1100130768 11.839 98 13 26 0 1165253088 62468720 2653600
09:54:42 67787 8192000000 10082143520 10516561920 1122470752 12.050 99 25 48 0 1163713504 36217008 5025744
09:56:52 40283 8192000000 10117936752 10562207744 1123682544 12.062 99 35 70 1 1209806576 78346304 7777728
09:58:34 145105 8192000000 10110853312 10587033600 1080817648 11.655 95 21 42 0 1158991552 74345264 3828640
10:02:00 80483 8192000000 10129410448 10606039040 1100790912 11.845 97 15 31 0 1194863088 90378624 3693552
10:04:27 59672 8192000000 10123682304 10564792320 1119809984 12.025 99 27 50 0 1221190256 97006864 4373408
10:06:35 12304 8192000000 10077785760 10506461184 1128058720 12.103 99 66 132 3 1178071872 34363968 15649184
10:07:49 67420 8192000000 10131266672 10583973888 1141453200 12.229 100 25 50 0 1224029008 77755312 4820496
10:10:00 46156 8192000000 10080630848 10529566720 1135403520 12.172 100 30 60 0 1194288000 52678400 6206080
10:11:59 14088 8192000000 10127561472 10558148608 1120949056 12.036 98 48 96 1 1202136976 71698240 9489680
10:13:28 26465 8192000000 10165116672 10621693952 1121650880 12.043 99 49 98 0 1227780608 96162592 9967136
10:14:55 40945 8192000000 10114737792 10578804736 1139625600 12.212 100 28 56 1 1215315760 69897584 5792576
10:16:39 111929 8192000000 10093936448 10560483328 1144108448 12.254 99 31 62 1 1187789104 37937584 5743072
10:19:39 33689 8192000000 10117926432 10573139968 1141874224 12.233 98 28 54 1 1195120672 46930432 6316016
10:21:18 73916 8192000000 10135907664 10626572288 1146490064 12.277 99 8 16 0 1217169648 68938832 1740752
10:23:42 82590 8192000000 10061870848 10510655488 1107858256 11.912 96 12 24 0 1172213264 61459744 2895264
10:26:19 59637 8192000000 10114141392 10556301312 1136858064 12.186 98 20 40 0 1197771232 56952688 3960480
10:28:19 30071 8192000000 10149576928 10598780928 1142395952 12.238 98 28 56 0 1241681632 93018128 6267552
10:30:03 30403 8192000000 10138587024 10583199744 1089296048 11.736 95 31 60 0 1202163376 105808272 7059056
10:31:39 26818 8192000000 10135322640 10587709440 1098947968 11.828 97 28 56 5 1205339152 100819616 5571568
10:33:13 59774 8192000000 10090844480 10565476352 1126440576 12.088 99 22 44 0 1182322096 50890480 4991040
10:35:13 38306 8192000000 10130860704 10581528576 1116984832 11.998 98 33 66 0 1196594640 72926672 6683136
10:36:59 44728 8192000000 9233905136 9539616768 377271600 4.402 33 3 5 0 396304992 18525984 507408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 03-05-2022 10:39:32 to 03-05-2022 10:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 210 v_add_invoice_to_stock 335544517
except 23 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 03-05-2022 10:39:50 to 03-05-2022 10:39:51.

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          221744
MON$OLDEST_ACTIVE               221745
MON$OLDEST_SNAPSHOT             221745
MON$NEXT_TRANSACTION            221745
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                       2071840
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

03.05.22 10:39:53. 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
2022-05-03 10:39:55 No severe PSQL-related problems occured
03.05.22 10:39:56. End of report.