Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.01.2021 13:28
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 16616396 kB
MemAvailable 31231168 kB
Buffers 0 kB
Cached 14983456 kB
SwapCached 4868 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-20 13:26:55
DTS_END                         2021-01-20 13:26:55.1930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/32962
TRN_ID                          240085
ATT_ID                          663
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: 8663.12 23373 1039574
customer order: creation 173.05 390 20766
customer order: refuse 13.88 304 1666
order to supplier: creation 46.00 1249 5520
order to supplier: removal 3.17 99049 380
invoice (draft): creation 46.56 3735 5587
invoice (draft): removal 47.17 807 5660
invoice accept: apply 42.78 17571 5134
invoice accept: cancel 42.70 954 5124
customer reserve: creation 3807.73 178 456927
customer reserve: removal 4152.75 61 498330
realization accept: apply 84.54 175 10145
realization accept: cancel 74.01 128 8881
payment from customer: creation 59.66 119 7159
payment from customer: removal 5.94 110 713
payment to supplier: creation 48.76 129 5851
payment to supplier: removal 3.68 99 442
service: total inventory turnovers 3.70 3360 444
service: total monetary turnovers 6.08 522 730
service: aggregate perf. data 0.93 12047 111
service: refresh index statistics 0.03 326480 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-01-2021 13:28:25 to 20-01-2021 13:28:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3298
TEST_TIME 2 4211
TEST_TIME 3 4800
TEST_TIME 4 4475
TEST_TIME 5 5186
TEST_TIME 6 5713
TEST_TIME 7 5900
TEST_TIME 8 5786
TEST_TIME 9 6144
TEST_TIME 10 6484
TEST_TIME 11 6651
TEST_TIME 12 6833
TEST_TIME 13 7137
TEST_TIME 14 7046
TEST_TIME 15 7163
TEST_TIME 16 7475
TEST_TIME 17 7530
TEST_TIME 18 7897
TEST_TIME 19 8268
TEST_TIME 20 8558
TEST_TIME 21 8857
TEST_TIME 22 9259
TEST_TIME 23 9187
TEST_TIME 24 9214
TEST_TIME 25 10083
TEST_TIME 26 10045
TEST_TIME 27 10703
TEST_TIME 28 10347
TEST_TIME 29 10018
TEST_TIME 30 9847
TEST_TIME 31 9831
TEST_TIME 32 10056
TEST_TIME 33 10004
TEST_TIME 34 9795
TEST_TIME 35 9628
TEST_TIME 36 9744
TEST_TIME 37 9628
TEST_TIME 38 9505
TEST_TIME 39 9355
TEST_TIME 40 9286
TEST_TIME 41 9348
TEST_TIME 42 9249
TEST_TIME 43 9249
TEST_TIME 44 9271
TEST_TIME 45 9238
TEST_TIME 46 9260
TEST_TIME 47 9223
TEST_TIME 48 9146
TEST_TIME 49 9373
TEST_TIME 50 9406
TEST_TIME 51 9402
TEST_TIME 52 9383
TEST_TIME 53 9318
TEST_TIME 54 9172
TEST_TIME 55 9132
TEST_TIME 56 9215
TEST_TIME 57 9130
TEST_TIME 58 9051
TEST_TIME 59 8975
TEST_TIME 60 8978
TEST_TIME 61 9096
TEST_TIME 62 9017
TEST_TIME 63 9056
TEST_TIME 64 9075
TEST_TIME 65 9087
TEST_TIME 66 9093
TEST_TIME 67 9119
TEST_TIME 68 9090
TEST_TIME 69 9172
TEST_TIME 70 9160
TEST_TIME 71 9203
TEST_TIME 72 9229
TEST_TIME 73 9183
TEST_TIME 74 9354
TEST_TIME 75 9318
TEST_TIME 76 9304
TEST_TIME 77 9189
TEST_TIME 78 9147
TEST_TIME 79 9117
TEST_TIME 80 9019
TEST_TIME 81 8955
TEST_TIME 82 8960
TEST_TIME 83 8984
TEST_TIME 84 8948
TEST_TIME 85 8917
TEST_TIME 86 8898
TEST_TIME 87 8894
TEST_TIME 88 8944
TEST_TIME 89 9007
TEST_TIME 90 9013
TEST_TIME 91 9079
TEST_TIME 92 9124
TEST_TIME 93 9141
TEST_TIME 94 9175
TEST_TIME 95 9204
TEST_TIME 96 9176
TEST_TIME 97 9109
TEST_TIME 98 9073
TEST_TIME 99 9025
TEST_TIME 100 8997
TEST_TIME 101 8938
TEST_TIME 102 8913
TEST_TIME 103 8964
TEST_TIME 104 8933
TEST_TIME 105 8925
TEST_TIME 106 8935
TEST_TIME 107 8939
TEST_TIME 108 8934
TEST_TIME 109 8944
TEST_TIME 110 8944
TEST_TIME 111 8964
TEST_TIME 112 8948
TEST_TIME 113 8937
TEST_TIME 114 8864
TEST_TIME 115 8819
TEST_TIME 116 8772
TEST_TIME 117 8748
TEST_TIME 118 8712
TEST_TIME 119 8703
TEST_TIME 120 8667

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-01-2021 13:28:28 to 20-01-2021 13:28:32.

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 1463210 1463210 0 0.00 0 19654 7 0 0
doc_list_biud 1463209 1463209 0 0.00 0 51151 27 0 0
sp_add_invoice_to_stock 5244 5134 110 2.09 27 196144 17571 0 110
sp_cancel_adding_invoice 5124 5124 0 0.00 5 64921 954 0 0
sp_cancel_client_order 1666 1666 0 0.00 5 11367 304 0 0
sp_cancel_customer_reserve 498330 498330 0 0.00 1 51154 61 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 16673 111 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 6206 99 0 0
sp_cancel_supplier_invoice 5673 5660 13 0.22 1 24949 807 0 13
sp_cancel_supplier_order 380 380 0 0.00 7 1172951 99050 0 0
sp_cancel_write_off 8881 8881 0 0.00 1 10576 129 0 0
sp_client_order 20766 20766 0 0.00 4 48376 391 0 0
sp_customer_reserve 456927 456927 0 0.00 0 56673 179 0 0
sp_fill_shopping_cart 31865 31865 0 0.00 1 14658 43 0 0
sp_fill_shopping_cart_clo_res 456917 456917 0 0.00 0 23401 16 0 0
sp_get_clo_for_invoice 5124 5124 0 0.00 0 44998 401 0 0
sp_kill_cost_storno 15160 15160 0 0.00 0 16669 55 0 0
sp_kill_qstorno_ret_qs2qd 1011581 1011581 0 0.00 0 51144 33 0 0
sp_kill_qty_storno 535310 535310 0 0.00 0 51151 72 0 0
sp_lock_selected_doc 21000 21000 0 0.00 0 10327 5 0 0
sp_make_cost_storno 28279 28279 0 0.00 0 16523 56 0 0
sp_multiply_rows_for_pdistr 28279 28279 0 0.00 0 8451 30 0 0
sp_multiply_rows_for_qdistr 20766 20766 0 0.00 0 47626 216 0 0
sp_pay_from_customer 7159 7159 0 0.00 4 15064 119 0 0
sp_pay_to_supplier 5851 5851 0 0.00 5 15048 129 0 0
sp_payment_common 13010 13010 0 0.00 1 10239 30 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 7211 268 0 0
sp_qd_handle_on_invoice_upd_sts 10248 10248 0 0.00 0 30442 342 0 0
sp_qd_handle_on_reserve_upd_sts 10145 10145 0 0.00 0 23286 53 0 0
sp_reserve_write_off 10145 10145 0 0.00 6 23309 175 0 0
sp_supplier_invoice 5587 5587 0 0.00 7 121297 3735 0 0
sp_supplier_order 5520 5520 0 0.00 5 33703 1250 0 0
srv_aggregate_perf_data 112 111 1 0.89 2907 81811 12047 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1657 155767 47210 0 0
srv_find_qd_qs_mism 992182 992182 0 0.00 0 19654 6 0 0
srv_make_invnt_saldo 444 444 0 0.00 47 99559 3361 0 0
srv_make_money_saldo 730 730 0 0.00 1 15458 522 0 0
srv_recalc_idx_stat 4 4 0 0.00 73240 479649 326481 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 5134 5134 0 0.00 0 34258 181 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 5522 105 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 4554 21 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1282 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1751 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 1513 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 3180 32 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 40802 1324 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 2172 8 0 0
t$perf-norm:sp_client_order 20766 20766 0 0.00 0 28310 19 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 587 4 0 0
t$perf-norm:sp_pay_from_customer 7159 7159 0 0.00 0 5277 7 0 0
t$perf-norm:sp_pay_to_supplier 5851 5851 0 0.00 0 7353 9 0 0
t$perf-norm:sp_reserve_write_off 10145 10145 0 0.00 0 7253 9 0 0
t$perf-norm:sp_supplier_invoice 5587 5587 0 0.00 0 4093 15 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 6862 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 321 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 14 1 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 1906 11 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 4276 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10468 10248 220 2.10 0 7679 20 0 220
v_all_customers 37622 37622 0 0.00 0 25 1 0 0
v_all_suppliers 30274 30274 0 0.00 0 16 1 0 0
v_all_wares 41532 41532 0 0.00 0 98 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 2665 9 0 0
v_cancel_client_order 3332 3332 0 0.00 0 6479 7 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 5237 9 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 1728 6 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 3699 13 0 0
v_cancel_supplier_invoice 876 850 26 2.96 0 2654 20 0 26
v_cancel_supplier_order 760 760 0 0.00 0 3676 12 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2410 14 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 1847 1 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 4369 15 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 5582 4 0 0
v_max_non_paid_invoice 1813 1813 0 0.00 0 3799 4 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 6438 5 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 3147 5 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 4622 8 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 6104 8 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 9820 21 0 0
v_min_non_paid_realizn 7159 7159 0 0.00 0 6610 22 0 0
v_our_firm 10472 10472 0 0.00 0 16 1 0 0
v_reserve_write_off 20290 20290 0 0.00 0 6509 7 0 0
x_lock_depdocs_on_canc_invoice 10359 10359 0 0.00 0 34718 116 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 8723 327 0 0
x_make_qstorno_1000_1200 5520 5520 0 0.00 1 32001 1171 0 0
x_make_qstorno_1200_2000 5587 5587 0 0.00 3 121267 3572 0 0
x_make_qstorno_2100_3300 456927 456927 0 0.00 0 56661 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-01-2021 13:28:33 to 20-01-2021 13:28:37.

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
10:56:59 7 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:57:59 30280 8192000000 9708706336 10001715200 784450176 8.738 100 89 178 0 813746800 7198880 22097744
10:59:36 21387 8192000000 9754260112 10057183232 820033904 9.099 100 86 171 0 853560784 12742176 20784704
11:01:06 11384 8192000000 9784875552 10099179520 853807488 9.438 100 83 166 0 897015216 23837568 19370160
11:02:28 11539 8192000000 9860224256 10194534400 913932592 10.036 100 75 150 0 977323008 46796224 16594192
11:03:46 11078 8192000000 9916327280 10260283392 957851328 10.468 100 74 147 1 1026114832 53283920 14979584
11:05:00 7064 8192000000 9964829968 10318426112 990692480 10.788 100 81 162 1 1073915792 66923344 16299968
11:06:09 3342 8192000000 9996848400 10358349824 1020326624 11.075 100 84 168 0 1114084368 76467984 17289760
11:07:13 1564 8192000000 10015983120 10383847424 1045157488 11.314 100 94 187 1 1136016096 71175856 19682752
11:08:16 39327 8192000000 9975148464 10356281344 1061477328 11.471 100 45 90 0 1087421872 15943504 10001040
11:09:56 6922 8192000000 9990831520 10386034688 1066336688 11.517 100 42 84 1 1101743072 26107248 9299136
11:11:03 6540 8192000000 9986432784 10376646656 1082659104 11.673 100 32 63 0 1111246800 21957056 6630640
11:12:12 7491 8192000000 9997724192 10397458432 1090993088 11.752 100 34 68 2 1117066896 18986480 7087328
11:13:21 67395 8192000000 9999841504 10402496512 1091675072 11.759 100 40 80 0 1124853232 23546336 9631824
11:15:29 8218 8192000000 9988916288 10389143552 1085595456 11.701 99 37 73 1 1110228064 16478128 8154480
11:16:39 9176 8192000000 10020907904 10415095808 1097776096 11.817 100 40 80 4 1133276960 26159040 9341824
11:17:50 6026 8192000000 9968451840 10351075328 1004035952 10.918 94 34 67 0 1035284800 23249552 7999296
11:18:56 49335 8192000000 9906369808 10292109312 906466848 9.962 87 25 47 0 937348336 26026752 4854736
11:20:46 3932 8192000000 9921588208 10293518336 999460480 10.873 98 37 72 3 1019938928 12816352 7662096
11:21:51 7357 8192000000 9933829344 10317971456 993311568 10.814 96 43 87 3 1021139712 17908896 9919248
11:22:59 9749 8192000000 9956773344 10354933760 1043718352 11.300 100 23 46 0 1070779280 21549264 5511664
11:24:09 42862 8192000000 9963304064 10367614976 1054069088 11.400 100 24 48 1 1079571152 19663968 5838096
11:25:53 6658 8192000000 9980951520 10369277952 1065970448 11.514 100 43 84 0 1089648128 13890000 9787680
11:27:05 6332 8192000000 10005625344 10401685504 1089689424 11.740 100 36 72 0 1115363488 17754592 7919472
11:28:18 37106 8192000000 10051931632 10453262336 1097158720 11.811 100 47 94 1 1173448128 66616208 9673200
11:30:01 21158 8192000000 10074625792 10476822528 1115011184 11.980 100 39 76 3 1172451344 49484272 7955888
11:31:25 9995 8192000000 10106561888 10508582912 1126877680 12.092 100 57 111 0 1212572992 74506160 11189152
11:32:38 3462 8192000000 10109386416 10530942976 1134473952 12.164 100 64 127 0 1222276752 72453472 15349328
11:33:48 15795 8192000000 10138824208 10552750080 1145667504 12.269 100 48 96 1 1244423504 88402464 10353536
11:35:07 10578 8192000000 10185999232 10599653376 1150450704 12.314 100 53 105 1 1290042656 128796624 10795328
11:36:23 3480 8192000000 10198016304 10599419904 1137393904 12.191 99 75 148 0 1287421216 133176560 16850752
11:37:31 7012 8192000000 10182288416 10601631744 1157409536 12.379 100 56 112 0 1290029456 119936832 12683088
11:38:42 3559 8192000000 10211014064 10617810944 1162396656 12.426 100 76 151 3 1316457728 137855808 16205264
11:39:49 2770 8192000000 10215499136 10609774592 1152198080 12.330 99 78 151 2 1299509520 131114240 16197200
11:40:55 3302 8192000000 10231839936 10635894784 1136479952 12.182 99 63 126 0 1321792784 171429184 13883648
11:42:04 11320 8192000000 10273452576 10665832448 1132929744 12.149 99 76 150 0 1360956800 211249120 16777936
11:43:24 1654 8192000000 10305666256 10696237056 1140668672 12.222 100 84 168 0 1398887440 239155232 19063536
11:44:29 1669 8192000000 10305016000 10708008960 1124455776 12.069 100 89 178 2 1405895040 259991184 21448080
11:45:34 1897 8192000000 10260115936 10655805440 1120162768 12.029 100 74 150 1 1357337184 219919824 17254592
11:46:40 6967 8192000000 10275801392 10693484544 1123089920 12.056 100 53 106 1 1385323392 248820560 13412912
11:47:49 7641 8192000000 10254839552 10669649920 1104029632 11.876 99 45 88 0 1340495600 225278224 11187744
11:49:22 14461 8192000000 10206300416 10646945792 1090377952 11.746 98 25 50 2 1303806512 207466304 5962256
11:50:48 11958 8192000000 10254245744 10673242112 1088703392 11.730 99 34 68 0 1340741280 243754288 8283600
11:52:03 26274 8192000000 10146919040 10579238912 1094860496 11.789 99 14 28 0 1246914256 148785712 3268048
11:53:35 28328 8192000000 10062695168 10511962112 1104806336 11.883 100 19 38 0 1160905824 51734000 4365488
11:55:04 71936 8192000000 10092309728 10513768448 1105347616 11.888 99 45 89 2 1175622096 60639808 9634672
11:57:19 67464 8192000000 10158832784 10591182848 1134234176 12.161 100 19 38 1 1240794448 102393776 4166496
11:59:27 38971 8192000000 10074244096 10524942336 1123768592 12.063 99 13 26 0 1194018672 67273616 2976464
12:01:07 92294 8192000000 10054692400 10521776128 1120136816 12.028 99 23 45 0 1195755568 70287472 5331280
12:04:13 20930 8192000000 10065296464 10509082624 1137865856 12.195 100 8 16 0 1182640144 43000704 1773584
12:05:47 41107 8192000000 10103573008 10557988864 1145987200 12.272 100 21 42 0 1206420896 56596432 3837264
12:07:29 61268 8192000000 10084764688 10534821888 1132326784 12.143 98 13 26 1 1178025376 43088464 2610128
12:09:34 28480 8192000000 10119121328 10595229696 1150608592 12.315 100 48 94 3 1245854640 84618160 10627888
12:11:22 36484 8192000000 10105740800 10547605504 1154990640 12.356 100 20 40 1 1197397408 38606256 3800512
12:13:10 58045 8192000000 10171922688 10620366848 1169259248 12.490 100 19 38 2 1258557184 85156800 4141136
12:15:08 63463 8192000000 10128095648 10594598912 1138071088 12.197 98 9 18 0 1199303936 59013872 2218976
12:17:42 65568 8192000000 10109504288 10558406656 1125607408 12.080 97 10 20 0 1190431248 62381920 2441920
12:19:48 155767 8192000000 10136368624 10643890176 1140119856 12.217 99 33 66 0 1219414000 72604032 6690112
12:23:24 44047 8192000000 10100125088 10547097600 1133069536 12.150 99 8 16 0 1184534496 49618320 1846640
12:25:11 62234 8192000000 10134296256 10598723584 1119893440 12.026 98 19 38 0 1233768096 109534336 4340320
12:27:39 53488 8192000000 10077214976 10559946752 1111281728 11.945 99 9 18 0 1167278896 54304304 1692864
12:29:38 90131 8192000000 10052900704 10492891136 1090285024 11.745 96 12 24 1 1144571216 52086560 2199632
12:32:12 42288 8192000000 10064967296 10494230528 1081903792 11.666 97 17 32 2 1129668336 44516880 3247664
12:33:56 59849 8192000000 10097101776 10551533568 1076222784 11.611 96 26 50 1 1147306160 66102944 4980432
12:35:56 55813 8192000000 10097556512 10544218112 1084845824 11.694 97 27 54 0 1151570464 61146880 5577760
12:37:57 74587 8192000000 10081692560 10546495488 1045483712 11.317 95 20 40 1 1090939072 41756048 3699312
12:41:59 99240 8192000000 10013458592 10479063040 1074895776 11.599 98 17 34 1 1109273280 30550640 3826864
12:44:46 53433 8192000000 10051598368 10487558144 1090147952 11.744 99 9 18 0 1138146688 46059760 1938976
12:47:08 111085 8192000000 10083661472 10561249280 1104092640 11.876 99 20 40 0 1186191680 77718544 4380496
12:49:59 44076 8192000000 10052317568 10490159104 1111384704 11.946 99 24 47 1 1151079616 34403712 5291200
12:51:44 83149 8192000000 10127440256 10555338752 1120465200 12.031 99 17 33 2 1206650896 82435376 3750320
12:54:08 73745 8192000000 10116691152 10570817536 1129894528 12.120 100 13 26 1 1190508672 57421184 3192960
12:56:22 70158 8192000000 10103814928 10590228480 1117087792 11.999 97 19 38 0 1206267328 85226208 3953328
12:58:38 55089 8192000000 10096988784 10563547136 1134179776 12.161 99 7 14 0 1171286336 36396832 709728
13:00:33 54255 8192000000 10089139968 10568015872 1151250768 12.321 100 9 18 0 1215481648 62659440 1571440
13:02:34 90667 8192000000 10109772240 10551554048 1146431744 12.276 99 43 86 2 1197441040 42627728 8381568
13:05:09 69041 8192000000 10119234144 10582642688 1147852896 12.289 99 11 22 0 1173829968 23970288 2006784
13:07:37 75291 8192000000 10134583712 10598281216 1150153600 12.311 99 14 28 0 1244344032 91016064 3174368
13:09:58 96653 8192000000 10104401552 10571890688 1122679552 12.052 97 14 28 2 1186785360 61189664 2916144
13:12:35 67458 8192000000 10088824720 10552803328 1107113600 11.905 96 21 42 0 1154391376 43068528 4209248
13:14:50 29120 8192000000 10103180256 10546130944 1137050848 12.188 99 36 74 2 1185248192 40188080 8009264
13:16:22 85470 8192000000 10068254304 10533224448 1042431152 11.288 92 8 16 0 1095527744 51364416 1732176
13:18:48 111106 8192000000 10028297312 10481954816 1067586064 11.529 95 20 40 1 1125179360 53370960 4222336
13:21:52 81077 8192000000 10027367712 10471178240 1079732656 11.645 98 12 24 0 1117631360 35311136 2587568
13:24:22 52360 8192000000 10022923712 10477490176 1080778080 11.655 98 9 18 1 1134080496 51527056 1775360
13:26:17 50779 8192000000 9044646848 9308041216 195545280 2.331 18 5 8 1 208391232 11894752 951200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-01-2021 13:28:39 to 20-01-2021 13:28:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 110 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-01-2021 13:28:55 to 20-01-2021 13:28:56.

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          240529
MON$OLDEST_ACTIVE               240530
MON$OLDEST_SNAPSHOT             240530
MON$NEXT_TRANSACTION            240530
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                       2099456
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             817
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2332 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2332 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2332 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.2332 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 ---
:48: 
:49: mscs-test-161.budzdorov.ru    Wed Jan 20 13:27:10 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Wed Jan 20 13:27:12 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

20.01.21 13:28:58. 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-20 13:29:00 No severe PSQL-related problems occured
20.01.21 13:29:01. End of report.