Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2022 07:50
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 16462856 kB
MemAvailable 31313836 kB
Buffers 0 kB
Cached 15194416 kB
SwapCached 1204 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-02 07:49:26
DTS_END                         2022-01-02 07:49:26.9290
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/44550
TRN_ID                          232199
ATT_ID                          691
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: 8823.24 21523 1058789
customer order: creation 172.54 442 20705
customer order: refuse 14.29 298 1715
order to supplier: creation 45.33 1590 5439
order to supplier: removal 3.40 108611 408
invoice (draft): creation 45.37 3766 5444
invoice (draft): removal 49.55 959 5946
invoice accept: apply 40.38 17621 4846
invoice accept: cancel 44.97 859 5396
customer reserve: creation 3738.38 173 448605
customer reserve: removal 4379.70 66 525564
realization accept: apply 82.51 190 9901
realization accept: cancel 79.04 120 9485
payment from customer: creation 58.28 109 6993
payment from customer: removal 5.76 95 691
payment to supplier: creation 49.28 150 5914
payment to supplier: removal 3.44 112 413
service: total inventory turnovers 4.06 2989 487
service: total monetary turnovers 6.03 595 723
service: aggregate perf. data 0.92 14152 110
service: refresh index statistics 0.03 277555 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-01-2022 07:50:37 to 02-01-2022 07:50:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4584
TEST_TIME 2 3931
TEST_TIME 3 4045
TEST_TIME 4 4077
TEST_TIME 5 5320
TEST_TIME 6 5331
TEST_TIME 7 5770
TEST_TIME 8 5474
TEST_TIME 9 5662
TEST_TIME 10 6031
TEST_TIME 11 6545
TEST_TIME 12 7264
TEST_TIME 13 7736
TEST_TIME 14 7787
TEST_TIME 15 7873
TEST_TIME 16 8489
TEST_TIME 17 8669
TEST_TIME 18 8652
TEST_TIME 19 8777
TEST_TIME 20 8923
TEST_TIME 21 9063
TEST_TIME 22 9305
TEST_TIME 23 9598
TEST_TIME 24 9736
TEST_TIME 25 9990
TEST_TIME 26 11084
TEST_TIME 27 11217
TEST_TIME 28 11260
TEST_TIME 29 11190
TEST_TIME 30 11006
TEST_TIME 31 10757
TEST_TIME 32 10929
TEST_TIME 33 10751
TEST_TIME 34 10575
TEST_TIME 35 10406
TEST_TIME 36 10306
TEST_TIME 37 10202
TEST_TIME 38 10079
TEST_TIME 39 9974
TEST_TIME 40 9939
TEST_TIME 41 9823
TEST_TIME 42 9682
TEST_TIME 43 9559
TEST_TIME 44 9554
TEST_TIME 45 9565
TEST_TIME 46 9485
TEST_TIME 47 9373
TEST_TIME 48 9244
TEST_TIME 49 9148
TEST_TIME 50 9158
TEST_TIME 51 9069
TEST_TIME 52 9030
TEST_TIME 53 9113
TEST_TIME 54 9242
TEST_TIME 55 9195
TEST_TIME 56 9204
TEST_TIME 57 9240
TEST_TIME 58 9209
TEST_TIME 59 9202
TEST_TIME 60 9263
TEST_TIME 61 9282
TEST_TIME 62 9241
TEST_TIME 63 9359
TEST_TIME 64 9350
TEST_TIME 65 9368
TEST_TIME 66 9478
TEST_TIME 67 9363
TEST_TIME 68 9267
TEST_TIME 69 9206
TEST_TIME 70 9176
TEST_TIME 71 9114
TEST_TIME 72 9085
TEST_TIME 73 9081
TEST_TIME 74 9021
TEST_TIME 75 9004
TEST_TIME 76 9030
TEST_TIME 77 9019
TEST_TIME 78 9037
TEST_TIME 79 9018
TEST_TIME 80 9002
TEST_TIME 81 9069
TEST_TIME 82 9085
TEST_TIME 83 9154
TEST_TIME 84 9169
TEST_TIME 85 9159
TEST_TIME 86 9194
TEST_TIME 87 9162
TEST_TIME 88 9094
TEST_TIME 89 9101
TEST_TIME 90 9041
TEST_TIME 91 9029
TEST_TIME 92 8969
TEST_TIME 93 8957
TEST_TIME 94 8926
TEST_TIME 95 8931
TEST_TIME 96 8929
TEST_TIME 97 8900
TEST_TIME 98 8895
TEST_TIME 99 8919
TEST_TIME 100 8906
TEST_TIME 101 8901
TEST_TIME 102 8921
TEST_TIME 103 8964
TEST_TIME 104 9012
TEST_TIME 105 9017
TEST_TIME 106 9033
TEST_TIME 107 8980
TEST_TIME 108 8905
TEST_TIME 109 8866
TEST_TIME 110 8889
TEST_TIME 111 8890
TEST_TIME 112 8862
TEST_TIME 113 8849
TEST_TIME 114 8856
TEST_TIME 115 8882
TEST_TIME 116 8878
TEST_TIME 117 8871
TEST_TIME 118 8900
TEST_TIME 119 8878
TEST_TIME 120 8826

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-01-2022 07:50:41 to 02-01-2022 07:50:44.

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 1474179 1474179 0 0.00 0 19274 7 0 0
doc_list_biud 1474176 1474176 0 0.00 0 29142 30 0 0
sp_add_invoice_to_stock 4982 4846 136 2.72 35 175823 17621 0 136
sp_cancel_adding_invoice 5396 5396 0 0.00 4 65889 859 0 0
sp_cancel_client_order 1715 1715 0 0.00 5 9070 299 0 0
sp_cancel_customer_reserve 525564 525564 0 0.00 1 29144 67 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 14104 95 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 12415 113 0 0
sp_cancel_supplier_invoice 5961 5946 15 0.25 1 20440 959 0 15
sp_cancel_supplier_order 408 408 0 0.00 7 927220 108611 0 0
sp_cancel_write_off 9485 9485 0 0.00 1 26876 121 0 0
sp_client_order 20705 20705 0 0.00 4 42033 442 0 0
sp_customer_reserve 448605 448605 0 0.00 0 51699 174 0 0
sp_fill_shopping_cart 31566 31566 0 0.00 1 19825 50 0 0
sp_fill_shopping_cart_clo_res 448599 448599 0 0.00 0 20566 17 0 0
sp_get_clo_for_invoice 4840 4840 0 0.00 0 38968 367 0 0
sp_kill_cost_storno 15985 15985 0 0.00 0 10015 63 0 0
sp_kill_qstorno_ret_qs2qd 1066967 1066967 0 0.00 0 29137 36 0 0
sp_kill_qty_storno 563255 563255 0 0.00 0 29142 77 0 0
sp_lock_selected_doc 20472 20472 0 0.00 0 7621 4 0 0
sp_make_cost_storno 27648 27648 0 0.00 0 12536 57 0 0
sp_multiply_rows_for_pdistr 27648 27648 0 0.00 0 11950 36 0 0
sp_multiply_rows_for_qdistr 20705 20705 0 0.00 0 15009 218 0 0
sp_pay_from_customer 6993 6993 0 0.00 4 19152 109 0 0
sp_pay_to_supplier 5914 5914 0 0.00 5 15994 150 0 0
sp_payment_common 12906 12906 0 0.00 1 8719 21 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 9058 275 0 0
sp_qd_handle_on_invoice_upd_sts 10236 10236 0 0.00 0 19815 337 0 0
sp_qd_handle_on_reserve_upd_sts 9901 9901 0 0.00 0 13445 51 0 0
sp_reserve_write_off 9901 9901 0 0.00 6 15703 191 0 0
sp_supplier_invoice 5444 5444 0 0.00 6 97519 3766 0 0
sp_supplier_order 5439 5439 0 0.00 5 109505 1591 0 0
srv_aggregate_perf_data 110 110 0 0.00 2690 137261 14152 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2783 167287 50785 0 0
srv_find_qd_qs_mism 1011719 1011719 0 0.00 0 19274 6 0 0
srv_make_invnt_saldo 487 487 0 0.00 55 95386 2990 0 0
srv_make_money_saldo 723 723 0 0.00 1 25272 595 0 0
srv_recalc_idx_stat 4 4 0 0.00 128667 453940 277556 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4846 4846 0 0.00 0 32309 183 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 2572 63 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 8815 24 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1595 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 750 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1203 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1268 17 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 26801 1764 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 1596 8 0 0
t$perf-norm:sp_client_order 20706 20706 0 0.00 0 31533 19 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 5857 13 0 0
t$perf-norm:sp_pay_from_customer 6993 6993 0 0.00 0 4736 7 0 0
t$perf-norm:sp_pay_to_supplier 5914 5914 0 0.00 0 20465 17 0 0
t$perf-norm:sp_reserve_write_off 9901 9901 0 0.00 0 9416 10 0 0
t$perf-norm:sp_supplier_invoice 5444 5444 0 0.00 0 9854 22 0 0
t$perf-norm:sp_supplier_order 5439 5439 0 0.00 0 4166 14 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 574 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 50 2 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 755 8 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 3741 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 17 6 0 0
v_add_invoice_to_stock 9950 9678 272 2.73 0 10908 18 0 272
v_all_customers 37584 37584 0 0.00 0 28 1 0 0
v_all_suppliers 30712 30712 0 0.00 0 23 1 0 0
v_all_wares 41410 41410 0 0.00 0 119 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 3558 12 0 0
v_cancel_client_order 3430 3430 0 0.00 0 3554 6 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 1379 8 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 2113 7 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 12393 33 0 0
v_cancel_supplier_invoice 840 810 30 3.57 0 3418 23 0 30
v_cancel_supplier_order 816 816 0 0.00 0 5012 19 0 0
v_cancel_write_off 1954 1954 0 0.00 0 8757 21 0 0
v_max_id_clo_ord 5431 5431 0 0.00 0 1869 2 0 0
v_max_id_clo_res 971 971 0 0.00 0 1681 10 0 0
v_max_id_ord_sup 5430 5430 0 0.00 0 2914 5 0 0
v_max_non_paid_invoice 1418 1418 0 0.00 0 518 3 0 0
v_max_non_paid_realizn 3670 3670 0 0.00 0 2547 3 0 0
v_min_id_clo_ord 5431 5431 0 0.00 0 3857 8 0 0
v_min_id_clo_res 971 971 0 0.00 0 4768 10 0 0
v_min_id_ord_sup 5430 5430 0 0.00 0 8791 8 0 0
v_min_non_paid_invoice 5913 5913 0 0.00 0 8713 13 0 0
v_min_non_paid_realizn 6993 6993 0 0.00 0 3276 13 0 0
v_our_firm 10472 10472 0 0.00 0 20 1 0 0
v_reserve_write_off 19801 19801 0 0.00 0 4849 7 0 0
x_lock_depdocs_on_canc_invoice 10937 10937 0 0.00 0 15000 105 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 12778 349 0 0
x_make_qstorno_1000_1200 5439 5439 0 0.00 2 109494 1508 0 0
x_make_qstorno_1200_2000 5444 5444 0 0.00 3 97513 3570 0 0
x_make_qstorno_2100_3300 448605 448605 0 0.00 0 51670 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2022 07:50:45 to 02-01-2022 07:50:49.

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
05:19:30 5 8192000000 8799338256 8810844160 5349552 0.065 2 1 2 0 5447168 61840 35776
05:20:31 31561 8192000000 9743898256 10047799296 819611040 9.095 100 87 174 0 848399792 7747360 21041392
05:22:08 15998 8192000000 9782300992 10093981696 856178528 9.462 100 90 180 0 891629680 14442464 21008688
05:23:28 11105 8192000000 9813196976 10134429696 877736256 9.677 100 82 164 0 920361008 24014672 18610080
05:24:50 8792 8192000000 9875058384 10210840576 927184560 10.167 100 88 176 0 991978704 45373712 19420432
05:26:08 9480 8192000000 9932270592 10282188800 970261728 10.589 100 80 160 0 1046479584 59396656 16821200
05:27:19 4430 8192000000 9987602112 10343579648 1012194496 10.997 100 88 174 0 1101414288 71892080 17327712
05:28:26 2810 8192000000 10037789104 10399477760 1035527152 11.222 100 95 190 1 1145439872 91156896 18755824
05:29:30 1557 8192000000 10030311776 10400845824 1056196208 11.420 100 92 178 2 1141804832 66740320 18868304
05:30:32 28442 8192000000 10061754512 10440417280 1071182080 11.563 100 73 148 0 1161057648 74264064 15611504
05:32:01 7763 8192000000 9995246704 10392338432 1080913088 11.656 100 37 74 1 1111945712 23426240 7606384
05:33:14 8590 8192000000 10006542496 10406780928 1090976784 11.752 100 29 58 2 1115320032 17961888 6381360
05:34:27 12814 8192000000 9996684720 10404143104 1093133584 11.772 100 43 85 0 1121749408 17824592 10791232
05:35:40 31411 8192000000 10023877872 10418487296 1100566128 11.843 100 72 143 3 1137843184 22849568 14427488
05:37:12 5800 8192000000 10045460464 10455494656 1102323376 11.860 100 45 90 1 1153311728 41143264 9845088
05:38:19 7442 8192000000 10017061184 10420023296 1104839744 11.884 100 43 86 0 1131220928 16820592 9560592
05:39:27 4490 8192000000 10030247440 10433744896 1102379312 11.860 100 38 76 1 1135086240 24203360 8503568
05:40:32 7774 8192000000 9989211936 10388201472 1056215376 11.420 98 28 55 1 1090202112 28272240 5714496
05:41:40 51902 8192000000 9942930128 10342694912 983754960 10.721 94 29 58 1 1012047552 22261328 6031264
05:43:34 5896 8192000000 9967768288 10352783360 1026188224 11.132 99 64 129 3 1065750016 25714080 13847712
05:44:42 9071 8192000000 9935342416 10325450752 1021377904 11.085 99 38 78 0 1047730320 17777520 8574896
05:45:55 6650 8192000000 9963156336 10342879232 1047181808 11.334 100 54 105 0 1071562512 12507056 11873648
05:47:02 47750 8192000000 9975145392 10378698752 1064830496 11.503 100 19 38 0 1097651152 28865760 3954896
05:48:50 5075 8192000000 9993703840 10380107776 1072688112 11.578 100 57 116 1 1100301520 15506080 12107328
05:50:00 16050 8192000000 10008673504 10408075264 1099626656 11.834 100 39 77 2 1127081408 18962128 8492624
05:51:19 32743 8192000000 10090744864 10494033920 1123808288 12.063 100 39 78 0 1183467808 51645472 8014048
05:53:22 28981 8192000000 10131969392 10547408896 1137525840 12.192 100 39 77 0 1252250304 105444288 9280176
05:55:08 48694 8192000000 10148744736 10571558912 1148625904 12.297 100 39 78 2 1274760368 117004128 9130336
05:57:14 15814 8192000000 10192665040 10603651072 1169447792 12.492 100 54 107 2 1287951328 105686400 12817136
05:58:33 8829 8192000000 10253975680 10662289408 1178300288 12.574 100 58 112 2 1361123328 169906416 12916624
05:59:45 9803 8192000000 10314142400 10734157824 1184684912 12.634 100 65 130 4 1410830816 212010048 14135856
06:01:04 3811 8192000000 10329754224 10744901632 1193445872 12.715 100 66 131 0 1439584304 231670896 14467536
06:02:12 2782 8192000000 10329460400 10745712640 1194401312 12.724 100 71 141 0 1433190816 222504288 16285216
06:03:21 5502 8192000000 10328032544 10747215872 1197676368 12.755 100 65 127 2 1428346688 216585968 14084352
06:04:31 9041 8192000000 10363375040 10781822976 1190459888 12.688 100 56 112 0 1461248800 257096192 13692720
06:05:43 12042 8192000000 10345995472 10802352128 1185622096 12.643 100 37 72 1 1450592352 255950384 9019872
06:07:01 12425 8192000000 10339562144 10769285120 1146557904 12.277 98 35 69 0 1427063600 271689216 8816480
06:08:23 6454 8192000000 10335575008 10749005824 1123019968 12.056 97 40 80 0 1440325360 307126336 10179056
06:09:37 4001 8192000000 10436696320 10847154176 1154573056 12.352 99 68 136 1 1528856144 358232128 16050960
06:10:48 7929 8192000000 10437574528 10856509440 1166683168 12.466 100 48 96 2 1538155952 359430416 12042368
06:11:59 11186 8192000000 10441824032 10872561664 1138330928 12.200 100 35 70 1 1536710992 388983808 9396256
06:13:28 19573 8192000000 10386690096 10817572864 1098164688 11.820 96 30 60 2 1453261984 347169936 7927360
06:15:08 29161 8192000000 10221255056 10693484544 1118687024 12.015 99 23 46 0 1395314368 270568000 6059344
06:16:44 35417 8192000000 10158362880 10598490112 1134164544 12.161 100 27 54 0 1257171216 116826672 6180000
06:18:21 24406 8192000000 10116016000 10552844288 1113004976 11.961 98 25 50 1 1205166160 86838912 5322272
06:19:53 116606 8192000000 10092207664 10584023040 1088728128 11.731 96 16 33 0 1182959568 90666960 3564480
06:22:50 41780 8192000000 10058052800 10493587456 1096073952 11.800 97 14 29 1 1159817536 60947728 2795856
06:24:32 62669 8192000000 10055157760 10504536064 1104057856 11.876 98 18 35 0 1153319424 45889744 3371824
06:26:35 116109 8192000000 10103581424 10564898816 1126106752 12.085 99 26 52 1 1181237440 49188448 5942240
06:30:00 75877 8192000000 10070035168 10526195712 1139785136 12.214 100 18 36 0 1187579568 44064624 3729808
06:32:52 59106 8192000000 10079894352 10549792768 1145340112 12.266 100 28 53 0 1187067344 36212128 5515104
06:34:59 80173 8192000000 10072670176 10538139648 1125642512 12.080 98 15 30 1 1164229776 35240256 3347008
06:37:20 61270 8192000000 10082143056 10537439232 1142276544 12.237 99 18 36 1 1204060736 57756960 4027232
06:39:26 26186 8192000000 10080710704 10559590400 1150682640 12.316 100 25 50 2 1192529344 36564272 5282432
06:40:52 106092 8192000000 10107894208 10654056448 1133717024 12.156 98 10 20 0 1223095520 86723168 2655328
06:43:43 78389 8192000000 10110632944 10581491712 1142836032 12.242 99 26 52 0 1180302384 31966880 5499472
06:46:02 80305 8192000000 10105912224 10566303744 1165728096 12.457 100 17 34 0 1209273488 39904992 3640400
06:48:24 66021 8192000000 10108905168 10569875456 1152865920 12.336 99 21 42 0 1210444448 53427408 4151120
06:50:32 92472 8192000000 10111370192 10567733248 1086910352 11.713 93 12 24 1 1152811808 62751408 3150048
06:53:06 55623 8192000000 10094182064 10556882944 1081122448 11.658 95 19 38 0 1133056320 47940688 3993184
06:55:04 65229 8192000000 10062825296 10532126720 1092598912 11.767 96 9 18 0 1163603424 69127792 1876720
06:57:09 82140 8192000000 10059866688 10482864128 1123866960 12.064 99 25 50 0 1156302752 26973296 5462496
06:59:32 5849 8192000000 10100411600 10542542848 1131881888 12.139 99 36 72 0 1186469872 47576128 7011856
07:00:44 65439 8192000000 10087604368 10550853632 1113593328 11.966 97 15 30 0 1187765344 70612688 3559328
07:02:49 90109 8192000000 10093108768 10547847168 1126326144 12.087 98 15 30 0 1185256448 55776720 3153584
07:05:23 66057 8192000000 10039506672 10503323648 1078366944 11.632 96 7 14 0 1119497920 39952512 1178464
07:07:31 27635 8192000000 10038533552 10458066944 1098574832 11.824 98 31 60 2 1122263344 16404208 7284304
07:08:59 10915 8192000000 10067064224 10487742464 1100178400 11.839 99 37 74 0 1149592272 42193952 7219920
07:10:13 42687 8192000000 10048703744 10512076800 1096700112 11.806 99 32 64 0 1198417344 95080848 6636384
07:12:03 104528 8192000000 10085107008 10572640256 1085393808 11.699 97 21 42 1 1170237280 80238512 4604960
07:14:56 79143 8192000000 10050255360 10520903680 1101179024 11.849 99 23 46 0 1156619072 51048496 4391552
07:17:16 152372 8192000000 10022344512 10458443776 1082741952 11.674 97 26 52 1 1156489232 67427680 6319600
07:20:49 24182 8192000000 10032211360 10488147968 1118344784 12.011 100 8 16 0 1143003824 23392080 1266960
07:22:15 56667 8192000000 10065037232 10530824192 1127837952 12.101 100 5 10 0 1162569744 34092960 638832
07:24:12 65637 8192000000 10068174240 10517614592 1106593760 11.900 97 11 22 0 1141489776 32682576 2213440
07:26:17 29158 8192000000 10091518784 10516688896 1143495520 12.248 100 32 62 2 1176784336 26915904 6372912
07:27:48 27370 8192000000 10127351536 10562727936 1143290416 12.246 99 39 78 0 1199002192 48039120 7672656
07:29:20 52019 8192000000 10116586928 10561409024 1131127440 12.132 98 23 46 0 1185935184 50013056 4794688
07:31:21 45019 8192000000 10104138832 10567168000 1124306192 12.068 98 21 42 0 1198688544 69714144 4668208
07:33:07 123256 8192000000 10086093728 10555797504 1112210992 11.953 97 11 22 0 1175869664 61724320 1934352
07:36:10 167287 8192000000 10127753072 10569699328 1106144640 11.896 96 32 64 0 1178903536 65688304 7070592
07:40:03 43662 8192000000 10073218576 10617561088 1132289552 12.143 100 5 10 0 1176786224 43507344 989328
07:41:52 68344 8192000000 10076813792 10537156608 1112966320 11.960 98 11 21 0 1148240240 32961504 2312416
07:44:07 57124 8192000000 10038577776 10478505984 1062760208 11.483 94 16 32 0 1122202800 55967424 3475168
07:46:07 63227 8192000000 10098793872 10555879424 1110068448 11.933 98 19 38 0 1177487280 63435744 3983088
07:48:24 70237 8192000000 9540922528 9933709312 632100960 7.163 57 18 35 0 689376160 53676608 3598592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-01-2022 07:50:51 to 02-01-2022 07:51:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2022 07:51:07 to 02-01-2022 07:51: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          229727
MON$OLDEST_ACTIVE               232943
MON$OLDEST_SNAPSHOT             232943
MON$NEXT_TRANSACTION            232943
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                       2040224
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

02.01.22 07:51: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-02 07:51:13 No severe PSQL-related problems occured
02.01.22 07:51:14. End of report.