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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16378136 kB
MemAvailable 31375288 kB
Buffers 20 kB
Cached 15224220 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-18 04:37:10
DTS_END                         2021-12-18 04:37:10.7920
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/33680
TRN_ID                          235483
ATT_ID                          690
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: 9139.03 21371 1096683
customer order: creation 173.79 412 20855
customer order: refuse 15.08 339 1809
order to supplier: creation 45.78 1425 5494
order to supplier: removal 3.14 124703 377
invoice (draft): creation 46.43 3424 5572
invoice (draft): removal 51.62 997 6194
invoice accept: apply 41.61 17821 4993
invoice accept: cancel 46.83 907 5619
customer reserve: creation 3813.49 176 457619
customer reserve: removal 4597.39 68 551687
realization accept: apply 84.83 162 10179
realization accept: cancel 90.86 119 10903
payment from customer: creation 59.13 102 7096
payment from customer: removal 5.83 68 699
payment to supplier: creation 49.35 127 5922
payment to supplier: removal 3.23 83 388
service: total inventory turnovers 3.60 3641 432
service: total monetary turnovers 6.20 647 744
service: aggregate perf. data 0.81 16193 97
service: refresh index statistics 0.03 256003 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2021 04:38:01 to 18-12-2021 04:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5435
TEST_TIME 2 4667
TEST_TIME 3 5339
TEST_TIME 4 5515
TEST_TIME 5 5773
TEST_TIME 6 5817
TEST_TIME 7 5944
TEST_TIME 8 6289
TEST_TIME 9 6058
TEST_TIME 10 6196
TEST_TIME 11 6372
TEST_TIME 12 6712
TEST_TIME 13 6662
TEST_TIME 14 6651
TEST_TIME 15 7187
TEST_TIME 16 7222
TEST_TIME 17 7547
TEST_TIME 18 7782
TEST_TIME 19 7760
TEST_TIME 20 7741
TEST_TIME 21 8075
TEST_TIME 22 8421
TEST_TIME 23 8567
TEST_TIME 24 9066
TEST_TIME 25 9801
TEST_TIME 26 10264
TEST_TIME 27 11035
TEST_TIME 28 11170
TEST_TIME 29 11174
TEST_TIME 30 10900
TEST_TIME 31 10692
TEST_TIME 32 10580
TEST_TIME 33 10275
TEST_TIME 34 10226
TEST_TIME 35 10243
TEST_TIME 36 10144
TEST_TIME 37 10055
TEST_TIME 38 10072
TEST_TIME 39 10162
TEST_TIME 40 10139
TEST_TIME 41 10082
TEST_TIME 42 9941
TEST_TIME 43 9937
TEST_TIME 44 9789
TEST_TIME 45 9863
TEST_TIME 46 9716
TEST_TIME 47 9623
TEST_TIME 48 9642
TEST_TIME 49 9625
TEST_TIME 50 9598
TEST_TIME 51 9539
TEST_TIME 52 9440
TEST_TIME 53 9504
TEST_TIME 54 9512
TEST_TIME 55 9507
TEST_TIME 56 9559
TEST_TIME 57 9510
TEST_TIME 58 9499
TEST_TIME 59 9564
TEST_TIME 60 9674
TEST_TIME 61 9635
TEST_TIME 62 9629
TEST_TIME 63 9557
TEST_TIME 64 9463
TEST_TIME 65 9416
TEST_TIME 66 9344
TEST_TIME 67 9286
TEST_TIME 68 9223
TEST_TIME 69 9212
TEST_TIME 70 9161
TEST_TIME 71 9125
TEST_TIME 72 9157
TEST_TIME 73 9148
TEST_TIME 74 9154
TEST_TIME 75 9138
TEST_TIME 76 9108
TEST_TIME 77 9167
TEST_TIME 78 9163
TEST_TIME 79 9233
TEST_TIME 80 9263
TEST_TIME 81 9285
TEST_TIME 82 9218
TEST_TIME 83 9165
TEST_TIME 84 9111
TEST_TIME 85 9113
TEST_TIME 86 9047
TEST_TIME 87 9042
TEST_TIME 88 9004
TEST_TIME 89 8989
TEST_TIME 90 8978
TEST_TIME 91 9015
TEST_TIME 92 9020
TEST_TIME 93 9059
TEST_TIME 94 9056
TEST_TIME 95 9109
TEST_TIME 96 9168
TEST_TIME 97 9190
TEST_TIME 98 9224
TEST_TIME 99 9212
TEST_TIME 100 9255
TEST_TIME 101 9266
TEST_TIME 102 9286
TEST_TIME 103 9219
TEST_TIME 104 9170
TEST_TIME 105 9238
TEST_TIME 106 9186
TEST_TIME 107 9116
TEST_TIME 108 9111
TEST_TIME 109 9079
TEST_TIME 110 9103
TEST_TIME 111 9057
TEST_TIME 112 9085
TEST_TIME 113 9052
TEST_TIME 114 9034
TEST_TIME 115 9028
TEST_TIME 116 9048
TEST_TIME 117 9137
TEST_TIME 118 9143
TEST_TIME 119 9157
TEST_TIME 120 9144

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2021 04:38:05 to 18-12-2021 04:38:08.

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 1520671 1520671 0 0.00 0 18462 7 0 0
doc_list_biud 1520671 1520671 0 0.00 0 59818 31 0 0
sp_add_invoice_to_stock 5113 4993 120 2.34 42 145978 17822 0 120
sp_cancel_adding_invoice 5619 5619 0 0.00 6 81836 907 0 0
sp_cancel_client_order 1809 1809 0 0.00 5 16610 340 0 0
sp_cancel_customer_reserve 551687 551687 0 0.00 1 59821 68 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 4245 68 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 4253 84 0 0
sp_cancel_supplier_invoice 6199 6194 5 0.08 2 47159 998 0 5
sp_cancel_supplier_order 377 377 0 0.00 7 1074771 124704 0 0
sp_cancel_write_off 10903 10903 0 0.00 1 12710 120 0 0
sp_client_order 20855 20855 0 0.00 4 21690 413 0 0
sp_customer_reserve 457619 457619 0 0.00 0 77713 177 0 0
sp_fill_shopping_cart 31909 31909 0 0.00 1 14895 45 0 0
sp_fill_shopping_cart_clo_res 457603 457603 0 0.00 0 16648 16 0 0
sp_get_clo_for_invoice 4977 4977 0 0.00 0 25459 346 0 0
sp_kill_cost_storno 17609 17609 0 0.00 0 9607 57 0 0
sp_kill_qstorno_ret_qs2qd 1120848 1120848 0 0.00 0 59810 37 0 0
sp_kill_qty_storno 591745 591745 0 0.00 0 59818 79 0 0
sp_lock_selected_doc 20948 20948 0 0.00 0 8123 6 0 0
sp_make_cost_storno 28174 28174 0 0.00 0 13770 50 0 0
sp_multiply_rows_for_pdistr 28174 28174 0 0.00 0 9911 30 0 0
sp_multiply_rows_for_qdistr 20855 20855 0 0.00 0 13105 216 0 0
sp_pay_from_customer 7096 7096 0 0.00 4 15906 102 0 0
sp_pay_to_supplier 5922 5922 0 0.00 5 16548 127 0 0
sp_payment_common 13018 13018 0 0.00 1 9046 18 0 0
sp_qd_handle_on_cancel_clo 1809 1809 0 0.00 0 16603 308 0 0
sp_qd_handle_on_invoice_upd_sts 10596 10596 0 0.00 0 28424 328 0 0
sp_qd_handle_on_reserve_upd_sts 10179 10179 0 0.00 0 9672 50 0 0
sp_reserve_write_off 10179 10179 0 0.00 6 14945 163 0 0
sp_supplier_invoice 5572 5572 0 0.00 6 90675 3425 0 0
sp_supplier_order 5494 5494 0 0.00 5 77407 1425 0 0
srv_aggregate_perf_data 97 97 0 0.00 2966 143585 16194 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1078 148009 45305 0 0
srv_find_qd_qs_mism 1049161 1049161 0 0.00 0 18461 6 0 0
srv_make_invnt_saldo 432 432 0 0.00 41 101060 3641 0 0
srv_make_money_saldo 744 744 0 0.00 1 15723 647 0 0
srv_recalc_idx_stat 4 4 0 0.00 206065 348674 256004 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4993 4993 0 0.00 0 9313 141 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 2787 103 0 0
t$perf-norm:sp_cancel_client_order 1809 1809 0 0.00 0 5284 14 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 6539 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 921 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 674 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 906 14 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 17426 1498 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 1654 6 0 0
t$perf-norm:sp_client_order 20855 20855 0 0.00 0 24264 17 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 1549 5 0 0
t$perf-norm:sp_pay_from_customer 7096 7096 0 0.00 0 5714 6 0 0
t$perf-norm:sp_pay_to_supplier 5922 5922 0 0.00 0 12907 15 0 0
t$perf-norm:sp_reserve_write_off 10179 10179 0 0.00 0 4955 9 0 0
t$perf-norm:sp_supplier_invoice 5572 5572 0 0.00 0 7028 18 0 0
t$perf-norm:sp_supplier_order 5494 5494 0 0.00 0 5268 15 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 498 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1956 32 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 975 10 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 2993 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10194 9954 240 2.35 0 4289 15 0 240
v_all_customers 37592 37592 0 0.00 0 25 1 0 0
v_all_suppliers 30496 30496 0 0.00 0 20 1 0 0
v_all_wares 41710 41710 0 0.00 0 98 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 648 6 0 0
v_cancel_client_order 3618 3618 0 0.00 0 1541 6 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 825 5 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1779 7 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 1516 5 0 0
v_cancel_supplier_invoice 806 796 10 1.24 0 2012 17 0 10
v_cancel_supplier_order 754 754 0 0.00 0 3041 8 0 0
v_cancel_write_off 1992 1992 0 0.00 0 5364 15 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 4656 3 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 4242 17 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 3340 4 0 0
v_max_non_paid_invoice 1728 1728 0 0.00 0 753 3 0 0
v_max_non_paid_realizn 3810 3810 0 0.00 0 521 2 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 4177 7 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 9353 32 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 7571 9 0 0
v_min_non_paid_invoice 5922 5922 0 0.00 0 5596 11 0 0
v_min_non_paid_realizn 7096 7096 0 0.00 0 5443 12 0 0
v_our_firm 10690 10690 0 0.00 0 15 1 0 0
v_reserve_write_off 20358 20358 0 0.00 0 2015 4 0 0
x_lock_depdocs_on_canc_invoice 11415 11415 0 0.00 0 12408 112 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 8612 337 0 0
x_make_qstorno_1000_1200 5494 5494 0 0.00 2 77364 1347 0 0
x_make_qstorno_1200_2000 5572 5572 0 0.00 3 90667 3243 0 0
x_make_qstorno_2100_3300 457619 457619 0 0.00 0 77708 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2021 04:38:09 to 18-12-2021 04:38:13.

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
02:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 15792 8192000000 9715529168 10011398144 796177616 8.858 100 87 174 0 824920864 7646320 21096928
02:09:35 35179 8192000000 9762830112 10067169280 832703328 9.226 100 91 182 1 866689280 12224048 21761904
02:11:14 16169 8192000000 9787671152 10100576256 851378640 9.414 100 81 162 1 890904512 20704512 18821360
02:12:35 5137 8192000000 9859053664 10189135872 900085584 9.899 100 77 154 0 971261456 53732432 17443440
02:13:48 8219 8192000000 9896215792 10237456384 948998816 10.381 100 78 155 1 1005530352 40335776 16195760
02:14:58 4803 8192000000 9959432064 10308255744 990482576 10.786 100 83 166 0 1063808432 56670144 16655712
02:16:04 3266 8192000000 9975498208 10333315072 1011685648 10.992 100 81 160 1 1088444880 60556752 16202480
02:17:09 2194 8192000000 10004998304 10374275072 1038679600 11.252 100 81 162 1 1126162400 70656528 16826272
02:18:12 3486 8192000000 10066427792 10442346496 1057397600 11.432 100 78 155 0 1160352432 87554544 15400288
02:19:29 7748 8192000000 9979099840 10373677056 1070859536 11.560 100 34 68 0 1104867456 26479408 7528512
02:20:38 6347 8192000000 9985301840 10380795904 1078690112 11.635 100 33 67 0 1108657872 22532384 7435376
02:21:46 67014 8192000000 9997589632 10397200384 1089889104 11.742 100 43 80 3 1109415072 9940544 9585424
02:23:53 4323 8192000000 10034159072 10430062592 1094483440 11.785 100 66 127 0 1135469968 27748400 13238128
02:25:00 5912 8192000000 10026811216 10421542912 1098985664 11.828 100 36 72 0 1134105504 27147024 7972816
02:26:07 17927 8192000000 10014905056 10415005696 1090231808 11.745 99 30 60 0 1115746080 18205472 7308800
02:27:27 29772 8192000000 9983301328 10386968576 1029468608 11.163 94 16 32 1 1054810992 22163488 3178896
02:29:00 6615 8192000000 9967619008 10357125120 1046512432 11.327 100 41 82 1 1073767712 18043072 9212208
02:30:09 5903 8192000000 9940733696 10318946304 997349024 10.853 96 43 87 3 1015223760 8258000 9616736
02:31:19 66914 8192000000 9935547808 10330796032 974159072 10.627 95 32 64 1 1004724928 24032880 6532976
02:33:26 3816 8192000000 9967500512 10358956032 1038388256 11.249 100 39 78 3 1086686768 40111632 8186880
02:34:32 6374 8192000000 9961054864 10347048960 1051252784 11.373 100 30 59 1 1083483648 25624384 6606480
02:35:40 6941 8192000000 9987089632 10377949184 1060754672 11.464 100 39 78 1 1094715184 26171968 7788544
02:36:48 88156 8192000000 9999788416 10403184640 1082166048 11.668 100 30 57 0 1106658496 18204880 6287568
02:39:21 17814 8192000000 10040426832 10441510912 1104894032 11.884 100 28 55 3 1152219280 41351440 5973808
02:40:41 3317 8192000000 10081906368 10478841856 1117939264 12.008 100 60 115 1 1183834192 54078752 11816176
02:41:49 5698 8192000000 10108732576 10514771968 1128931168 12.111 100 60 119 1 1214086208 72847728 12307312
02:42:58 17521 8192000000 10110100512 10526679040 1134508096 12.164 100 53 106 3 1227331808 80680880 12142832
02:44:25 9633 8192000000 10159404128 10573029376 1151066832 12.320 100 54 108 2 1262541888 99439200 12035856
02:45:40 5963 8192000000 10184369024 10602172416 1162373168 12.425 100 61 119 0 1291229728 116139904 12716656
02:46:51 10076 8192000000 10226677056 10639712256 1173802176 12.532 100 51 100 3 1329170064 144006768 11361120
02:48:09 2509 8192000000 10275434160 10685009920 1180215872 12.592 100 85 169 2 1378985088 180381152 18388064
02:49:19 1094 8192000000 10290146480 10696454144 1174107536 12.535 100 82 163 1 1399674032 207495296 18071200
02:50:24 1824 8192000000 10313471744 10709860352 1158502816 12.389 99 84 163 0 1405366032 227144912 19718304
02:51:31 8862 8192000000 10334137264 10738704384 1163329024 12.434 100 72 144 0 1432197472 251624336 17244112
02:52:44 3272 8192000000 10337793808 10758008832 1167430752 12.473 100 73 146 0 1446650272 260755936 18463584
02:53:53 1076 8192000000 10400214848 10817392640 1178527744 12.576 100 83 166 1 1493045216 294943392 19574080
02:55:00 2741 8192000000 10406200912 10825220096 1182188768 12.611 100 66 133 0 1502144464 303268048 16687648
02:56:09 10106 8192000000 10430118848 10845999104 1176557776 12.558 99 51 102 0 1518071872 328402464 13111632
02:57:24 9258 8192000000 10492794048 10916270080 1182174400 12.610 100 49 98 0 1582349600 387379888 12795312
02:58:44 3680 8192000000 10476231488 10911838208 1168574080 12.484 100 64 129 0 1570547696 385638160 16335456
02:59:55 10372 8192000000 10424510336 10848149504 1124629280 12.071 99 42 84 1 1509509136 373895408 10984448
03:01:49 27575 8192000000 10399464000 10856337408 1119046096 12.018 98 29 58 1 1502152480 375277152 7829232
03:03:21 16260 8192000000 10307734400 10762895360 1121434512 12.041 99 24 46 0 1414554080 286892912 6226656
03:04:41 50487 8192000000 10056863072 10510262272 1094836624 11.789 98 6 10 1 1188862096 92992192 1033280
03:06:33 76417 8192000000 10058603232 10484912128 1082710320 11.673 96 19 38 0 1120707280 34713168 3283792
03:08:50 117338 8192000000 10114286352 10553540608 1135190672 12.170 100 27 54 2 1198960656 58127152 5642832
03:11:50 30548 8192000000 10078015872 10498682880 1115501168 11.984 98 18 36 0 1164392896 44876176 4015552
03:13:21 14245 8192000000 10107955408 10548056064 1139594384 12.212 100 38 75 2 1206803776 58677152 8532240
03:14:38 43977 8192000000 10150848880 10609917952 1140467728 12.220 100 51 102 2 1234883008 83395888 11019392
03:16:29 100344 8192000000 10087236128 10586705920 1131684528 12.137 99 12 24 0 1176552640 42255312 2612800
03:19:16 69803 8192000000 10131657616 10583519232 1124659568 12.071 98 13 26 0 1200309056 72701136 2948352
03:21:33 54290 8192000000 10141121392 10604601344 1137650448 12.193 100 26 51 0 1237613088 94526672 5435968
03:24:17 65819 8192000000 10101599952 10592485376 1141773312 12.232 100 21 42 0 1214947024 68927376 4246336
03:26:23 57613 8192000000 10061737328 10536931328 1136128128 12.179 99 12 24 1 1171784592 33439344 2217120
03:28:23 52670 8192000000 10112970784 10538991616 1120327136 12.030 97 18 36 0 1154924512 31112336 3485040
03:30:16 88327 8192000000 10084157424 10560802816 1113012144 11.961 97 16 32 0 1159444064 43425216 3006704
03:32:45 17614 8192000000 10077693984 10512736256 1125445424 12.078 98 34 68 1 1159685104 27715984 6523696
03:34:05 49064 8192000000 10097782688 10545864704 1119774272 12.025 98 18 36 1 1172323408 48920000 3629136
03:36:00 56046 8192000000 10053911088 10514927616 1102729664 11.864 98 20 40 2 1190153696 82767904 4656128
03:38:01 52085 8192000000 10083235408 10528677888 1094639824 11.787 96 29 57 0 1149732528 49466208 5626496
03:39:59 98400 8192000000 10068517520 10532212736 1139057968 12.207 100 20 40 0 1170947632 28023728 3865936
03:43:07 53250 8192000000 10064812352 10529013760 1132772592 12.147 99 8 16 1 1168532896 33876608 1883696
03:45:00 56673 8192000000 10066461424 10528997376 1135871888 12.177 100 27 52 0 1166104944 24726192 5506864
03:47:05 60557 8192000000 10056934880 10527903744 1103671536 11.872 97 10 20 0 1143644640 38352160 1620944
03:49:06 42705 8192000000 10045090128 10497957888 1076059088 11.610 95 24 49 0 1122198928 41028576 5111264
03:50:55 53734 8192000000 10045024368 10499526656 1080576864 11.653 97 21 40 0 1131407792 46583616 4247312
03:52:52 43889 8192000000 10101342512 10536775680 1076117696 11.610 97 32 65 1 1139098160 56837120 6143344
03:54:40 33657 8192000000 10051098960 10498318336 1093871968 11.779 99 25 50 2 1128918768 30164928 4881872
03:56:14 91768 8192000000 10081716528 10541195264 1089550176 11.738 98 22 44 1 1166638432 72505184 4583072
03:59:14 96624 8192000000 10047834704 10495705088 1118503296 12.013 100 28 56 0 1148791392 24923200 5364896
04:01:55 35498 8192000000 10032710064 10468786176 1090316640 11.746 97 26 52 0 1130354624 35256864 4781120
04:03:37 60951 8192000000 10052475040 10520383488 1112985840 11.961 99 7 14 0 1153177536 38731488 1460208
04:05:39 43098 8192000000 10057231888 10500964352 1106161936 11.896 98 22 44 2 1141458976 30604720 4692320
04:07:23 42521 8192000000 10106122048 10538106880 1113619104 11.967 98 25 50 0 1177137680 58213008 5305568
04:09:32 72376 8192000000 10104272080 10588434432 1130274928 12.124 98 19 38 2 1201709392 67007200 4427264
04:11:47 65315 8192000000 10099365664 10541961216 1112811056 11.959 97 12 24 0 1188840400 73570144 2459200
04:13:59 107188 8192000000 10089173968 10559213568 1085509248 11.700 95 21 42 1 1156356656 66096592 4750816
04:16:46 30649 8192000000 10093432528 10529005568 1091448976 11.756 96 35 70 0 1153179376 54445360 7285040
04:18:18 61188 8192000000 10078934192 10592829440 1130815920 12.129 100 8 16 0 1200133776 67664512 1653344
04:20:20 148008 8192000000 10101403712 10627596288 1124440208 12.069 99 29 58 1 1260097024 129581088 6075728
04:24:02 45616 8192000000 10062691312 10512527360 1109245984 11.925 98 12 24 3 1161331312 49822240 2263088
04:25:53 88912 8192000000 10096690928 10572509184 1127783536 12.100 99 13 26 0 1186357488 56028240 2545712
04:28:38 71869 8192000000 10049586768 10541797376 1098177424 11.820 96 16 32 1 1161655856 59562048 3916384
04:30:54 42654 8192000000 10122080320 10571104256 1152307760 12.331 100 29 58 0 1235353696 76731488 6314448
04:32:37 53032 8192000000 10176992272 10651865088 1124913104 12.073 97 24 48 1 1236897024 106554384 5429536
04:34:34 48413 8192000000 10090073456 10556121088 1117377136 12.002 98 24 48 0 1209934816 87550624 5007056
04:36:39 42721 8192000000 9059389216 9320349696 210131680 2.500 19 7 12 1 236726672 25305088 1289904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2021 04:38:15 to 18-12-2021 04:38:30.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2021 04:38:31 to 18-12-2021 04:38:33.

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          234926
MON$OLDEST_ACTIVE               235896
MON$OLDEST_SNAPSHOT             235896
MON$NEXT_TRANSACTION            235896
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                       2048928
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             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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