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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16386176 kB
MemAvailable 31090476 kB
Buffers 0 kB
Cached 15264296 kB
SwapCached 3892 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-02-23 07:26:56
DTS_END                         2021-02-23 07:26:56.3680
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/45430
TRN_ID                          220168
ATT_ID                          629
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: 9028.33 22831 1083400
customer order: creation 163.93 428 19671
customer order: refuse 14.51 400 1741
order to supplier: creation 43.61 1477 5233
order to supplier: removal 3.43 133892 411
invoice (draft): creation 43.70 3942 5244
invoice (draft): removal 52.46 1083 6295
invoice accept: apply 39.72 19533 4766
invoice accept: cancel 47.42 1029 5690
customer reserve: creation 3721.11 188 446533
customer reserve: removal 4602.31 78 552277
realization accept: apply 79.68 198 9561
realization accept: cancel 93.02 130 11162
payment from customer: creation 56.78 114 6814
payment from customer: removal 5.49 121 659
payment to supplier: creation 47.53 126 5704
payment to supplier: removal 3.56 99 427
service: total inventory turnovers 3.39 4221 407
service: total monetary turnovers 5.85 650 702
service: aggregate perf. data 0.83 15654 99
service: refresh index statistics 0.03 273250 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-02-2021 07:27:33 to 23-02-2021 07:27:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2610
TEST_TIME 2 3248
TEST_TIME 3 4328
TEST_TIME 4 4515
TEST_TIME 5 4476
TEST_TIME 6 4579
TEST_TIME 7 4947
TEST_TIME 8 4882
TEST_TIME 9 4992
TEST_TIME 10 5166
TEST_TIME 11 5417
TEST_TIME 12 5906
TEST_TIME 13 6082
TEST_TIME 14 6282
TEST_TIME 15 6365
TEST_TIME 16 6659
TEST_TIME 17 6870
TEST_TIME 18 7245
TEST_TIME 19 7360
TEST_TIME 20 7843
TEST_TIME 21 7993
TEST_TIME 22 8395
TEST_TIME 23 8421
TEST_TIME 24 8341
TEST_TIME 25 8462
TEST_TIME 26 8447
TEST_TIME 27 8892
TEST_TIME 28 9197
TEST_TIME 29 9621
TEST_TIME 30 10103
TEST_TIME 31 10582
TEST_TIME 32 10703
TEST_TIME 33 10654
TEST_TIME 34 10586
TEST_TIME 35 10580
TEST_TIME 36 10413
TEST_TIME 37 10666
TEST_TIME 38 10538
TEST_TIME 39 10329
TEST_TIME 40 10146
TEST_TIME 41 10001
TEST_TIME 42 9810
TEST_TIME 43 9926
TEST_TIME 44 9859
TEST_TIME 45 9750
TEST_TIME 46 9794
TEST_TIME 47 9790
TEST_TIME 48 9694
TEST_TIME 49 9636
TEST_TIME 50 9625
TEST_TIME 51 9474
TEST_TIME 52 9350
TEST_TIME 53 9437
TEST_TIME 54 9390
TEST_TIME 55 9513
TEST_TIME 56 9457
TEST_TIME 57 9410
TEST_TIME 58 9508
TEST_TIME 59 9476
TEST_TIME 60 9384
TEST_TIME 61 9423
TEST_TIME 62 9429
TEST_TIME 63 9565
TEST_TIME 64 9610
TEST_TIME 65 9686
TEST_TIME 66 9768
TEST_TIME 67 9626
TEST_TIME 68 9545
TEST_TIME 69 9488
TEST_TIME 70 9396
TEST_TIME 71 9364
TEST_TIME 72 9325
TEST_TIME 73 9248
TEST_TIME 74 9280
TEST_TIME 75 9240
TEST_TIME 76 9222
TEST_TIME 77 9206
TEST_TIME 78 9186
TEST_TIME 79 9160
TEST_TIME 80 9217
TEST_TIME 81 9233
TEST_TIME 82 9256
TEST_TIME 83 9281
TEST_TIME 84 9255
TEST_TIME 85 9352
TEST_TIME 86 9312
TEST_TIME 87 9235
TEST_TIME 88 9201
TEST_TIME 89 9178
TEST_TIME 90 9124
TEST_TIME 91 9051
TEST_TIME 92 9004
TEST_TIME 93 9054
TEST_TIME 94 9008
TEST_TIME 95 9028
TEST_TIME 96 9045
TEST_TIME 97 9036
TEST_TIME 98 9008
TEST_TIME 99 8990
TEST_TIME 100 9144
TEST_TIME 101 9124
TEST_TIME 102 9160
TEST_TIME 103 9203
TEST_TIME 104 9167
TEST_TIME 105 9128
TEST_TIME 106 9067
TEST_TIME 107 9045
TEST_TIME 108 8997
TEST_TIME 109 8946
TEST_TIME 110 8948
TEST_TIME 111 8920
TEST_TIME 112 8891
TEST_TIME 113 8885
TEST_TIME 114 8854
TEST_TIME 115 8860
TEST_TIME 116 8894
TEST_TIME 117 8911
TEST_TIME 118 8950
TEST_TIME 119 9047
TEST_TIME 120 9033

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-02-2021 07:27:37 to 23-02-2021 07:27:41.

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 1497083 1497083 0 0.00 0 14341 7 0 0
doc_list_biud 1497079 1497079 0 0.00 0 49427 37 0 0
sp_add_invoice_to_stock 4838 4766 72 1.48 42 215036 19534 0 72
sp_cancel_adding_invoice 5690 5690 0 0.00 5 71018 1029 0 0
sp_cancel_client_order 1741 1741 0 0.00 4 12550 400 0 0
sp_cancel_customer_reserve 552277 552277 0 0.00 1 49433 79 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 12071 121 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 2598 100 0 0
sp_cancel_supplier_invoice 6303 6295 8 0.12 2 22262 1084 0 8
sp_cancel_supplier_order 411 411 0 0.00 6 1055384 133893 0 0
sp_cancel_write_off 11162 11162 0 0.00 1 7417 130 0 0
sp_client_order 19671 19671 0 0.00 4 25283 429 0 0
sp_customer_reserve 446533 446533 0 0.00 0 135280 188 0 0
sp_fill_shopping_cart 30147 30147 0 0.00 1 13867 46 0 0
sp_fill_shopping_cart_clo_res 446524 446524 0 0.00 0 32997 19 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 48137 391 0 0
sp_kill_cost_storno 17938 17938 0 0.00 0 9370 68 0 0
sp_kill_qstorno_ret_qs2qd 1122422 1122422 0 0.00 0 49393 43 0 0
sp_kill_qty_storno 591892 591892 0 0.00 0 49423 92 0 0
sp_lock_selected_doc 19892 19892 0 0.00 0 8634 6 0 0
sp_make_cost_storno 26836 26836 0 0.00 0 14134 58 0 0
sp_multiply_rows_for_pdistr 26836 26836 0 0.00 0 8530 34 0 0
sp_multiply_rows_for_qdistr 19671 19671 0 0.00 0 25219 251 0 0
sp_pay_from_customer 6814 6814 0 0.00 4 14606 114 0 0
sp_pay_to_supplier 5704 5704 0 0.00 5 22848 127 0 0
sp_payment_common 12516 12516 0 0.00 1 11839 20 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 12540 373 0 0
sp_qd_handle_on_invoice_upd_sts 10446 10446 0 0.00 0 24956 456 0 0
sp_qd_handle_on_reserve_upd_sts 9560 9560 0 0.00 0 17583 59 0 0
sp_reserve_write_off 9561 9561 0 0.00 6 17611 199 0 0
sp_supplier_invoice 5244 5244 0 0.00 7 152602 3943 0 0
sp_supplier_order 5233 5233 0 0.00 5 29063 1477 0 0
srv_aggregate_perf_data 100 99 1 1.00 2695 98455 15654 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1951 244445 42138 0 0
srv_find_qd_qs_mism 1037686 1037686 0 0.00 0 11051 5 0 0
srv_make_invnt_saldo 407 407 0 0.00 45 116984 4221 0 0
srv_make_money_saldo 702 702 0 0.00 1 14678 650 0 0
srv_recalc_idx_stat 4 4 0 0.00 186941 460304 273251 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 4766 4766 0 0.00 0 8754 216 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 3146 116 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 4384 15 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 1238 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 1259 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 604 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1125 20 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 23295 1845 0 0
t$perf-norm:sp_cancel_write_off 898 898 0 0.00 0 1928 9 0 0
t$perf-norm:sp_client_order 19671 19671 0 0.00 0 10601 23 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 2633 11 0 0
t$perf-norm:sp_pay_from_customer 6814 6814 0 0.00 0 7480 12 0 0
t$perf-norm:sp_pay_to_supplier 5704 5704 0 0.00 0 5047 12 0 0
t$perf-norm:sp_reserve_write_off 9561 9561 0 0.00 0 7278 12 0 0
t$perf-norm:sp_supplier_invoice 5244 5244 0 0.00 0 4283 19 0 0
t$perf-norm:sp_supplier_order 5233 5233 0 0.00 0 8227 23 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 6460 78 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 407 407 0 0.00 0 1446 18 0 0
t$perf-norm:srv_make_money_saldo 702 702 0 0.00 0 2942 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 198 53 0 0
v_add_invoice_to_stock 9652 9508 144 1.49 0 7880 17 0 144
v_all_customers 36284 36284 0 0.00 0 24 1 0 0
v_all_suppliers 29344 29344 0 0.00 0 20 1 0 0
v_all_wares 39342 39342 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 840 8 0 0
v_cancel_client_order 3482 3482 0 0.00 0 3956 6 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 12024 17 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 0 3700 7 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 2170 8 0 0
v_cancel_supplier_invoice 798 782 16 2.00 0 4456 17 0 16
v_cancel_supplier_order 822 822 0 0.00 0 2084 7 0 0
v_cancel_write_off 1796 1796 0 0.00 0 6760 16 0 0
v_max_id_clo_ord 5233 5233 0 0.00 0 173 1 0 0
v_max_id_clo_res 944 944 0 0.00 0 3696 11 0 0
v_max_id_ord_sup 5243 5243 0 0.00 0 4487 3 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 47 2 0 0
v_max_non_paid_realizn 3460 3460 0 0.00 0 1862 3 0 0
v_min_id_clo_ord 5233 5233 0 0.00 0 8633 10 0 0
v_min_id_clo_res 944 944 0 0.00 0 6083 14 0 0
v_min_id_ord_sup 5243 5243 0 0.00 0 5359 8 0 0
v_min_non_paid_invoice 5703 5703 0 0.00 0 6133 10 0 0
v_min_non_paid_realizn 6813 6813 0 0.00 0 5251 12 0 0
v_our_firm 9764 9764 0 0.00 0 72 1 0 0
v_reserve_write_off 19118 19118 0 0.00 0 8156 7 0 0
x_lock_depdocs_on_canc_invoice 11594 11594 0 0.00 0 18619 128 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 3599 348 0 0
x_make_qstorno_1000_1200 5233 5233 0 0.00 2 29037 1380 0 0
x_make_qstorno_1200_2000 5244 5244 0 0.00 3 144976 3774 0 0
x_make_qstorno_2100_3300 446533 446533 0 0.00 0 135280 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-02-2021 07:27:42 to 23-02-2021 07:27:46.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
04:57:00 5 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:00 34793 8192000000 9724302144 10019921920 795571712 8.851 100 89 178 0 825797456 7752192 22473552
04:59:42 28568 8192000000 9759533392 10067460096 834916560 9.249 100 89 178 0 869868832 13475024 21477248
05:01:13 21680 8192000000 9795138960 10112770048 859148016 9.492 100 74 148 0 897930720 21754816 17027888
05:02:38 13344 8192000000 9867889584 10194743296 901637824 9.915 100 81 161 1 971966448 52251984 18076640
05:03:53 5713 8192000000 9910511568 10249424896 942124640 10.314 100 74 149 0 1023492016 65965104 15402272
05:05:03 6247 8192000000 9926515936 10277265408 985031616 10.733 100 77 154 1 1040871504 39639152 16200736
05:06:12 4139 8192000000 10007404288 10364321792 1015464672 11.028 100 87 174 0 1122711600 89867664 17379264
05:07:18 7635 8192000000 10000946240 10364477440 1026331952 11.133 100 68 135 1 1114524880 75400656 12792272
05:08:28 2028 8192000000 10039620960 10407542784 1054836880 11.407 100 90 181 1 1151857104 78683328 18336896
05:09:32 13559 8192000000 10036089072 10424119296 1065667232 11.511 100 57 110 0 1153922224 77559872 10695120
05:10:48 7492 8192000000 10046036944 10428293120 1072173536 11.573 100 69 135 0 1160469440 73930736 14365168
05:11:55 6297 8192000000 9983826752 10385805312 1077456240 11.623 100 28 54 2 1112105584 29290960 5358384
05:13:02 10260 8192000000 9999874160 10393571328 1088143504 11.725 100 32 66 0 1111276832 15794352 7338976
05:14:15 7953 8192000000 9997832448 10401210368 1090042016 11.743 100 16 32 0 1116695072 23091648 3561408
05:15:26 59342 8192000000 10028658912 10419609600 1097056560 11.810 100 48 97 0 1129608048 21690432 10861056
05:17:26 5348 8192000000 10032254384 10436661248 1101269152 11.850 100 43 86 1 1131611568 20337632 10004784
05:18:32 6614 8192000000 10008310816 10416087040 1092302544 11.765 100 28 53 6 1119745104 22114144 5328416
05:19:41 7175 8192000000 9966839856 10361372672 1046748640 11.329 98 40 80 1 1076346864 20794912 8803312
05:20:51 55512 8192000000 9954418064 10353713152 994431808 10.825 94 24 47 0 1021325536 22166640 4727088
05:22:49 6653 8192000000 9934650720 10305515520 980187872 10.686 95 35 70 2 1004407104 16203296 8015936
05:24:00 3340 8192000000 9938228944 10316247040 1017908960 11.052 99 54 108 0 1039729616 8333488 13487168
05:25:05 11261 8192000000 9972102032 10371981312 1046376848 11.326 100 36 70 4 1089924704 35803216 7744640
05:26:18 40830 8192000000 9971375904 10383245312 1057317808 11.431 100 23 45 1 1097723040 35435408 4969824
05:28:14 21083 8192000000 10026504720 10423406592 1087468912 11.719 100 46 92 3 1134335056 37640768 9225376
05:29:40 5476 8192000000 10052688848 10453868544 1112928368 11.960 100 56 112 1 1169899424 44843232 12127824
05:30:49 25988 8192000000 10110858288 10517213184 1128548416 12.108 100 46 92 4 1219834544 81244912 10041216
05:32:18 18447 8192000000 10159570368 10560073728 1135596256 12.174 100 61 120 0 1260855712 111041408 14218048
05:33:54 9405 8192000000 10163517200 10574938112 1149067376 12.301 100 51 102 1 1274399552 114024784 11307392
05:35:10 9369 8192000000 10224689424 10631114752 1161778240 12.420 100 61 122 1 1328826368 153783936 13264192
05:36:22 5772 8192000000 10237595440 10651492352 1165390256 12.454 100 56 112 1 1346791744 168748416 12653072
05:37:32 4705 8192000000 10299991152 10710978560 1180435552 12.594 100 82 164 0 1408520816 209395072 18690192
05:38:40 2455 8192000000 10289284288 10701230080 1179776352 12.588 100 75 146 1 1396359264 200000688 16582224
05:39:47 8643 8192000000 10313696336 10732998656 1195180960 12.732 100 65 129 0 1417129968 207501632 14447376
05:40:59 2264 8192000000 10344716432 10759925760 1199967328 12.776 100 70 138 0 1444795824 229348752 15479744
05:42:11 9202 8192000000 10393163904 10808434688 1206925440 12.841 100 73 144 0 1492372384 268917728 16529216
05:43:25 3583 8192000000 10397639168 10814959616 1203675136 12.810 100 69 138 0 1502023808 281993312 16355360
05:44:37 1951 8192000000 10375326896 10786951168 1200266768 12.779 100 86 171 0 1474393888 254686384 19440736
05:45:44 2222 8192000000 10399420752 10820034560 1192119888 12.703 100 80 160 1 1504559920 293625344 18814688
05:46:52 2686 8192000000 10361771520 10778198016 1189516896 12.679 100 79 157 0 1460104464 252469328 18118240
05:47:58 7213 8192000000 10334660576 10787422208 1173197728 12.527 99 49 97 2 1443178272 257655920 12324624
05:49:18 11475 8192000000 10331513424 10780798976 1168514416 12.483 99 30 60 1 1421930480 245257456 8158608
05:50:36 6446 8192000000 10388339376 10818043904 1145122240 12.264 99 47 92 1 1488679584 331523136 12034208
05:52:04 9829 8192000000 10458631712 10892939264 1160708752 12.410 99 44 87 1 1543059488 370514288 11836448
05:53:18 8928 8192000000 10433525456 10860331008 1154327808 12.350 100 52 102 0 1525559520 358500432 12731280
05:54:31 18042 8192000000 10375354128 10835865600 1159568160 12.399 100 27 54 2 1485377312 318574000 7235152
05:55:56 17786 8192000000 10263645824 10741219328 1085562624 11.700 97 17 34 0 1352009008 261810544 4635840
05:57:23 61698 8192000000 10072010128 10544005120 1091803216 11.760 98 12 24 0 1166891552 72551536 2536800
05:59:31 12752 8192000000 10067303776 10494631936 1108028752 11.914 99 41 82 2 1137647664 20662256 8956656
06:00:45 54201 8192000000 10093128352 10562859008 1097574304 11.815 98 18 36 0 1165374448 63649952 4150192
06:02:40 38383 8192000000 10135361120 10582102016 1112479648 11.956 99 35 69 1 1207782848 87210704 8092496
06:04:26 38311 8192000000 10061756208 10488549376 1087973408 11.723 98 22 43 2 1119699856 26545104 5181344
06:06:07 244445 8192000000 10058875024 10544951296 1102094512 11.858 98 23 46 0 1192231840 85044432 5092896
06:11:16 39015 8192000000 10106426528 10527264768 1093136528 11.772 98 41 82 1 1177715280 75700720 8878032
06:13:01 115384 8192000000 10067252960 10518319104 1122222528 12.048 100 15 30 0 1183869728 58377568 3269632
06:16:00 27670 8192000000 10053751792 10529894400 1130228912 12.124 100 15 30 1 1165387872 32083856 3075104
06:18:05 90095 8192000000 10113854784 10619129856 1124811952 12.072 99 16 33 0 1212076192 83657808 3606432
06:21:42 50813 8192000000 10075605648 10539577344 1127668256 12.099 99 8 16 0 1170220928 41200288 1352384
06:23:38 17898 8192000000 10133409504 10584903680 1151167648 12.320 100 42 84 1 1228122144 68349552 8604944
06:25:03 28648 8192000000 10093200640 10545250304 1135684112 12.175 99 26 52 0 1169632944 28771600 5177232
06:26:34 46300 8192000000 10178696528 10630737920 1139253440 12.209 98 27 54 0 1229093808 84210608 5629760
06:28:30 61365 8192000000 10139294800 10603790336 1122900048 12.054 97 17 34 1 1226169136 99518064 3751024
06:30:44 57833 8192000000 10169504688 10634452992 1146833792 12.280 99 9 18 0 1221260048 72520576 1905680
06:32:42 138360 8192000000 10089709120 10538954752 1145598880 12.268 99 18 36 0 1177099568 28020912 3479776
06:36:02 45547 8192000000 10107733952 10580897792 1142883472 12.243 100 9 18 1 1205906544 60954064 2069008
06:39:03 23591 8192000000 10092562624 10546536448 1137509792 12.192 100 19 38 1 1204231728 62638928 4083008
06:40:31 56034 8192000000 10077010384 10522935296 1120573792 12.032 98 22 44 0 1158196384 33109584 4513008
06:42:35 66459 8192000000 10111720576 10567323648 1091438256 11.756 95 24 48 0 1156258752 59531840 5288656
06:44:43 44969 8192000000 10128962800 10572574720 1121487728 12.041 99 28 56 2 1211353104 83864208 6001168
06:46:33 39833 8192000000 10106086400 10542837760 1099127040 11.829 97 41 80 2 1189202336 81394720 8680576
06:48:39 41707 8192000000 10052083456 10491027456 1066942704 11.523 95 36 72 0 1123076016 49299600 6833712
06:50:21 11369 8192000000 10089761616 10497544192 1110433008 11.937 100 65 130 1 1175641008 51202544 14005456
06:51:32 71201 8192000000 10038946944 10468405248 1081342752 11.660 97 15 30 1 1124781392 40089184 3349456
06:53:53 66856 8192000000 10030330784 10482720768 1098747616 11.826 99 17 34 0 1137284080 35030240 3506224
06:56:14 97151 8192000000 10090861008 10551312384 1088093904 11.725 97 13 24 0 1150465312 59657264 2714144
06:58:51 35594 8192000000 10054143968 10577080320 1104293408 11.878 99 8 16 1 1187524640 81492160 1739072
07:00:30 62520 8192000000 10071198048 10547425280 1128852208 12.111 100 25 50 1 1205827568 71656960 5318400
07:02:34 81286 8192000000 10076514224 10538352640 1074938032 11.599 95 16 32 2 1139509376 61324560 3246784
07:05:06 111820 8192000000 10059722048 10582142976 1088361616 11.727 96 19 38 0 1193424480 100870048 4192816
07:07:58 29586 8192000000 10113436496 10542174208 1122258768 12.048 99 46 91 0 1170297504 38460512 9578224
07:09:35 91220 8192000000 10086032064 10523664384 1142688800 12.241 100 25 50 2 1183799296 36427408 4683088
07:12:06 109124 8192000000 10072836464 10550304768 1146024576 12.272 100 22 44 1 1184114096 33782816 4306704
07:15:05 33022 8192000000 10072288640 10539835392 1151607632 12.325 100 4 8 1 1180001520 27577360 816528
07:16:39 63233 8192000000 10090680144 10570534912 1144043104 12.254 99 13 26 2 1185609344 38626320 2939920
07:18:42 48086 8192000000 10132360912 10591940608 1126627744 12.090 97 18 37 1 1202084624 71759408 3697472
07:21:17 30676 8192000000 10186144992 10644291584 1151600528 12.325 98 27 54 4 1265137168 107380416 6156224
07:23:31 62785 8192000000 10194307824 10677665792 1161670208 12.419 100 17 34 1 1322684368 156524576 4489584
07:26:24 38173 8192000000 9143665328 9403871232 264218848 3.124 24 11 20 1 301191504 34556832 2415824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-02-2021 07:27:48 to 23-02-2021 07:28:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 72 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-02-2021 07:28:04 to 23-02-2021 07:28:06.

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          221187
MON$OLDEST_ACTIVE               221188
MON$OLDEST_SNAPSHOT             221188
MON$NEXT_TRANSACTION            221188
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                       2102656
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 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.2372 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 ---
:114: 
:115: mscs-test-161.budzdorov.ru    Tue Feb 23 07:27:05 2021
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Tue Feb 23 07:27:05 2021
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Tue Feb 23 07:27:06 2021
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
--- end of diff output ---

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

23.02.21 07:28:07. 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-02-23 07:28:09 No severe PSQL-related problems occured
23.02.21 07:28:11. End of report.