Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.11.2021 13:39
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 16028292 kB
MemAvailable 30845076 kB
Buffers 0 kB
Cached 15718732 kB
SwapCached 2448 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-11-07 13:37:07
DTS_END                         2021-11-07 13:37:07.6870
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/50954
TRN_ID                          237650
ATT_ID                          672
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: 8920.84 25026 1070501
customer order: creation 165.00 413 19800
customer order: refuse 14.41 353 1729
order to supplier: creation 46.03 1575 5524
order to supplier: removal 3.18 111168 381
invoice (draft): creation 45.86 3686 5503
invoice (draft): removal 49.94 981 5993
invoice accept: apply 40.86 19872 4903
invoice accept: cancel 44.86 877 5383
customer reserve: creation 3810.85 194 457302
customer reserve: removal 4414.53 62 529744
realization accept: apply 82.08 217 9849
realization accept: cancel 77.13 137 9255
payment from customer: creation 57.65 145 6918
payment from customer: removal 5.53 83 663
payment to supplier: creation 48.33 173 5800
payment to supplier: removal 3.49 85 419
service: total inventory turnovers 3.81 3607 457
service: total monetary turnovers 6.45 429 774
service: aggregate perf. data 0.83 15547 99
service: refresh index statistics 0.04 340923 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-11-2021 13:39:45 to 07-11-2021 13:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5207
TEST_TIME 2 3327
TEST_TIME 3 5031
TEST_TIME 4 5721
TEST_TIME 5 5844
TEST_TIME 6 5657
TEST_TIME 7 5530
TEST_TIME 8 5575
TEST_TIME 9 5799
TEST_TIME 10 5932
TEST_TIME 11 6003
TEST_TIME 12 6116
TEST_TIME 13 6377
TEST_TIME 14 6526
TEST_TIME 15 6629
TEST_TIME 16 6909
TEST_TIME 17 6995
TEST_TIME 18 7321
TEST_TIME 19 7703
TEST_TIME 20 7718
TEST_TIME 21 8041
TEST_TIME 22 8110
TEST_TIME 23 8497
TEST_TIME 24 8884
TEST_TIME 25 9275
TEST_TIME 26 10746
TEST_TIME 27 10654
TEST_TIME 28 10392
TEST_TIME 29 10213
TEST_TIME 30 10265
TEST_TIME 31 10092
TEST_TIME 32 9897
TEST_TIME 33 9694
TEST_TIME 34 9811
TEST_TIME 35 9666
TEST_TIME 36 9593
TEST_TIME 37 9492
TEST_TIME 38 9302
TEST_TIME 39 9291
TEST_TIME 40 9427
TEST_TIME 41 9488
TEST_TIME 42 9315
TEST_TIME 43 9259
TEST_TIME 44 9319
TEST_TIME 45 9249
TEST_TIME 46 9353
TEST_TIME 47 9405
TEST_TIME 48 9408
TEST_TIME 49 9342
TEST_TIME 50 9378
TEST_TIME 51 9437
TEST_TIME 52 9534
TEST_TIME 53 9502
TEST_TIME 54 9575
TEST_TIME 55 9482
TEST_TIME 56 9594
TEST_TIME 57 9459
TEST_TIME 58 9377
TEST_TIME 59 9286
TEST_TIME 60 9262
TEST_TIME 61 9183
TEST_TIME 62 9111
TEST_TIME 63 9109
TEST_TIME 64 9035
TEST_TIME 65 9088
TEST_TIME 66 9116
TEST_TIME 67 9063
TEST_TIME 68 9004
TEST_TIME 69 9113
TEST_TIME 70 9122
TEST_TIME 71 9166
TEST_TIME 72 9162
TEST_TIME 73 9111
TEST_TIME 74 9209
TEST_TIME 75 9200
TEST_TIME 76 9227
TEST_TIME 77 9318
TEST_TIME 78 9208
TEST_TIME 79 9167
TEST_TIME 80 9133
TEST_TIME 81 9089
TEST_TIME 82 9062
TEST_TIME 83 9025
TEST_TIME 84 8983
TEST_TIME 85 9037
TEST_TIME 86 8986
TEST_TIME 87 8997
TEST_TIME 88 9028
TEST_TIME 89 9048
TEST_TIME 90 8989
TEST_TIME 91 9063
TEST_TIME 92 9053
TEST_TIME 93 9148
TEST_TIME 94 9133
TEST_TIME 95 9137
TEST_TIME 96 9138
TEST_TIME 97 9071
TEST_TIME 98 9007
TEST_TIME 99 8980
TEST_TIME 100 8976
TEST_TIME 101 8924
TEST_TIME 102 8891
TEST_TIME 103 8864
TEST_TIME 104 8913
TEST_TIME 105 8888
TEST_TIME 106 8894
TEST_TIME 107 8901
TEST_TIME 108 8937
TEST_TIME 109 8936
TEST_TIME 110 8937
TEST_TIME 111 8990
TEST_TIME 112 8991
TEST_TIME 113 9060
TEST_TIME 114 9068
TEST_TIME 115 9080
TEST_TIME 116 9097
TEST_TIME 117 9030
TEST_TIME 118 8985
TEST_TIME 119 8978
TEST_TIME 120 8924

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-11-2021 13:39:49 to 07-11-2021 13:39:52.

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 1491600 1491600 0 0.00 0 29195 8 0 0
doc_list_biud 1491600 1491600 0 0.00 0 41758 29 0 0
sp_add_invoice_to_stock 4992 4903 89 1.78 36 176381 19873 0 89
sp_cancel_adding_invoice 5383 5383 0 0.00 4 105222 878 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 11433 354 0 0
sp_cancel_customer_reserve 529744 529744 0 0.00 1 30610 63 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 3308 84 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 4945 85 0 0
sp_cancel_supplier_invoice 6000 5993 7 0.11 2 41810 981 0 7
sp_cancel_supplier_order 381 381 0 0.00 6 1290557 111169 0 0
sp_cancel_write_off 9255 9255 0 0.00 1 15795 137 0 0
sp_client_order 19800 19800 0 0.00 4 21150 413 0 0
sp_customer_reserve 457302 457302 0 0.00 0 39852 194 0 0
sp_fill_shopping_cart 30826 30826 0 0.00 1 16477 51 0 0
sp_fill_shopping_cart_clo_res 457299 457299 0 0.00 0 21125 17 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 35129 382 0 0
sp_kill_cost_storno 15720 15720 0 0.00 0 15333 59 0 0
sp_kill_qstorno_ret_qs2qd 1075117 1075117 0 0.00 0 41754 35 0 0
sp_kill_qty_storno 567233 567233 0 0.00 0 41758 75 0 0
sp_lock_selected_doc 20469 20469 0 0.00 0 7048 5 0 0
sp_make_cost_storno 27466 27466 0 0.00 0 26495 76 0 0
sp_multiply_rows_for_pdistr 27466 27466 0 0.00 0 14163 38 0 0
sp_multiply_rows_for_qdistr 19800 19800 0 0.00 0 14150 208 0 0
sp_pay_from_customer 6918 6918 0 0.00 4 25621 146 0 0
sp_pay_to_supplier 5800 5800 0 0.00 5 20076 174 0 0
sp_payment_common 12718 12718 0 0.00 1 16179 38 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 11426 333 0 0
sp_qd_handle_on_invoice_upd_sts 10282 10282 0 0.00 0 28741 367 0 0
sp_qd_handle_on_reserve_upd_sts 9849 9849 0 0.00 0 18022 62 0 0
sp_reserve_write_off 9849 9849 0 0.00 7 28082 218 0 0
sp_supplier_invoice 5503 5503 0 0.00 6 93115 3687 0 0
sp_supplier_order 5524 5524 0 0.00 6 49463 1576 0 0
srv_aggregate_perf_data 100 99 1 1.00 2935 211578 15547 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1423 126546 45928 0 0
srv_find_qd_qs_mism 1022517 1022517 0 0.00 0 20444 7 0 0
srv_make_invnt_saldo 457 457 0 0.00 45 190842 3607 0 0
srv_make_money_saldo 774 774 0 0.00 1 13851 430 0 0
srv_recalc_idx_stat 5 5 0 0.00 187919 545924 340923 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 4903 4903 0 0.00 0 14807 182 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 4127 111 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 1485 11 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2397 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 1809 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 1611 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 10328 61 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 10558 1425 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 3737 11 0 0
t$perf-norm:sp_client_order 19800 19800 0 0.00 0 13319 17 0 0
t$perf-norm:sp_customer_reserve 1065 1065 0 0.00 0 2832 7 0 0
t$perf-norm:sp_pay_from_customer 6918 6918 0 0.00 0 7787 10 0 0
t$perf-norm:sp_pay_to_supplier 5800 5800 0 0.00 0 6059 14 0 0
t$perf-norm:sp_reserve_write_off 9849 9849 0 0.00 0 5192 9 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 8879 17 0 0
t$perf-norm:sp_supplier_order 5524 5524 0 0.00 0 3368 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 469 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 271 8 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 3876 26 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 1210 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 9974 9796 178 1.78 0 8436 16 0 178
v_all_customers 35844 35844 0 0.00 0 30 1 0 0
v_all_suppliers 30472 30472 0 0.00 0 25 1 0 0
v_all_wares 39600 39600 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 433 7 0 0
v_cancel_client_order 3458 3458 0 0.00 0 2165 4 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 1321 7 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 3459 10 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1052 6 0 0
v_cancel_supplier_invoice 864 850 14 1.62 0 2254 15 0 14
v_cancel_supplier_order 762 762 0 0.00 0 3450 10 0 0
v_cancel_write_off 1964 1964 0 0.00 0 8533 21 0 0
v_max_id_clo_ord 5524 5524 0 0.00 0 810 1 0 0
v_max_id_clo_res 1065 1065 0 0.00 0 4302 21 0 0
v_max_id_ord_sup 5502 5502 0 0.00 0 6732 7 0 0
v_max_non_paid_invoice 1590 1590 0 0.00 0 330 2 0 0
v_max_non_paid_realizn 3751 3751 0 0.00 0 2042 3 0 0
v_min_id_clo_ord 5524 5524 0 0.00 0 9700 20 0 0
v_min_id_clo_res 1065 1065 0 0.00 0 4342 16 0 0
v_min_id_ord_sup 5502 5502 0 0.00 0 12646 14 0 0
v_min_non_paid_invoice 5800 5800 0 0.00 0 10347 22 0 0
v_min_non_paid_realizn 6918 6918 0 0.00 0 10383 33 0 0
v_our_firm 10090 10090 0 0.00 0 33 1 0 0
v_reserve_write_off 19698 19698 0 0.00 0 8478 5 0 0
x_lock_depdocs_on_canc_invoice 10951 10951 0 0.00 0 15066 117 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 7402 340 0 0
x_make_qstorno_1000_1200 5524 5524 0 0.00 2 49455 1483 0 0
x_make_qstorno_1200_2000 5503 5503 0 0.00 3 93044 3491 0 0
x_make_qstorno_2100_3300 457301 457301 0 0.00 0 39847 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-11-2021 13:39:53 to 07-11-2021 13:39:57.

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
11:07:11 24 8192000000 8807404064 8821858304 8626128 0.105 4 1 2 0 8729136 67232 35776
11:08:12 37250 8192000000 9740193552 10039336960 814820400 9.046 100 86 171 0 843372832 7815952 20736480
11:09:55 34999 8192000000 9782922064 10095300608 857406000 9.474 100 93 186 0 892705152 13381040 21918112
11:11:32 19978 8192000000 9821039648 10142371840 884327120 9.743 100 88 176 0 928811104 24082912 20401072
11:13:02 22590 8192000000 9896080496 10237198336 925495856 10.150 100 75 148 1 1001644672 60174240 15974576
11:14:28 7196 8192000000 9943864960 10294763520 972275424 10.609 100 82 163 1 1050694144 61533488 16885232
11:15:38 6139 8192000000 9965999632 10319982592 1004485568 10.922 100 72 142 0 1080528704 61579312 14463824
11:16:46 2694 8192000000 9999698896 10364973056 1030911296 11.177 100 82 164 0 1116112576 68999728 16201552
11:17:51 1225 8192000000 10029355424 10398220288 1050832512 11.369 100 97 195 0 1148645632 78004128 19808992
11:18:53 36869 8192000000 9982215568 10386112512 1058316928 11.440 100 45 90 1 1115730880 48155136 9258816
11:20:35 7982 8192000000 9994880544 10387521536 1074258512 11.593 100 47 93 1 1112692512 28419984 10014016
11:21:43 6928 8192000000 9997087968 10400681984 1079564688 11.643 100 35 69 0 1124307664 37170480 7572496
11:22:51 3272 8192000000 10011261104 10403811328 1085411520 11.699 100 48 97 0 1122559664 25142688 12005456
11:23:57 9066 8192000000 10010087344 10400423936 1088593104 11.729 100 69 137 1 1113928784 10982880 14352800
11:25:07 5844 8192000000 10030196816 10434605056 1095061568 11.791 100 51 101 1 1130576944 24962064 10553312
11:26:15 7199 8192000000 10029974784 10432143360 1105651616 11.891 100 49 96 1 1138932080 22675648 10604816
11:27:24 5816 8192000000 9990575440 10387759104 1049498352 11.356 97 40 79 0 1080530048 22441744 8589952
11:28:32 21922 8192000000 9985082768 10391080960 1045802256 11.320 97 36 70 2 1072443344 19472000 7169088
11:30:02 5617 8192000000 9945179376 10319458304 994831344 10.828 96 48 98 1 1036439344 31437472 10170528
11:31:12 6384 8192000000 9933129568 10308501504 985986208 10.742 96 26 50 1 1014725968 23251168 5488592
11:32:22 15228 8192000000 9935699424 10322317312 1014876464 11.023 98 24 48 0 1051910816 31661808 5372544
11:33:38 10661 8192000000 9974860544 10365571072 1036274688 11.229 99 25 50 0 1074243744 32797120 5171936
11:34:49 11220 8192000000 9978757856 10386128896 1061667312 11.472 100 46 91 1 1083346960 11710352 9969296
11:36:01 6522 8192000000 9992317616 10390167552 1072475360 11.576 100 30 60 0 1097203104 18568352 6159392
11:37:08 34094 8192000000 10000251456 10392678400 1079516880 11.643 100 34 68 1 1109394224 21743904 8133440
11:38:45 102506 8192000000 10064361904 10476015616 1094401760 11.784 100 42 81 3 1159872752 55844000 9626992
11:41:32 47027 8192000000 10056069584 10467180544 1116276640 11.992 100 40 80 0 1165467776 41098752 8092384
11:43:22 12481 8192000000 10087606640 10498023424 1125178128 12.076 100 44 86 2 1199784144 65192336 9413680
11:44:47 18718 8192000000 10138456096 10555846656 1140008512 12.216 100 39 78 0 1254254320 105728528 8517280
11:46:12 8431 8192000000 10165590320 10588004352 1150418032 12.313 100 51 102 1 1271962656 109745024 11799600
11:47:33 12705 8192000000 10208679376 10628390912 1166180208 12.461 100 52 99 4 1308089104 131071424 10837472
11:48:54 1590 8192000000 10235188448 10635997184 1175368736 12.547 100 80 152 0 1332249440 140713216 16167488
11:49:59 9034 8192000000 10271486528 10686525440 1190115184 12.684 100 67 132 1 1366493088 162103888 14274016
11:51:19 4185 8192000000 10298609104 10702614528 1171671344 12.512 100 78 156 2 1398219040 209325536 17222160
11:52:30 2589 8192000000 10340597504 10743107584 1181106080 12.601 100 80 159 2 1436206736 236928096 18172560
11:53:39 2890 8192000000 10339940384 10731556864 1159586112 12.399 100 90 172 1 1430106960 251238720 19282128
11:54:46 1421 8192000000 10378108976 10775080960 1142206368 12.236 99 91 182 2 1469813680 307269792 20337520
11:55:50 3500 8192000000 10382905744 10804256768 1164676448 12.447 100 62 124 2 1486694416 307228640 14789328
11:56:57 5595 8192000000 10365572864 10796208128 1145247104 12.265 100 46 91 3 1466746352 309730928 11768320
11:58:07 7379 8192000000 10417096096 10840076288 1131858544 12.139 99 47 94 0 1507669136 364020672 11789920
11:59:17 10185 8192000000 10392380240 10814464000 1136326832 12.181 100 36 72 1 1491461600 345950576 9184192
12:00:28 15183 8192000000 10399539008 10885447680 1140612992 12.221 100 33 63 1 1527866816 378593664 8660160
12:01:45 25382 8192000000 10312491872 10791784448 1115785520 11.987 99 29 57 0 1452695920 329002160 7908240
12:03:48 59726 8192000000 10044172864 10471583744 1100706128 11.844 97 18 36 0 1133213696 28654128 3853440
12:05:53 30977 8192000000 10093086016 10534985728 1133025344 12.150 100 46 96 1 1188350816 44907952 10417520
12:07:28 61922 8192000000 10094690032 10520616960 1109735232 11.930 98 15 32 0 1152965760 40439312 2791216
12:09:44 69334 8192000000 10128916032 10560258048 1102591632 11.862 97 9 17 1 1203267456 98371312 2304512
12:11:54 98122 8192000000 10097704720 10557194240 1127433648 12.097 100 36 72 1 1202418192 66495648 8488896
12:14:42 2458 8192000000 10074279440 10570878976 1126245728 12.086 100 19 34 0 1186309984 56026080 4038176
12:15:45 27670 8192000000 10130849184 10576769024 1125502016 12.079 99 38 76 1 1211288064 77535552 8250496
12:17:13 73154 8192000000 10086873008 10605903872 1115800352 11.987 99 13 26 0 1212933520 94274064 2859104
12:20:18 82971 8192000000 10065371072 10534789120 1113760224 11.968 99 12 24 0 1170270496 53780208 2730064
12:22:44 48986 8192000000 10094088912 10547273728 1115073920 11.980 98 23 45 1 1186680192 66487360 5118912
12:24:38 48117 8192000000 10082425888 10545909760 1143424736 12.248 100 20 40 1 1198376896 50807744 4144416
12:26:28 34499 8192000000 10115631536 10559586304 1142154192 12.236 99 43 84 2 1191766592 41189712 8422688
12:28:04 66653 8192000000 10075120128 10588708864 1133850912 12.158 99 14 28 0 1194177904 57639680 2687312
12:30:12 61250 8192000000 10104208048 10542714880 1094592320 11.786 95 21 42 1 1146416896 47579984 4244592
12:32:14 31872 8192000000 10098090880 10510589952 1133751712 12.157 99 45 87 0 1180476544 37882768 8842064
12:33:46 126546 8192000000 10085443552 10531966976 1130776432 12.129 99 19 38 0 1176578400 41587808 4214160
12:37:20 21980 8192000000 10066708976 10516787200 1117627776 12.005 98 21 42 0 1173455184 51447952 4379456
12:38:42 72728 8192000000 10077220928 10522259456 1112682640 11.958 98 12 24 0 1177703360 62326368 2694352
12:41:01 64991 8192000000 10074415472 10526420992 1098806048 11.826 98 13 26 1 1158242672 56475584 2961040
12:43:06 80993 8192000000 10034603760 10500182016 1061020272 11.466 94 12 24 0 1124370144 60447696 2902176
12:45:27 49730 8192000000 10122565696 10561609728 1096656480 11.806 97 37 74 0 1188165936 83606864 7902592
12:47:21 68143 8192000000 10080507440 10544472064 1076570544 11.615 96 23 46 0 1132180768 50945536 4664688
12:49:31 35204 8192000000 10069233600 10489737216 1094970576 11.790 98 31 63 0 1121857760 20623104 6264080
12:51:20 41226 8192000000 10041291840 10497548288 1089360432 11.737 98 21 43 0 1147318864 53840624 4117808
12:53:04 51688 8192000000 10102312080 10592210944 1108104944 11.914 99 26 52 1 1188997376 75573392 5319040
12:55:00 54389 8192000000 10053577296 10507051008 1109206336 11.925 100 11 22 0 1168379008 56627424 2545248
12:56:55 17041 8192000000 10035080112 10454376448 1109491712 11.928 99 50 98 4 1134133936 14236912 10405312
12:58:12 49435 8192000000 10058769408 10508005376 1123249952 12.058 100 8 16 0 1168539104 43726384 1562768
13:00:06 56200 8192000000 10071800672 10553540608 1120941376 12.036 100 9 17 0 1180169776 57536576 1691824
13:02:03 78724 8192000000 10075069168 10506948608 1087704240 11.721 96 22 43 1 1150861408 58468928 4688240
13:04:31 43615 8192000000 10120001904 10564268032 1140234224 12.218 100 18 36 0 1221356912 77351488 3771200
13:06:26 76462 8192000000 10127697536 10573529088 1136221040 12.180 99 18 36 0 1208426320 67990000 4215280
13:08:50 33842 8192000000 10119513968 10546200576 1152302848 12.331 100 33 65 1 1226503904 67240816 6960240
13:10:28 50420 8192000000 10074654336 10518675456 1113028768 11.961 97 11 22 1 1145547968 30521200 1998000
13:12:19 43527 8192000000 10097418192 10543673344 1110058976 11.933 97 25 51 0 1151117904 35961824 5097104
13:14:07 106246 8192000000 10087808400 10523828224 1104400768 11.879 97 35 70 1 1150862608 39240576 7221264
13:16:56 41058 8192000000 10068983984 10553282560 1126975008 12.093 99 13 26 0 1172852640 42707344 3170288
13:18:45 49316 8192000000 10071280960 10534412288 1119339648 12.021 98 17 33 1 1167442960 43456448 4646864
13:20:35 77577 8192000000 10081216688 10585288704 1137874864 12.196 100 12 25 0 1198986176 58424048 2687264
13:23:29 65936 8192000000 10128576720 10566631424 1135149136 12.170 99 18 36 0 1213522272 74448064 3925072
13:25:56 86969 8192000000 10136564208 10579718144 1123671376 12.062 98 17 34 0 1172915680 46191952 3052352
13:28:27 86147 8192000000 10116526816 10587049984 1084846656 11.694 95 11 22 0 1168826640 81689920 2290064
13:31:00 32650 8192000000 10104542160 10541846528 1131230400 12.133 100 32 64 0 1170607344 32650656 6726288
13:33:04 98269 8192000000 10075838352 10558861312 1114758128 11.977 98 21 42 0 1160460848 40912784 4789936
13:36:20 55593 8192000000 9644687248 10049851392 728005296 8.161 65 6 12 0 760734480 31593536 1135648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-11-2021 13:39:59 to 07-11-2021 13:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-11-2021 13:40:15 to 07-11-2021 13:40:17.

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          237630
MON$OLDEST_ACTIVE               238165
MON$OLDEST_SNAPSHOT             238165
MON$NEXT_TRANSACTION            238165
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                       2041056
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2653 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2653 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2653 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2653 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 ---
:108: 
:109: mscs-test-161.budzdorov.ru    Sun Nov  7 13:37:19 2021
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Sun Nov  7 13:37:31 2021
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Sun Nov  7 13:37:43 2021
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
--- end of diff output ---

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

07.11.21 13:40:18. 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-11-07 13:40:20 No severe PSQL-related problems occured
07.11.21 13:40:21. End of report.