Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.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 16126896 kB
MemAvailable 31344440 kB
Buffers 0 kB
Cached 15575992 kB
SwapCached 1220 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 2022-01-08 10:37:12
DTS_END                         2022-01-08 10:37:12.1820
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/53980
TRN_ID                          223426
ATT_ID                          620
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: 9076.49 28799 1089179
customer order: creation 167.79 462 20135
customer order: refuse 15.13 377 1816
order to supplier: creation 45.35 1337 5442
order to supplier: removal 3.37 161448 404
invoice (draft): creation 46.30 2968 5556
invoice (draft): removal 50.64 881 6077
invoice accept: apply 41.98 26206 5037
invoice accept: cancel 46.23 1225 5548
customer reserve: creation 3872.36 251 464683
customer reserve: removal 4486.18 96 538341
realization accept: apply 83.76 258 10051
realization accept: cancel 87.97 168 10556
payment from customer: creation 59.31 159 7117
payment from customer: removal 5.68 123 682
payment to supplier: creation 48.57 185 5828
payment to supplier: removal 3.72 98 446
service: total inventory turnovers 3.85 4392 462
service: total monetary turnovers 7.33 424 880
service: aggregate perf. data 0.95 14702 114
service: refresh index statistics 0.03 360223 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-01-2022 10:38:36 to 08-01-2022 10:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10589
TEST_TIME 2 7131
TEST_TIME 3 6095
TEST_TIME 4 6126
TEST_TIME 5 6241
TEST_TIME 6 7048
TEST_TIME 7 6924
TEST_TIME 8 7477
TEST_TIME 9 7476
TEST_TIME 10 7556
TEST_TIME 11 7415
TEST_TIME 12 7762
TEST_TIME 13 7536
TEST_TIME 14 7635
TEST_TIME 15 7784
TEST_TIME 16 7864
TEST_TIME 17 8130
TEST_TIME 18 8162
TEST_TIME 19 8225
TEST_TIME 20 8095
TEST_TIME 21 8357
TEST_TIME 22 8400
TEST_TIME 23 8534
TEST_TIME 24 8664
TEST_TIME 25 8565
TEST_TIME 26 8609
TEST_TIME 27 8737
TEST_TIME 28 8972
TEST_TIME 29 9400
TEST_TIME 30 9526
TEST_TIME 31 9834
TEST_TIME 32 9957
TEST_TIME 33 10352
TEST_TIME 34 11164
TEST_TIME 35 11228
TEST_TIME 36 11257
TEST_TIME 37 11269
TEST_TIME 38 11093
TEST_TIME 39 10958
TEST_TIME 40 10835
TEST_TIME 41 10867
TEST_TIME 42 10734
TEST_TIME 43 10548
TEST_TIME 44 10494
TEST_TIME 45 10376
TEST_TIME 46 10261
TEST_TIME 47 10252
TEST_TIME 48 10159
TEST_TIME 49 10108
TEST_TIME 50 10145
TEST_TIME 51 10096
TEST_TIME 52 10083
TEST_TIME 53 10051
TEST_TIME 54 9947
TEST_TIME 55 9853
TEST_TIME 56 9907
TEST_TIME 57 9835
TEST_TIME 58 9831
TEST_TIME 59 9924
TEST_TIME 60 9870
TEST_TIME 61 9843
TEST_TIME 62 9782
TEST_TIME 63 9830
TEST_TIME 64 9820
TEST_TIME 65 9765
TEST_TIME 66 9858
TEST_TIME 67 9904
TEST_TIME 68 10109
TEST_TIME 69 9984
TEST_TIME 70 9994
TEST_TIME 71 9967
TEST_TIME 72 9888
TEST_TIME 73 9797
TEST_TIME 74 9709
TEST_TIME 75 9694
TEST_TIME 76 9709
TEST_TIME 77 9701
TEST_TIME 78 9662
TEST_TIME 79 9567
TEST_TIME 80 9663
TEST_TIME 81 9664
TEST_TIME 82 9665
TEST_TIME 83 9627
TEST_TIME 84 9686
TEST_TIME 85 9738
TEST_TIME 86 9717
TEST_TIME 87 9693
TEST_TIME 88 9675
TEST_TIME 89 9620
TEST_TIME 90 9583
TEST_TIME 91 9530
TEST_TIME 92 9491
TEST_TIME 93 9475
TEST_TIME 94 9461
TEST_TIME 95 9401
TEST_TIME 96 9357
TEST_TIME 97 9367
TEST_TIME 98 9349
TEST_TIME 99 9318
TEST_TIME 100 9304
TEST_TIME 101 9357
TEST_TIME 102 9315
TEST_TIME 103 9286
TEST_TIME 104 9310
TEST_TIME 105 9318
TEST_TIME 106 9369
TEST_TIME 107 9340
TEST_TIME 108 9442
TEST_TIME 109 9416
TEST_TIME 110 9428
TEST_TIME 111 9405
TEST_TIME 112 9364
TEST_TIME 113 9311
TEST_TIME 114 9294
TEST_TIME 115 9259
TEST_TIME 116 9218
TEST_TIME 117 9184
TEST_TIME 118 9150
TEST_TIME 119 9172
TEST_TIME 120 9101

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2022 10:38:40 to 08-01-2022 10:38:43.

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 1518491 1518491 0 0.00 0 22671 13 0 0
doc_list_biud 1518489 1518489 0 0.00 0 25082 39 0 0
sp_add_invoice_to_stock 5140 5037 103 2.00 40 230104 26207 0 103
sp_cancel_adding_invoice 5548 5548 0 0.00 5 90116 1225 0 0
sp_cancel_client_order 1816 1816 0 0.00 7 12429 378 0 0
sp_cancel_customer_reserve 538341 538341 0 0.00 1 21237 96 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 4 7596 123 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 4 2146 99 0 0
sp_cancel_supplier_invoice 6084 6077 7 0.11 3 14535 881 0 7
sp_cancel_supplier_order 404 404 0 0.00 10 1414553 161448 0 0
sp_cancel_write_off 10556 10556 0 0.00 2 15751 168 0 0
sp_client_order 20135 20135 0 0.00 6 12789 462 0 0
sp_customer_reserve 464683 464683 0 0.00 1 59361 252 0 0
sp_fill_shopping_cart 31132 31132 0 0.00 1 9808 76 0 0
sp_fill_shopping_cart_clo_res 464659 464659 0 0.00 0 15051 18 0 0
sp_get_clo_for_invoice 4996 4996 0 0.00 0 26471 273 0 0
sp_kill_cost_storno 17232 17232 0 0.00 0 9176 71 0 0
sp_kill_qstorno_ret_qs2qd 1093719 1093719 0 0.00 0 21234 41 0 0
sp_kill_qty_storno 577789 577789 0 0.00 0 25081 95 0 0
sp_lock_selected_doc 20861 20861 0 0.00 0 10443 8 0 0
sp_make_cost_storno 27992 27992 0 0.00 0 22667 75 0 0
sp_multiply_rows_for_pdistr 27992 27992 0 0.00 0 11304 36 0 0
sp_multiply_rows_for_qdistr 20135 20135 0 0.00 0 9679 177 0 0
sp_pay_from_customer 7117 7117 0 0.00 5 11361 160 0 0
sp_pay_to_supplier 5828 5828 0 0.00 6 15249 186 0 0
sp_payment_common 12945 12945 0 0.00 2 15217 42 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 12420 310 0 0
sp_qd_handle_on_invoice_upd_sts 10544 10544 0 0.00 0 25080 501 0 0
sp_qd_handle_on_reserve_upd_sts 10051 10051 0 0.00 0 14211 59 0 0
sp_reserve_write_off 10051 10051 0 0.00 9 22681 259 0 0
sp_supplier_invoice 5556 5556 0 0.00 10 66586 2969 0 0
sp_supplier_order 5442 5442 0 0.00 8 27977 1337 0 0
srv_aggregate_perf_data 114 114 0 0.00 2426 146107 14702 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 891 118675 36235 0 0
srv_find_qd_qs_mism 1041109 1041109 0 0.00 0 13782 10 0 0
srv_make_invnt_saldo 462 462 0 0.00 82 116655 4393 0 0
srv_make_money_saldo 880 880 0 0.00 1 24238 425 0 0
srv_recalc_idx_stat 4 4 0 0.00 193213 509449 360223 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5038 5038 0 0.00 0 24630 920 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 1 6263 492 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 1196 26 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 2078 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 930 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 1592 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1445 30 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 69677 6610 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 12740 36 0 0
t$perf-norm:sp_client_order 20135 20135 0 0.00 0 19246 36 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 890 13 0 0
t$perf-norm:sp_pay_from_customer 7117 7117 0 0.00 0 10282 19 0 0
t$perf-norm:sp_pay_to_supplier 5828 5828 0 0.00 0 6104 19 0 0
t$perf-norm:sp_reserve_write_off 10051 10051 0 0.00 0 11816 23 0 0
t$perf-norm:sp_supplier_invoice 5556 5556 0 0.00 0 2797 28 0 0
t$perf-norm:sp_supplier_order 5442 5442 0 0.00 0 6520 37 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 513 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 206 6 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 880 13 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 725 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 186 81 0 0
v_add_invoice_to_stock 10198 9992 206 2.02 0 4734 25 0 206
v_all_customers 37142 37142 0 0.00 0 94 4 0 0
v_all_suppliers 30642 30642 0 0.00 0 96 4 0 0
v_all_wares 40270 40270 0 0.00 0 100 4 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 477 14 0 0
v_cancel_client_order 3632 3632 0 0.00 0 2168 9 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 1832 12 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1457 10 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 2034 11 0 0
v_cancel_supplier_invoice 788 774 14 1.77 0 792 22 0 14
v_cancel_supplier_order 808 808 0 0.00 0 892 12 0 0
v_cancel_write_off 1908 1908 0 0.00 0 2629 25 0 0
v_max_id_clo_ord 5442 5442 0 0.00 0 468 5 0 0
v_max_id_clo_res 988 988 0 0.00 0 1424 10 0 0
v_max_id_ord_sup 5554 5554 0 0.00 0 3868 8 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 83 7 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 2482 8 0 0
v_min_id_clo_ord 5442 5442 0 0.00 0 1982 8 0 0
v_min_id_clo_res 988 988 0 0.00 0 1415 9 0 0
v_min_id_ord_sup 5554 5554 0 0.00 0 4138 6 0 0
v_min_non_paid_invoice 5828 5828 0 0.00 0 15182 15 0 0
v_min_non_paid_realizn 7117 7117 0 0.00 0 4861 14 0 0
v_our_firm 10118 10118 0 0.00 0 53 4 0 0
v_reserve_write_off 20102 20102 0 0.00 0 3241 10 0 0
x_lock_depdocs_on_canc_invoice 11238 11238 0 0.00 0 13515 127 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 7608 369 0 0
x_make_qstorno_1000_1200 5442 5442 0 0.00 2 27945 1175 0 0
x_make_qstorno_1200_2000 5556 5556 0 0.00 4 60700 2776 0 0
x_make_qstorno_2100_3300 464682 464682 0 0.00 0 59352 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2022 10:38:44 to 08-01-2022 10:38:48.

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 16 8192000000 8806744256 8821399552 10077984 0.122 4 1 2 0 10243312 76656 88672
08:08:16 37431 8192000000 9732678384 10033213440 811511040 9.013 100 91 182 0 841346496 7725520 22109936
08:09:58 23854 8192000000 9781232704 10090377216 852927808 9.429 100 89 178 0 886657872 12582352 21147712
08:11:28 18474 8192000000 9830162096 10155499520 889695280 9.796 100 82 164 0 933281152 24884784 18701088
08:12:49 8100 8192000000 9885694640 10220617728 936772384 10.261 100 80 159 0 995334288 41401296 17160608
08:14:01 5314 8192000000 9952290160 10301710336 987643376 10.759 100 87 172 2 1064905616 58845216 18417024
08:15:09 1456 8192000000 10001167584 10361835520 1020432784 11.076 100 89 172 1 1115234992 77525984 17276224
08:16:13 1590 8192000000 10011703600 10378096640 1036203680 11.228 100 92 174 1 1130888720 77715024 16970016
08:17:17 1101 8192000000 10028038752 10401226752 1064385200 11.498 100 97 188 1 1153875072 70251168 19238704
08:18:19 1091 8192000000 10052299472 10428428288 1068877520 11.541 100 94 180 5 1172440688 84701840 18861328
08:19:21 1265 8192000000 10064276352 10445139968 1072058448 11.572 100 96 184 0 1189079104 97463600 19557056
08:20:23 3090 8192000000 10038928720 10427965440 1081321824 11.660 100 75 147 0 1171658032 75036208 15300000
08:21:28 1100 8192000000 10064585744 10447736832 1082664640 11.673 100 96 186 2 1181363056 79099520 19598896
08:22:30 2484 8192000000 10055525600 10444075008 1088742176 11.731 100 80 152 2 1178682736 74164592 15775968
08:23:33 1203 8192000000 10059298944 10444070912 1092544576 11.767 100 95 174 1 1179416512 68919712 17952224
08:24:35 1396 8192000000 10071414912 10458910720 1099408848 11.832 100 95 177 0 1190463648 72713568 18341232
08:25:37 1434 8192000000 10072812336 10457714688 1099348624 11.831 100 97 187 3 1193448976 74661584 19438768
08:26:39 1774 8192000000 10034611808 10424954880 1087313536 11.717 99 77 145 2 1163433904 60804816 15315552
08:27:42 3028 8192000000 10039169392 10437939200 1099414304 11.832 100 75 144 0 1164604160 50777280 14412576
08:28:45 3079 8192000000 10056019408 10443710464 1093893168 11.780 100 63 119 1 1180840000 75043936 11902896
08:29:50 1316 8192000000 10019394464 10403487744 1079513440 11.643 100 80 153 2 1138164240 43299952 15350848
08:30:52 1146 8192000000 10067520288 10449338368 1083527616 11.681 100 96 179 3 1189661696 87184032 18950048
08:31:54 1434 8192000000 10012382272 10401570816 1054470560 11.404 100 73 145 0 1141673504 72472944 14730000
08:32:56 1239 8192000000 10020499120 10403131392 1054059552 11.400 100 68 136 2 1143084064 76115504 12909008
08:33:58 1275 8192000000 10006822560 10389196800 1051502704 11.375 100 83 163 0 1127442048 58789520 17149824
08:35:05 918 8192000000 10001163088 10375847936 1042886656 11.292 100 90 166 0 1125184576 64967024 17330896
08:36:07 1075 8192000000 9988000048 10364043264 1045151488 11.314 100 90 169 3 1112313776 48812832 18349456
08:37:08 14325 8192000000 10005925520 10391703552 1055603184 11.414 100 56 105 1 1126889056 61235584 10050288
08:38:28 2340 8192000000 10007394720 10386874368 1064523344 11.500 100 81 139 2 1127397264 49535664 13338256
08:39:47 6552 8192000000 10027768464 10410131456 1071574064 11.567 100 56 104 0 1143974000 62234576 10165360
08:40:57 3172 8192000000 10076777024 10454794240 1088968576 11.733 100 84 160 0 1190672688 85888000 15816112
08:42:05 2558 8192000000 10100138704 10488049664 1095718944 11.797 100 77 143 2 1214499408 104291088 14489376
08:43:14 1254 8192000000 10116126752 10508734464 1109653968 11.929 100 92 178 0 1234075488 105833312 18588208
08:44:20 1761 8192000000 10119935856 10506936320 1110692688 11.939 99 93 176 2 1239686304 110882224 18111392
08:45:24 1563 8192000000 10178162512 10578173952 1133161264 12.151 100 90 169 0 1288384576 137714048 17509264
08:46:29 2782 8192000000 10170927824 10562363392 1130498144 12.126 100 79 159 0 1289100256 142223680 16378432
08:47:36 4569 8192000000 10215412752 10614116352 1144167104 12.255 100 71 138 0 1330395664 171658720 14569840
08:48:48 1108 8192000000 10207264288 10617286656 1151699936 12.325 99 91 173 0 1319396224 149438512 18257776
08:49:52 6106 8192000000 10246823680 10653646848 1166877136 12.468 100 91 169 0 1352532448 166883104 18772208
08:51:08 1337 8192000000 10288368304 10707521536 1185872400 12.645 100 94 185 2 1398735776 192402016 20461360
08:52:13 1219 8192000000 10321427792 10717499392 1189516448 12.679 100 96 186 0 1427291392 216626176 21148768
08:53:18 898 8192000000 10368108208 10775117824 1197337552 12.752 100 97 194 0 1476119504 256642720 22139232
08:54:21 1523 8192000000 10370021264 10802262016 1205648112 12.829 100 83 165 0 1478155248 253346688 19160448
08:55:26 893 8192000000 10396643760 10801025024 1195147728 12.731 100 98 189 1 1503653600 286802624 21703248
08:56:31 2310 8192000000 10387854048 10807693312 1195987680 12.739 100 60 118 1 1494206464 284045840 14172944
08:57:38 1610 8192000000 10457813072 10865008640 1200180944 12.778 100 96 190 0 1561082304 338183872 22717488
08:58:42 881 8192000000 10475383728 10914979840 1195919904 12.738 100 98 192 0 1581171792 362148352 23103536
08:59:44 936 8192000000 10489926144 10910023680 1190985296 12.693 100 97 189 1 1595543520 381409456 23148768
09:00:48 1256 8192000000 10528135808 10950885376 1191978624 12.702 100 79 155 0 1636535616 425637776 18919216
09:01:55 8684 8192000000 10525094080 10956472320 1164375040 12.444 99 57 110 1 1616853984 438722912 13756032
09:03:21 2972 8192000000 10581450656 11005423616 1188085200 12.666 100 72 138 0 1680333472 475271952 16976320
09:04:26 10373 8192000000 10554779040 11008069632 1185376768 12.640 100 52 102 2 1661197920 462694256 13126896
09:05:38 6286 8192000000 10541592912 11009056768 1190336848 12.686 100 54 108 0 1643298000 438900416 14060736
09:06:55 21485 8192000000 10466753936 10958368768 1180719536 12.597 100 40 77 3 1569883952 379131328 10033088
09:08:46 16641 8192000000 10405569232 10914402304 1162513040 12.427 99 28 56 2 1509975360 339851568 7610752
09:10:17 34355 8192000000 10325391824 10813784064 1146835008 12.280 99 25 49 1 1419613776 266507680 6271088
09:11:55 62521 8192000000 10107046352 10599608320 1114264016 11.973 98 13 26 1 1174741680 58108224 2369440
09:13:59 89565 8192000000 10074139376 10528227328 1123981648 12.065 98 30 58 0 1184722400 54149808 6590944
09:16:29 33996 8192000000 10065703248 10486431744 1088667152 11.730 95 33 59 1 1117437168 23314576 5455440
09:18:05 118673 8192000000 10058326416 10559545344 1086336816 11.708 96 13 26 0 1160210624 71549616 2324192
09:21:34 35425 8192000000 10065206240 10507571200 1109499920 11.928 98 53 100 2 1158460432 38601616 10358896
09:23:14 37598 8192000000 10103752240 10517168128 1129128224 12.113 99 78 154 2 1186645536 40980416 16536896
09:24:53 30885 8192000000 10114472080 10550755328 1147564576 12.287 100 44 84 1 1210569888 52955136 10050176
09:26:26 27140 8192000000 10133559424 10574442496 1121112672 12.038 98 52 93 4 1211508672 81160512 9235488
09:27:57 68527 8192000000 10102593088 10560966656 1116014624 11.989 98 15 30 1 1190236128 70588064 3633440
09:30:09 42248 8192000000 10075010064 10568097792 1130200800 12.123 100 12 24 1 1171962032 39032880 2728352
09:31:51 98476 8192000000 10063991104 10553315328 1131281264 12.133 99 17 32 2 1183222944 48148304 3793376
09:34:34 32914 8192000000 10099263056 10537832448 1125986560 12.084 99 38 75 0 1167928608 34538224 7403824
09:36:08 48285 8192000000 10077210720 10546204672 1123348000 12.059 99 25 50 2 1205988912 77183280 5457632
09:37:59 10592 8192000000 10119076976 10540093440 1126077376 12.084 99 73 135 0 1200305392 59849072 14378944
09:39:12 35554 8192000000 10180373488 10621235200 1122274480 12.048 98 23 46 1 1242890368 115618544 4997344
09:40:52 34289 8192000000 10160840960 10602307584 1134046000 12.159 99 26 52 2 1268311472 128741824 5523648
09:42:33 44381 8192000000 10266026720 10712694784 1156935168 12.375 100 31 62 0 1351010336 187165168 6910000
09:44:19 73235 8192000000 10183927616 10690752512 1127245312 12.095 98 22 44 1 1306790816 174277248 5268256
09:46:32 93279 8192000000 10081367024 10525462528 1120279904 12.030 99 11 20 0 1156763744 34469392 2014448
09:49:11 83610 8192000000 10066567072 10542592000 1111877808 11.950 98 5 10 1 1155606608 43008944 719856
09:51:55 79992 8192000000 10090462688 10600198144 1136315600 12.181 99 21 42 2 1219710864 78680528 4714736
09:54:15 27558 8192000000 10057334688 10485243904 1100587168 11.843 97 36 72 0 1136175584 27570656 8017760
09:55:58 23310 8192000000 10125208832 10542927872 1115926512 11.988 97 67 131 4 1188272832 58680432 13665888
09:57:26 65592 8192000000 10065513024 10534965248 1094788560 11.788 97 16 32 1 1171840112 73786272 3265280
09:59:48 51243 8192000000 10062003792 10494730240 1079810800 11.646 97 18 36 3 1149614096 66005728 3797568
10:01:44 95546 8192000000 10073965392 10519396352 1062404784 11.479 95 20 40 0 1151126944 84616608 4105552
10:04:21 23717 8192000000 10088467552 10518724608 1105022016 11.885 98 41 82 0 1157099808 44119264 7958528
10:05:45 100050 8192000000 10058854080 10512232448 1097488736 11.814 99 42 82 1 1194257136 88183472 8584928
10:08:35 29864 8192000000 10054560768 10497044480 1088024256 11.724 98 28 56 0 1134641504 40560928 6056320
10:10:15 47256 8192000000 10071539424 10552836096 1111565008 11.947 100 14 28 1 1177753360 63103728 3084624
10:12:07 56720 8192000000 10057750080 10509529088 1085049760 11.696 97 20 40 1 1128821968 39189760 4582448
10:14:05 101815 8192000000 10030101968 10511507456 1093187712 11.773 98 17 34 1 1146726848 49549440 3989696
10:16:48 33673 8192000000 10092829824 10538024960 1117594528 12.004 99 33 66 0 1190344224 65941792 6807904
10:18:31 61019 8192000000 10100523824 10559066112 1129457280 12.116 100 25 51 1 1210973472 76224576 5291616
10:20:36 71050 8192000000 10156101696 10617364480 1102272032 11.859 97 26 52 0 1227193648 119141728 5779888
10:22:49 77353 8192000000 10142175776 10603134976 1112339424 11.955 98 14 28 1 1226670192 111485888 2844880
10:25:11 43200 8192000000 10087159072 10550702080 1125565120 12.080 99 28 56 0 1188601984 57473168 5563696
10:26:56 50722 8192000000 10099155184 10550128640 1126270576 12.086 99 40 80 0 1192526592 58559104 7696912
10:28:47 70677 8192000000 10091628144 10550304768 1143498192 12.248 100 43 83 2 1217734048 65734304 8501552
10:31:34 52573 8192000000 10067859904 10538672128 1136194544 12.180 99 10 20 0 1176570976 38445840 1930592
10:33:45 65072 8192000000 10097109104 10552672256 1126032512 12.084 98 13 26 0 1170137888 41476080 2629296
10:35:50 81443 8192000000 10078549888 10553184256 1135612144 12.174 99 12 24 0 1208943296 70638880 2692272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-01-2022 10:38:50 to 08-01-2022 10:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2022 10:39:07 to 08-01-2022 10:39:09.

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          221110
MON$OLDEST_ACTIVE               223692
MON$OLDEST_SNAPSHOT             223692
MON$NEXT_TRANSACTION            223692
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                       2078432
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

08.01.22 10:39:10. 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-01-08 10:39:13 No severe PSQL-related problems occured
08.01.22 10:39:14. End of report.