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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16221212 kB
MemAvailable 30961928 kB
Buffers 20 kB
Cached 15455448 kB
SwapCached 1308 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-08-26 04:37:06
DTS_END                         2021-08-26 04:37:06.7440
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/33320
TRN_ID                          234518
ATT_ID                          667
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: 8765.76 22305 1051891
customer order: creation 170.14 435 20417
customer order: refuse 14.22 323 1706
order to supplier: creation 43.88 1565 5266
order to supplier: removal 3.20 116480 384
invoice (draft): creation 45.83 3451 5500
invoice (draft): removal 48.39 1076 5807
invoice accept: apply 40.58 18380 4870
invoice accept: cancel 43.56 851 5227
customer reserve: creation 3769.03 180 452283
customer reserve: removal 4298.90 68 515868
realization accept: apply 82.44 184 9893
realization accept: cancel 79.08 122 9489
payment from customer: creation 58.28 114 6993
payment from customer: removal 5.58 91 670
payment to supplier: creation 48.41 121 5809
payment to supplier: removal 3.36 104 403
service: total inventory turnovers 4.08 3167 489
service: total monetary turnovers 5.94 446 713
service: aggregate perf. data 0.83 17385 100
service: refresh index statistics 0.03 281558 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-08-2021 04:38:53 to 26-08-2021 04:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8318
TEST_TIME 2 6753
TEST_TIME 3 6137
TEST_TIME 4 5761
TEST_TIME 5 5316
TEST_TIME 6 6176
TEST_TIME 7 6016
TEST_TIME 8 5916
TEST_TIME 9 6214
TEST_TIME 10 6201
TEST_TIME 11 6101
TEST_TIME 12 6043
TEST_TIME 13 6129
TEST_TIME 14 6339
TEST_TIME 15 6830
TEST_TIME 16 6988
TEST_TIME 17 7316
TEST_TIME 18 7977
TEST_TIME 19 8569
TEST_TIME 20 8474
TEST_TIME 21 8340
TEST_TIME 22 8436
TEST_TIME 23 8721
TEST_TIME 24 8633
TEST_TIME 25 9612
TEST_TIME 26 10411
TEST_TIME 27 10984
TEST_TIME 28 10937
TEST_TIME 29 10636
TEST_TIME 30 10490
TEST_TIME 31 10387
TEST_TIME 32 10145
TEST_TIME 33 10067
TEST_TIME 34 10010
TEST_TIME 35 9825
TEST_TIME 36 9706
TEST_TIME 37 9578
TEST_TIME 38 9597
TEST_TIME 39 9430
TEST_TIME 40 9380
TEST_TIME 41 9261
TEST_TIME 42 9279
TEST_TIME 43 9313
TEST_TIME 44 9315
TEST_TIME 45 9298
TEST_TIME 46 9278
TEST_TIME 47 9262
TEST_TIME 48 9331
TEST_TIME 49 9332
TEST_TIME 50 9327
TEST_TIME 51 9353
TEST_TIME 52 9419
TEST_TIME 53 9483
TEST_TIME 54 9371
TEST_TIME 55 9204
TEST_TIME 56 9150
TEST_TIME 57 9091
TEST_TIME 58 8995
TEST_TIME 59 9043
TEST_TIME 60 9069
TEST_TIME 61 9078
TEST_TIME 62 9066
TEST_TIME 63 8991
TEST_TIME 64 9038
TEST_TIME 65 9017
TEST_TIME 66 8980
TEST_TIME 67 8976
TEST_TIME 68 9026
TEST_TIME 69 9009
TEST_TIME 70 8960
TEST_TIME 71 9098
TEST_TIME 72 9208
TEST_TIME 73 9141
TEST_TIME 74 9242
TEST_TIME 75 9215
TEST_TIME 76 9101
TEST_TIME 77 9076
TEST_TIME 78 9064
TEST_TIME 79 9013
TEST_TIME 80 8954
TEST_TIME 81 8910
TEST_TIME 82 8908
TEST_TIME 83 8838
TEST_TIME 84 8905
TEST_TIME 85 8958
TEST_TIME 86 8969
TEST_TIME 87 8963
TEST_TIME 88 8975
TEST_TIME 89 9067
TEST_TIME 90 9090
TEST_TIME 91 9055
TEST_TIME 92 9127
TEST_TIME 93 9162
TEST_TIME 94 9159
TEST_TIME 95 9205
TEST_TIME 96 9143
TEST_TIME 97 9106
TEST_TIME 98 9059
TEST_TIME 99 9061
TEST_TIME 100 9007
TEST_TIME 101 9001
TEST_TIME 102 8984
TEST_TIME 103 8934
TEST_TIME 104 8917
TEST_TIME 105 8907
TEST_TIME 106 8912
TEST_TIME 107 8974
TEST_TIME 108 8944
TEST_TIME 109 8980
TEST_TIME 110 8971
TEST_TIME 111 8929
TEST_TIME 112 8963
TEST_TIME 113 9005
TEST_TIME 114 9019
TEST_TIME 115 8952
TEST_TIME 116 8944
TEST_TIME 117 8902
TEST_TIME 118 8868
TEST_TIME 119 8831
TEST_TIME 120 8770

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-08-2021 04:38:57 to 26-08-2021 04:39:00.

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 1471149 1471149 0 0.00 0 17278 7 0 0
doc_list_biud 1471149 1471149 0 0.00 0 41947 30 0 0
sp_add_invoice_to_stock 4953 4870 83 1.67 48 219194 18380 0 83
sp_cancel_adding_invoice 5227 5227 0 0.00 4 86937 851 0 0
sp_cancel_client_order 1706 1706 0 0.00 4 9369 324 0 0
sp_cancel_customer_reserve 515868 515868 0 0.00 1 41948 68 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 6703 91 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 7546 104 0 0
sp_cancel_supplier_invoice 5809 5807 2 0.03 1 26836 1076 0 2
sp_cancel_supplier_order 384 384 0 0.00 6 923800 116480 0 0
sp_cancel_write_off 9489 9489 0 0.00 1 10013 122 0 0
sp_client_order 20417 20417 0 0.00 4 45540 436 0 0
sp_customer_reserve 452283 452283 0 0.00 0 86666 180 0 0
sp_fill_shopping_cart 31175 31175 0 0.00 1 23575 47 0 0
sp_fill_shopping_cart_clo_res 452276 452276 0 0.00 0 19378 18 0 0
sp_get_clo_for_invoice 4859 4859 0 0.00 0 27056 404 0 0
sp_kill_cost_storno 15789 15789 0 0.00 0 10170 60 0 0
sp_kill_qstorno_ret_qs2qd 1047416 1047416 0 0.00 0 41808 38 0 0
sp_kill_qty_storno 553233 553233 0 0.00 0 41947 80 0 0
sp_lock_selected_doc 20441 20441 0 0.00 0 5759 5 0 0
sp_make_cost_storno 27554 27554 0 0.00 0 17273 59 0 0
sp_multiply_rows_for_pdistr 27554 27554 0 0.00 0 10738 32 0 0
sp_multiply_rows_for_qdistr 20417 20417 0 0.00 0 40258 225 0 0
sp_pay_from_customer 6993 6993 0 0.00 4 11966 114 0 0
sp_pay_to_supplier 5809 5809 0 0.00 5 11499 122 0 0
sp_payment_common 12802 12802 0 0.00 1 11779 26 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 9362 292 0 0
sp_qd_handle_on_invoice_upd_sts 10086 10086 0 0.00 0 25416 319 0 0
sp_qd_handle_on_reserve_upd_sts 9893 9893 0 0.00 0 13547 46 0 0
sp_reserve_write_off 9893 9893 0 0.00 7 14952 184 0 0
sp_supplier_invoice 5500 5500 0 0.00 7 96782 3452 0 0
sp_supplier_order 5266 5266 0 0.00 5 51838 1566 0 0
srv_aggregate_perf_data 101 100 1 0.99 2659 333201 17386 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2615 129215 47495 0 0
srv_find_qd_qs_mism 1005261 1005261 0 0.00 0 16109 6 0 0
srv_make_invnt_saldo 489 489 0 0.00 54 100246 3168 0 0
srv_make_money_saldo 713 713 0 0.00 1 9805 447 0 0
srv_recalc_idx_stat 4 4 0 0.00 119709 574003 281559 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 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 4870 4870 0 0.00 0 19485 187 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 2629 80 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 5038 18 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 466 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 2855 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 286 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 801 12 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 20763 1481 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 7933 27 0 0
t$perf-norm:sp_client_order 20417 20417 0 0.00 0 10211 20 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 1775 5 0 0
t$perf-norm:sp_pay_from_customer 6993 6993 0 0.00 0 6316 9 0 0
t$perf-norm:sp_pay_to_supplier 5809 5809 0 0.00 0 7576 12 0 0
t$perf-norm:sp_reserve_write_off 9893 9893 0 0.00 0 7018 13 0 0
t$perf-norm:sp_supplier_invoice 5500 5500 0 0.00 0 4283 18 0 0
t$perf-norm:sp_supplier_order 5266 5266 0 0.00 0 7000 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 132 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4820 78 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 5695 23 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 1405 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 9884 9718 166 1.67 0 2921 13 0 166
v_all_customers 37366 37366 0 0.00 0 15 1 0 0
v_all_suppliers 29882 29882 0 0.00 0 21 1 0 0
v_all_wares 40834 40834 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 3558 11 0 0
v_cancel_client_order 3412 3412 0 0.00 0 6499 7 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 6500 12 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 232 3 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 7532 21 0 0
v_cancel_supplier_invoice 866 862 4 0.46 0 5079 18 0 4
v_cancel_supplier_order 768 768 0 0.00 0 232 3 0 0
v_cancel_write_off 1998 1998 0 0.00 0 1437 12 0 0
v_max_id_clo_ord 5266 5266 0 0.00 0 319 1 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 2839 19 0 0
v_max_id_ord_sup 5492 5492 0 0.00 0 7092 12 0 0
v_max_non_paid_invoice 1626 1626 0 0.00 0 555 2 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 1310 3 0 0
v_min_id_clo_ord 5266 5266 0 0.00 0 2291 5 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 728 2 0 0
v_min_id_ord_sup 5492 5492 0 0.00 0 10314 11 0 0
v_min_non_paid_invoice 5809 5809 0 0.00 0 4358 17 0 0
v_min_non_paid_realizn 6993 6993 0 0.00 0 8940 22 0 0
v_our_firm 10028 10028 0 0.00 0 12 1 0 0
v_reserve_write_off 19784 19784 0 0.00 0 4429 5 0 0
x_lock_depdocs_on_canc_invoice 10603 10603 0 0.00 0 12327 114 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 15936 325 0 0
x_make_qstorno_1000_1200 5266 5266 0 0.00 2 51798 1498 0 0
x_make_qstorno_1200_2000 5500 5500 0 0.00 4 96745 3253 0 0
x_make_qstorno_2100_3300 452283 452283 0 0.00 0 78558 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-08-2021 04:39:01 to 26-08-2021 04:39:05.

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:10 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 27186 8192000000 9721819264 10016681984 797747408 8.873 100 91 182 0 826934672 7231904 21955360
02:09:43 26159 8192000000 9750332176 10054107136 824397728 9.143 100 87 174 1 858122208 12415136 21309344
02:11:17 13470 8192000000 9797559296 10113372160 860054384 9.501 100 83 166 1 902982480 23317264 19610832
02:12:43 10370 8192000000 9859915984 10194481152 916879536 10.065 100 75 150 0 974476592 41624000 15973056
02:13:55 5509 8192000000 9925110496 10270158848 962605600 10.514 100 86 172 2 1042562160 61688048 18268512
02:15:03 3281 8192000000 9992346576 10346291200 1001077776 10.889 100 91 182 2 1103748512 83696048 18974688
02:16:08 1593 8192000000 10008639840 10369126400 1029788848 11.166 100 88 176 0 1122277856 75116112 17372896
02:17:11 1627 8192000000 10021807280 10390216704 1056331360 11.421 100 94 188 3 1130622720 54839520 19451840
02:18:14 25032 8192000000 10012404688 10407899136 1066343232 11.517 100 57 115 1 1109850688 31052912 12454544
02:19:40 7139 8192000000 9998445200 10394902528 1079805536 11.646 100 49 99 1 1117025280 25744512 11475232
02:20:49 5999 8192000000 10014347888 10409492480 1092714240 11.768 100 34 69 2 1126552448 26010416 7827792
02:21:59 10889 8192000000 10006894624 10408144896 1101557760 11.852 100 31 63 0 1129335104 20972768 6804576
02:23:11 26383 8192000000 10028070800 10417860608 1102666416 11.863 100 67 135 1 1126978672 9345024 14967232
02:24:38 4302 8192000000 10043611216 10451353600 1105209888 11.887 100 43 86 2 1165916768 52030720 8676160
02:25:43 9103 8192000000 10017530944 10432294912 1106646448 11.901 100 35 70 1 1129053680 14707920 7699312
02:26:53 23788 8192000000 9994158272 10407985152 1036261632 11.229 94 32 60 1 1059835584 17482672 6091280
02:28:21 5323 8192000000 9994312800 10386112512 1067737968 11.530 100 50 101 1 1095426688 16593456 11095264
02:29:27 5306 8192000000 9922861632 10303565824 975689008 10.642 95 32 63 0 1013497824 30396832 7411984
02:30:34 72992 8192000000 9922053712 10318376960 954072528 10.431 93 38 76 1 985812256 23187904 8551824
02:32:49 9112 8192000000 9959729456 10352099328 1038867344 11.254 100 38 76 1 1066397456 20001664 7528448
02:33:59 7875 8192000000 9967654096 10368475136 1055962176 11.418 100 43 84 2 1077893408 11375648 10555584
02:35:07 4581 8192000000 9976453040 10372358144 1068338384 11.536 100 32 64 0 1082001312 5669248 7993680
02:36:15 25222 8192000000 9982332112 10400477184 1074835968 11.598 100 39 77 1 1104942992 20792944 9314080
02:37:42 14857 8192000000 10025342288 10425483264 1096745072 11.807 100 53 102 1 1126689040 19748992 10194976
02:38:58 3591 8192000000 10080815376 10477244416 1107996448 11.913 100 60 120 0 1179092160 58284592 12811120
02:40:17 22799 8192000000 10113461760 10520047616 1117502672 12.003 100 50 98 0 1217179360 89264896 10411792
02:41:55 45556 8192000000 10149353872 10560122880 1138909136 12.205 100 48 96 1 1245567552 95049408 11609008
02:44:05 11299 8192000000 10177342320 10589089792 1154663424 12.353 100 58 113 2 1282669344 116025968 11979952
02:45:28 12549 8192000000 10185150176 10609016832 1160655792 12.409 100 36 72 3 1304699888 136465088 7579008
02:46:45 7233 8192000000 10199337408 10622709760 1167123168 12.470 100 36 74 2 1311372432 135981792 8267472
02:48:02 6776 8192000000 10227790960 10645016576 1177171888 12.564 100 56 109 0 1337974160 148087584 12714688
02:49:15 5802 8192000000 10300522016 10715320320 1174031680 12.534 100 63 127 0 1400559056 211977344 14550032
02:50:27 4508 8192000000 10360172112 10773196800 1184171408 12.629 99 83 163 0 1454643056 251331280 19140368
02:51:35 3684 8192000000 10421606800 10838835200 1191347280 12.696 100 76 152 2 1517989248 308342944 18299024
02:52:47 2614 8192000000 10424083776 10850000896 1179860032 12.589 100 87 172 2 1518435408 318798784 19776592
02:53:53 6690 8192000000 10478526176 10902290432 1170542480 12.502 99 59 118 0 1572697216 386674816 15479920
02:55:02 12587 8192000000 10397371920 10899574784 1142986720 12.244 98 46 92 1 1526433392 371600880 11845792
02:56:19 9779 8192000000 10385230304 10841718784 1143179424 12.245 99 46 92 0 1479433904 324709536 11544944
02:57:34 21595 8192000000 10377853616 10798817280 1138489184 12.201 99 38 76 2 1468113648 319872736 9751728
02:59:01 16410 8192000000 10445528768 10870108160 1110917760 11.941 97 32 64 1 1495645504 375649872 9077872
03:00:26 28102 8192000000 10407131648 10851684352 1119995040 12.027 99 33 66 1 1509496976 380710544 8791392
03:02:35 33565 8192000000 10287675424 10769203200 1091902608 11.761 98 22 44 2 1450973424 352782096 6288720
03:04:13 72581 8192000000 10077114608 10498666496 1065259616 11.507 95 24 48 0 1150612464 79747296 5605552
03:06:35 118711 8192000000 10053038192 10509193216 1069080000 11.543 96 27 52 3 1118898208 44808528 5009680
03:09:51 76581 8192000000 10071433328 10519633920 1127071184 12.094 99 11 22 0 1173055424 44101440 1882800
03:12:13 28915 8192000000 10075202096 10535862272 1136885616 12.186 100 12 25 1 1176196016 36685248 2625152
03:13:45 109929 8192000000 10070494720 10559082496 1129980528 12.121 99 20 40 1 1196671632 61991856 4699248
03:16:43 12989 8192000000 10071331120 10506366976 1144380336 12.257 100 42 79 2 1170457696 17660304 8417056
03:18:01 55610 8192000000 10079639312 10516008960 1102639856 11.863 97 16 30 1 1152178544 45902848 3635840
03:20:03 56858 8192000000 10087760192 10552291328 1124072864 12.065 99 19 38 1 1176671552 48418112 4180576
03:22:13 60612 8192000000 10099870336 10527305728 1132699600 12.147 99 21 39 2 1185840864 48650032 4491232
03:24:17 51161 8192000000 10126928016 10602893312 1152973584 12.337 100 23 44 1 1212807984 55568896 4265504
03:26:27 108656 8192000000 10089130416 10571911168 1157110400 12.376 100 23 46 1 1210571264 49081968 4378896
03:29:49 33123 8192000000 10134509616 10610966528 1151164832 12.320 100 8 16 0 1252931600 99974144 1792624
03:31:27 129215 8192000000 10110526048 10565521408 1152633136 12.334 99 43 85 0 1222243120 60770384 8839600
03:34:38 74545 8192000000 10155017328 10610495488 1170386032 12.500 100 15 30 1 1256326928 82618944 3321952
03:37:00 61839 8192000000 10101556336 10614620160 1135442560 12.173 97 12 24 0 1239094144 100973824 2677760
03:39:13 49978 8192000000 10068053088 10511253504 1089367856 11.737 95 11 22 1 1124035472 32476288 2191328
03:41:05 49005 8192000000 10062744656 10521427968 1112996048 11.961 97 31 62 1 1166288800 47078688 6214064
03:42:56 48513 8192000000 10063121680 10491756544 1101464064 11.852 97 20 40 0 1133543040 28335408 3743568
03:44:47 53923 8192000000 10067269360 10499764224 1060472944 11.461 94 18 36 0 1112883552 48676832 3733776
03:46:43 88938 8192000000 10139728720 10620919808 1076377792 11.613 96 11 22 0 1160036160 81132272 2526096
03:49:13 92220 8192000000 10109348000 10541801472 1103627568 11.872 98 18 35 2 1180495312 72532400 4335344
03:51:50 37836 8192000000 10039748576 10486018048 1084168240 11.687 97 23 44 1 1136151760 47778624 4204896
03:53:28 80346 8192000000 10055544464 10520858624 1104848928 11.884 100 28 56 0 1156203680 45655392 5699360
03:56:01 84116 8192000000 10035540624 10472730624 1073486144 11.585 96 12 24 0 1114195408 38236944 2472320
03:58:31 67129 8192000000 10110883456 10555215872 1121321264 12.039 100 19 36 2 1190864128 65527024 4015840
04:00:44 63203 8192000000 10100987248 10600517632 1101549200 11.852 98 18 36 0 1215162256 109207552 4405504
04:02:48 49152 8192000000 10091075936 10526081024 1101030208 11.847 98 22 44 1 1165678544 59838448 4809888
04:04:40 27986 8192000000 10098224256 10526220288 1094765664 11.788 97 43 86 2 1162049648 57757264 9526720
04:06:17 75817 8192000000 10096562528 10548748288 1117578112 12.004 99 20 40 0 1209635552 87913472 4143968
04:08:34 24098 8192000000 10103798576 10532315136 1131943360 12.140 99 35 69 0 1177861296 38947664 6970272
04:09:58 32346 8192000000 10102679632 10538070016 1140339008 12.219 100 26 52 3 1207047696 61612240 5096448
04:11:31 28289 8192000000 10105804112 10545020928 1150474704 12.314 100 29 57 2 1191715232 35232240 6008288
04:13:10 119748 8192000000 10062525536 10506006528 1114868304 11.978 97 24 50 0 1168717392 48340752 5508336
04:16:52 36629 8192000000 10075320896 10554388480 1142880784 12.243 100 5 10 0 1178316448 34399232 1036432
04:18:34 52633 8192000000 10072556672 10560495616 1143592784 12.249 100 5 10 1 1167619376 22960432 1066160
04:20:26 58635 8192000000 10104288624 10568507392 1139699824 12.213 99 17 34 0 1201282304 57760960 3821520
04:22:30 79369 8192000000 10133658112 10607427584 1144324624 12.256 99 26 52 0 1239575072 89456912 5793536
04:24:49 62101 8192000000 10085540816 10526924800 1118112016 12.009 97 21 42 0 1164170496 41749952 4308528
04:26:53 44463 8192000000 10075658176 10513907712 1094130368 11.782 96 15 30 0 1129501936 32371648 2999920
04:28:37 26008 8192000000 10148690992 10574786560 1132287472 12.143 99 41 82 0 1226317760 85741504 8288784
04:30:05 42737 8192000000 10120579824 10549460992 1116159264 11.991 98 39 78 1 1204869728 80108304 8602160
04:31:50 106571 8192000000 10075457408 10560565248 1125980512 12.083 99 29 58 1 1178744784 46905072 5859200
04:34:50 60501 8192000000 10044978592 10491834368 1111678336 11.948 99 11 22 2 1144969120 31107520 2183264
04:36:57 28377 8192000000 9679217824 10092457984 791539536 8.810 71 4 8 0 819066464 26844496 682432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-08-2021 04:39:07 to 26-08-2021 04:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 83 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-08-2021 04:39:23 to 26-08-2021 04:39:25.

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          236467
MON$OLDEST_ACTIVE               236468
MON$OLDEST_SNAPSHOT             236468
MON$NEXT_TRANSACTION            236468
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                       2035040
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

26.08.21 04:39:26. 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-08-26 04:39:28 No severe PSQL-related problems occured
26.08.21 04:39:29. End of report.