Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2021 07:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16532460 kB
MemAvailable 31223036 kB
Buffers 0 kB
Cached 15004364 kB
SwapCached 4864 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.0
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-01-12 07:26:55
DTS_END                         2021-01-12 07:26:55.0360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(151:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50298
TRN_ID                          230414
ATT_ID                          622
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: 9140.20 22816 1096824
customer order: creation 164.21 468 19705
customer order: refuse 14.23 356 1708
order to supplier: creation 42.96 1394 5155
order to supplier: removal 3.43 119830 411
invoice (draft): creation 42.83 3391 5140
invoice (draft): removal 53.90 877 6468
invoice accept: apply 39.63 18518 4755
invoice accept: cancel 48.91 934 5869
customer reserve: creation 3695.35 179 443442
customer reserve: removal 4751.29 69 570155
realization accept: apply 78.25 177 9390
realization accept: cancel 82.73 132 9928
payment from customer: creation 56.55 104 6786
payment from customer: removal 5.74 56 689
payment to supplier: creation 46.23 117 5548
payment to supplier: removal 3.18 73 382
service: total inventory turnovers 3.80 3109 456
service: total monetary turnovers 6.13 511 736
service: aggregate perf. data 0.81 13322 97
service: refresh index statistics 0.03 292694 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-01-2021 07:27:49 to 12-01-2021 07:27:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1472
TEST_TIME 2 3452
TEST_TIME 3 4092
TEST_TIME 4 4418
TEST_TIME 5 4646
TEST_TIME 6 4375
TEST_TIME 7 4689
TEST_TIME 8 5068
TEST_TIME 9 5301
TEST_TIME 10 5389
TEST_TIME 11 5567
TEST_TIME 12 5565
TEST_TIME 13 6094
TEST_TIME 14 6505
TEST_TIME 15 6752
TEST_TIME 16 6912
TEST_TIME 17 7007
TEST_TIME 18 7023
TEST_TIME 19 7665
TEST_TIME 20 8309
TEST_TIME 21 9043
TEST_TIME 22 9307
TEST_TIME 23 9718
TEST_TIME 24 9790
TEST_TIME 25 11203
TEST_TIME 26 11002
TEST_TIME 27 11070
TEST_TIME 28 10833
TEST_TIME 29 10520
TEST_TIME 30 10570
TEST_TIME 31 10424
TEST_TIME 32 10186
TEST_TIME 33 10085
TEST_TIME 34 10063
TEST_TIME 35 10185
TEST_TIME 36 10017
TEST_TIME 37 10127
TEST_TIME 38 10070
TEST_TIME 39 9951
TEST_TIME 40 9938
TEST_TIME 41 10121
TEST_TIME 42 10036
TEST_TIME 43 10171
TEST_TIME 44 10149
TEST_TIME 45 10184
TEST_TIME 46 10246
TEST_TIME 47 10215
TEST_TIME 48 10181
TEST_TIME 49 10240
TEST_TIME 50 10219
TEST_TIME 51 10126
TEST_TIME 52 9998
TEST_TIME 53 9938
TEST_TIME 54 9877
TEST_TIME 55 9809
TEST_TIME 56 9762
TEST_TIME 57 9660
TEST_TIME 58 9672
TEST_TIME 59 9655
TEST_TIME 60 9608
TEST_TIME 61 9554
TEST_TIME 62 9574
TEST_TIME 63 9621
TEST_TIME 64 9646
TEST_TIME 65 9727
TEST_TIME 66 9709
TEST_TIME 67 9694
TEST_TIME 68 9707
TEST_TIME 69 9810
TEST_TIME 70 9812
TEST_TIME 71 9741
TEST_TIME 72 9647
TEST_TIME 73 9557
TEST_TIME 74 9472
TEST_TIME 75 9454
TEST_TIME 76 9451
TEST_TIME 77 9467
TEST_TIME 78 9451
TEST_TIME 79 9421
TEST_TIME 80 9381
TEST_TIME 81 9383
TEST_TIME 82 9389
TEST_TIME 83 9396
TEST_TIME 84 9469
TEST_TIME 85 9503
TEST_TIME 86 9508
TEST_TIME 87 9586
TEST_TIME 88 9562
TEST_TIME 89 9466
TEST_TIME 90 9411
TEST_TIME 91 9332
TEST_TIME 92 9286
TEST_TIME 93 9283
TEST_TIME 94 9271
TEST_TIME 95 9230
TEST_TIME 96 9201
TEST_TIME 97 9190
TEST_TIME 98 9218
TEST_TIME 99 9156
TEST_TIME 100 9318
TEST_TIME 101 9338
TEST_TIME 102 9318
TEST_TIME 103 9338
TEST_TIME 104 9352
TEST_TIME 105 9349
TEST_TIME 106 9380
TEST_TIME 107 9346
TEST_TIME 108 9276
TEST_TIME 109 9256
TEST_TIME 110 9212
TEST_TIME 111 9160
TEST_TIME 112 9109
TEST_TIME 113 9105
TEST_TIME 114 9092
TEST_TIME 115 9067
TEST_TIME 116 9082
TEST_TIME 117 9077
TEST_TIME 118 9065
TEST_TIME 119 9119
TEST_TIME 120 9142

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2021 07:27:53 to 12-01-2021 07:27:56.

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 1505961 1505961 0 0.00 0 14423 6 0 0
doc_list_biud 1505953 1505953 0 0.00 0 85598 33 0 0
sp_add_invoice_to_stock 4889 4755 134 2.74 43 155777 18519 0 134
sp_cancel_adding_invoice 5869 5869 0 0.00 4 102789 935 0 0
sp_cancel_client_order 1708 1708 0 0.00 4 18600 357 0 0
sp_cancel_customer_reserve 570155 570155 0 0.00 1 85600 69 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 1165 57 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 3196 74 0 0
sp_cancel_supplier_invoice 6477 6468 9 0.13 2 78364 877 0 9
sp_cancel_supplier_order 411 411 0 0.00 8 1244550 119830 0 0
sp_cancel_write_off 9928 9928 0 0.00 1 24701 133 0 0
sp_client_order 19705 19705 0 0.00 4 28688 469 0 0
sp_customer_reserve 443442 443442 0 0.00 0 89147 179 0 0
sp_fill_shopping_cart 29994 29994 0 0.00 1 14329 41 0 0
sp_fill_shopping_cart_clo_res 443434 443434 0 0.00 0 17896 18 0 0
sp_get_clo_for_invoice 4746 4746 0 0.00 0 59515 483 0 0
sp_kill_cost_storno 16868 16868 0 0.00 0 12723 57 0 0
sp_kill_qstorno_ret_qs2qd 1157117 1157117 0 0.00 0 85576 37 0 0
sp_kill_qty_storno 608672 608672 0 0.00 0 85597 80 0 0
sp_lock_selected_doc 19627 19627 0 0.00 0 10598 5 0 0
sp_make_cost_storno 26470 26470 0 0.00 0 14374 52 0 0
sp_multiply_rows_for_pdistr 26466 26466 0 0.00 0 7713 28 0 0
sp_multiply_rows_for_qdistr 19705 19705 0 0.00 0 17946 287 0 0
sp_pay_from_customer 6786 6786 0 0.00 4 22692 104 0 0
sp_pay_to_supplier 5548 5548 0 0.00 4 14787 118 0 0
sp_payment_common 12333 12333 0 0.00 1 22685 23 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 0 18591 329 0 0
sp_qd_handle_on_invoice_upd_sts 10613 10613 0 0.00 0 27829 387 0 0
sp_qd_handle_on_reserve_upd_sts 9389 9389 0 0.00 0 13686 54 0 0
sp_reserve_write_off 9390 9390 0 0.00 6 16637 178 0 0
sp_supplier_invoice 5140 5140 0 0.00 7 98549 3392 0 0
sp_supplier_order 5155 5155 0 0.00 5 49128 1394 0 0
srv_aggregate_perf_data 98 97 1 1.02 2770 101931 13323 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1394 159907 50376 0 0
srv_find_qd_qs_mism 1050615 1050615 0 0.00 0 12910 5 0 0
srv_make_invnt_saldo 456 456 0 0.00 56 96704 3110 0 0
srv_make_money_saldo 736 736 0 0.00 1 10956 512 0 0
srv_recalc_idx_stat 4 4 0 0.00 124763 397627 292694 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4755 4755 0 0.00 0 31031 181 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 4969 98 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 3884 15 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 9122 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 812 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 409 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 346 346 0 0.00 0 2804 29 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 16136 1287 0 0
t$perf-norm:sp_cancel_write_off 902 902 0 0.00 0 963 7 0 0
t$perf-norm:sp_client_order 19705 19705 0 0.00 0 39731 19 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 38928 44 0 0
t$perf-norm:sp_pay_from_customer 6786 6786 0 0.00 0 3318 7 0 0
t$perf-norm:sp_pay_to_supplier 5548 5548 0 0.00 0 5237 7 0 0
t$perf-norm:sp_reserve_write_off 9390 9390 0 0.00 0 12682 12 0 0
t$perf-norm:sp_supplier_invoice 5140 5140 0 0.00 0 8768 19 0 0
t$perf-norm:sp_supplier_order 5155 5155 0 0.00 0 25156 20 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 2128 47 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 10753 243 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 309 4 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 2190 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9756 9488 268 2.74 0 7642 22 0 268
v_all_customers 35908 35908 0 0.00 0 182 1 0 0
v_all_suppliers 28444 28444 0 0.00 0 25 1 0 0
v_all_wares 39410 39410 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 3603 11 0 0
v_cancel_client_order 3416 3416 0 0.00 0 3045 6 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 1137 4 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 477 4 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 682 5 0 0
v_cancel_supplier_invoice 710 692 18 2.53 0 7269 22 0 18
v_cancel_supplier_order 822 822 0 0.00 0 906 5 0 0
v_cancel_write_off 1804 1804 0 0.00 0 2076 14 0 0
v_max_id_clo_ord 5155 5155 0 0.00 0 308 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 5270 21 0 0
v_max_id_ord_sup 5134 5134 0 0.00 0 6378 6 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 249 2 0 0
v_max_non_paid_realizn 3644 3644 0 0.00 0 5060 4 0 0
v_min_id_clo_ord 5155 5155 0 0.00 0 4674 5 0 0
v_min_id_clo_res 991 991 0 0.00 0 6978 16 0 0
v_min_id_ord_sup 5134 5134 0 0.00 0 1692 3 0 0
v_min_non_paid_invoice 5547 5547 0 0.00 0 6577 12 0 0
v_min_non_paid_realizn 6785 6785 0 0.00 0 6500 14 0 0
v_our_firm 9786 9786 0 0.00 0 30 1 0 0
v_reserve_write_off 18776 18776 0 0.00 0 6890 6 0 0
x_lock_depdocs_on_canc_invoice 11991 11991 0 0.00 0 18724 104 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 10340 313 0 0
x_make_qstorno_1000_1200 5155 5155 0 0.00 2 49095 1320 0 0
x_make_qstorno_1200_2000 5140 5140 0 0.00 3 98516 3231 0 0
x_make_qstorno_2100_3300 443442 443442 0 0.00 0 89145 159 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-01-2021 07:27:57 to 12-01-2021 07:28:02.

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
04:56:58 8 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
04:58:00 34563 8192000000 9721701296 10015719424 795901664 8.855 100 87 174 0 824475472 7663264 20910544
04:59:40 24650 8192000000 9760796976 10065510400 831808048 9.217 100 92 184 1 866863392 13070080 21985264
05:01:10 15391 8192000000 9809181344 10122117120 865648896 9.557 100 86 172 0 912884256 27475376 19759984
05:02:31 10980 8192000000 9887511360 10217529344 918097344 10.077 100 87 174 2 992996464 56166752 18732368
05:03:45 9790 8192000000 9936711280 10281287680 956958064 10.459 100 82 164 1 1042834816 68312080 17564672
05:04:58 3524 8192000000 9968443344 10321166336 990844048 10.790 100 91 182 0 1084575968 75159680 18572240
05:06:03 2123 8192000000 9993937216 10352406528 1016622224 11.039 100 86 170 2 1111339984 77306832 17410928
05:07:06 1436 8192000000 10023732128 10386944000 1046689728 11.329 100 90 178 1 1139043920 73955504 18398688
05:08:08 6058 8192000000 10005383360 10382274560 1064830512 11.503 100 47 93 0 1126507280 52643488 9033280
05:09:16 38982 8192000000 9993405856 10374262784 1070169264 11.554 100 66 130 2 1093644608 9005040 14470304
05:11:03 6253 8192000000 9974291904 10378940416 1079223360 11.640 100 43 87 3 1097178352 8043328 9911664
05:12:11 5028 8192000000 10021218832 10419511296 1086369136 11.708 100 50 101 1 1126451232 29315184 10766912
05:13:17 4387 8192000000 10003970512 10403323904 1092328368 11.765 100 37 71 1 1122157376 22754032 7074976
05:14:22 58863 8192000000 10036597232 10448916480 1093732992 11.778 100 36 72 3 1125982608 24599888 7649728
05:16:24 5663 8192000000 10013779760 10416537600 1096982864 11.809 100 37 74 2 1136160320 31781072 7396384
05:17:31 8520 8192000000 9976425008 10381668352 1055627744 11.415 98 28 56 1 1086872192 25013760 6230688
05:18:40 8462 8192000000 9957068032 10340859904 1030741968 11.176 97 30 60 2 1061796304 24474528 6579808
05:19:52 38623 8192000000 9932645584 10326020096 983792544 10.721 95 25 50 2 1008939808 19761888 5385376
05:21:31 3902 8192000000 9924172928 10307850240 1014626832 11.020 99 36 68 2 1032368384 9425776 8315776
05:22:36 5590 8192000000 9973084720 10373386240 1050995744 11.370 100 22 43 4 1080939040 24986448 4956848
05:23:42 7889 8192000000 9974078464 10365321216 1054720720 11.406 100 33 64 0 1081122832 19308544 7093568
05:24:51 41773 8192000000 9973898736 10383470592 1061281552 11.469 100 19 38 1 1095522176 30282864 3957760
05:26:35 9776 8192000000 10007412224 10412658688 1087575040 11.720 100 33 66 2 1126263472 31355472 7332960
05:27:54 12919 8192000000 10021249408 10422964224 1101619472 11.853 100 41 81 0 1132021568 22590032 7812064
05:29:09 22772 8192000000 10080975584 10480979968 1115483520 11.984 100 54 105 0 1177046992 50469680 11093792
05:30:37 22578 8192000000 10140480592 10545991680 1139161104 12.208 100 50 100 0 1242130160 92179120 10789936
05:32:08 19263 8192000000 10141321872 10556444672 1149866496 12.308 100 37 74 0 1251310752 93637568 7806688
05:33:31 10275 8192000000 10227766128 10636460032 1159250112 12.396 100 56 112 1 1329350512 157788656 12311744
05:34:47 4389 8192000000 10235917248 10637717504 1160772480 12.410 100 75 150 0 1340781024 163751904 16256640
05:35:59 5177 8192000000 10245790288 10658639872 1177130832 12.563 100 56 112 0 1358210896 168858208 12221856
05:37:07 4996 8192000000 10330132016 10733715456 1178695696 12.578 100 79 156 1 1427596464 231457600 17443168
05:38:18 7993 8192000000 10337837904 10744885248 1175101344 12.544 99 69 138 0 1433737136 241254416 17381376
05:39:31 1721 8192000000 10391669008 10802909184 1173565136 12.530 99 75 149 0 1485284368 295258176 16461056
05:40:36 7352 8192000000 10412546256 10824425472 1175275984 12.546 100 71 140 0 1520398128 329658160 15463984
05:41:48 1390 8192000000 10420076592 10816532480 1164536032 12.446 100 88 177 0 1516267712 330988032 20743648
05:42:52 2464 8192000000 10439809248 10860531712 1155752432 12.363 100 68 134 1 1540915200 368760688 16402080
05:43:59 1699 8192000000 10492197632 10894426112 1154282160 12.350 100 90 178 0 1592252704 416484496 21486048
05:45:06 1809 8192000000 10548830080 10949525504 1142006816 12.234 99 85 170 0 1638289152 475506352 20775984
05:46:13 16311 8192000000 10471127728 10951933952 1154653664 12.353 100 42 84 1 1575726944 409305264 11768016
05:47:33 26270 8192000000 10349681872 10819387392 1147422176 12.285 100 28 56 0 1493849488 338944160 7483152
05:49:15 30582 8192000000 10331496208 10852761600 1125768848 12.081 99 23 46 2 1412472976 280731712 5972416
05:50:58 77861 8192000000 10057382048 10493861888 1057050432 11.428 95 15 30 0 1240212416 179389952 3772032
05:53:17 38738 8192000000 10037590576 10480009216 1101129072 11.848 100 20 40 1 1156240560 50692656 4418832
05:55:01 159906 8192000000 10052769232 10509705216 1089621952 11.739 97 31 62 0 1155001840 59006736 6373152
05:58:44 49798 8192000000 10104739792 10537570304 1084697072 11.692 96 28 56 0 1148485856 58152304 5636480
06:00:34 48371 8192000000 10137337968 10627530752 1122661504 12.052 100 28 56 0 1289155904 159873104 6621296
06:02:26 83762 8192000000 10114814512 10582626304 1111335504 11.945 98 19 38 1 1212457904 96343680 4778720
06:04:52 64714 8192000000 10135831152 10593636352 1130470624 12.126 99 19 38 2 1247319616 112457936 4391056
06:06:59 51202 8192000000 10067011664 10543071232 1116879600 11.998 99 13 26 0 1212946928 93336144 2731184
06:08:51 50383 8192000000 10137671952 10617335808 1142991936 12.244 100 27 54 0 1229460336 80806800 5661600
06:11:05 34365 8192000000 10105391488 10562211840 1150622128 12.315 100 39 78 2 1217958256 59051344 8284784
06:12:56 54207 8192000000 10082173088 10537766912 1140787616 12.223 99 16 32 2 1182673280 38488816 3396848
06:14:54 65529 8192000000 10071633328 10541322240 1125818112 12.082 98 9 18 2 1162463952 35000016 1645824
06:17:00 64072 8192000000 10113395968 10553061376 1134360608 12.162 99 30 59 2 1197781632 56672256 6748768
06:19:05 69843 8192000000 10086198416 10560483328 1146987744 12.281 100 23 46 0 1179337328 27266752 5082832
06:21:17 62947 8192000000 10076129472 10564780032 1127680592 12.099 98 12 23 0 1179846080 49680224 2485264
06:23:21 61795 8192000000 10078453472 10539601920 1113586928 11.966 97 16 32 0 1180953120 63701984 3664208
06:26:11 49692 8192000000 10074317072 10528100352 1091549104 11.757 95 18 36 1 1137844896 42633680 3662112
06:28:01 45319 8192000000 10091773296 10549665792 1125286608 12.077 98 22 44 0 1189369328 59322464 4760256
06:29:48 74456 8192000000 10071261360 10560315392 1106666768 11.901 98 19 38 1 1191225424 80444944 4113712
06:32:03 28444 8192000000 10077389520 10499129344 1095270032 11.793 97 32 64 2 1140637760 38201472 7166256
06:33:35 41168 8192000000 10093217408 10533060608 1076901232 11.618 96 20 40 0 1143952368 62914512 4136624
06:36:09 103993 8192000000 10077291888 10510245888 1071387920 11.565 95 25 50 0 1108117088 31050496 5678672
06:39:01 128548 8192000000 10083783616 10559528960 1095810832 11.798 98 18 36 0 1181624928 81540160 4273936
06:42:26 63279 8192000000 10057705760 10541678592 1073715840 11.588 96 12 24 1 1166846112 90210672 2919600
06:44:34 56825 8192000000 10075010240 10514026496 1118507552 12.013 99 25 49 0 1169709424 45417312 5784560
06:46:31 60477 8192000000 10108933872 10542448640 1095268032 11.793 97 32 64 0 1180339392 77915872 7155488
06:48:38 49376 8192000000 10143886560 10578173952 1128150912 12.104 99 24 48 0 1211784032 78717424 4915696
06:50:28 67778 8192000000 10089903888 10581106688 1121143456 12.038 99 12 24 1 1186558000 63179392 2235152
06:52:36 26291 8192000000 10107279008 10539499520 1126483648 12.088 99 40 80 2 1171467648 36584944 8399056
06:54:10 117032 8192000000 10050391248 10553274368 1095394576 11.794 98 14 28 0 1145429792 46643392 3391824
06:57:34 69435 8192000000 10057928624 10521800704 1099820640 11.836 98 5 10 0 1146135104 45484288 830176
07:00:02 81320 8192000000 10093516560 10530775040 1139245136 12.208 100 13 26 0 1185630160 43763360 2621664
07:02:29 76013 8192000000 10166202720 10634395648 1125471568 12.079 98 22 44 0 1211126848 80427296 5227984
07:04:56 70365 8192000000 10194402752 10631335936 1131539264 12.136 99 21 40 0 1266786624 129746128 5501232
07:07:10 51094 8192000000 10089287584 10551881728 1121392448 12.040 98 16 32 0 1161703280 36940320 3370512
07:09:03 61699 8192000000 10128949600 10590076928 1144459840 12.257 99 36 72 1 1222417328 70634368 7323120
07:11:05 58685 8192000000 10126869056 10577514496 1151973904 12.328 100 29 57 3 1203847136 45834960 6038272
07:13:05 125014 8192000000 10119656848 10608762880 1145955776 12.272 100 15 30 0 1229799328 80820224 3023328
07:16:21 67011 8192000000 10080898320 10544680960 1128017184 12.103 98 12 24 0 1159100000 28314112 2768704
07:19:25 98450 8192000000 10091270400 10546814976 1094236032 11.783 96 10 20 0 1136523312 39913616 2373664
07:22:04 29227 8192000000 10100645040 10559152128 1113953296 11.970 99 25 50 0 1182922848 63501200 5468352
07:23:40 78666 8192000000 10114282384 10559471616 1067630304 11.529 95 27 54 3 1150718016 76973776 6113936
07:26:16 55705 8192000000 9103674720 9370644480 234338352 2.781 22 2 4 0 254512800 19856864 317584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2021 07:28:03 to 12-01-2021 07:28:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 134 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2021 07:28:19 to 12-01-2021 07:28:21.

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          230724
MON$OLDEST_ACTIVE               230725
MON$OLDEST_SNAPSHOT             230725
MON$NEXT_TRANSACTION            230725
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                       2102976
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             796
MON$NEXT_STATEMENT              53


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

12.01.21 07:28:22. 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-01-12 07:28:25 No severe PSQL-related problems occured
12.01.21 07:28:26. End of report.