Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.10.2021 10:37
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 15693268 kB
MemAvailable 30733320 kB
Buffers 0 kB
Cached 15986568 kB
SwapCached 4716 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-10-02 10:37:08
DTS_END                         2021-10-02 10:37:08.8130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52032
TRN_ID                          241146
ATT_ID                          702
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: 9381.48 20538 1125778
customer order: creation 178.18 395 21382
customer order: refuse 15.23 328 1828
order to supplier: creation 46.47 1324 5576
order to supplier: removal 3.57 107423 428
invoice (draft): creation 47.16 3751 5659
invoice (draft): removal 53.19 1034 6383
invoice accept: apply 43.04 16996 5165
invoice accept: cancel 47.70 807 5724
customer reserve: creation 3967.56 167 476107
customer reserve: removal 4689.12 62 562694
realization accept: apply 84.92 174 10190
realization accept: cancel 74.87 117 8984
payment from customer: creation 60.13 107 7216
payment from customer: removal 5.81 69 697
payment to supplier: creation 49.52 109 5942
payment to supplier: removal 3.64 72 437
service: total inventory turnovers 3.91 3298 469
service: total monetary turnovers 6.41 415 769
service: aggregate perf. data 1.03 15039 123
service: refresh index statistics 0.04 259081 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-10-2021 10:37:58 to 02-10-2021 10:38:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1937
TEST_TIME 2 3213
TEST_TIME 3 2924
TEST_TIME 4 4187
TEST_TIME 5 4306
TEST_TIME 6 4631
TEST_TIME 7 4664
TEST_TIME 8 4732
TEST_TIME 9 5057
TEST_TIME 10 5550
TEST_TIME 11 5980
TEST_TIME 12 6255
TEST_TIME 13 7046
TEST_TIME 14 7239
TEST_TIME 15 7416
TEST_TIME 16 7819
TEST_TIME 17 7813
TEST_TIME 18 8077
TEST_TIME 19 8864
TEST_TIME 20 8785
TEST_TIME 21 8785
TEST_TIME 22 8904
TEST_TIME 23 9199
TEST_TIME 24 10036
TEST_TIME 25 10263
TEST_TIME 26 10716
TEST_TIME 27 10714
TEST_TIME 28 11144
TEST_TIME 29 11042
TEST_TIME 30 10897
TEST_TIME 31 10797
TEST_TIME 32 10710
TEST_TIME 33 10828
TEST_TIME 34 10798
TEST_TIME 35 10799
TEST_TIME 36 10779
TEST_TIME 37 10598
TEST_TIME 38 10355
TEST_TIME 39 10212
TEST_TIME 40 10056
TEST_TIME 41 10192
TEST_TIME 42 10183
TEST_TIME 43 10226
TEST_TIME 44 10229
TEST_TIME 45 10252
TEST_TIME 46 10101
TEST_TIME 47 10112
TEST_TIME 48 10094
TEST_TIME 49 10038
TEST_TIME 50 9961
TEST_TIME 51 9923
TEST_TIME 52 9841
TEST_TIME 53 9790
TEST_TIME 54 9797
TEST_TIME 55 9880
TEST_TIME 56 10020
TEST_TIME 57 9957
TEST_TIME 58 9892
TEST_TIME 59 10022
TEST_TIME 60 9964
TEST_TIME 61 10042
TEST_TIME 62 9991
TEST_TIME 63 9984
TEST_TIME 64 9875
TEST_TIME 65 9782
TEST_TIME 66 9783
TEST_TIME 67 9751
TEST_TIME 68 9682
TEST_TIME 69 9662
TEST_TIME 70 9632
TEST_TIME 71 9580
TEST_TIME 72 9586
TEST_TIME 73 9562
TEST_TIME 74 9522
TEST_TIME 75 9547
TEST_TIME 76 9537
TEST_TIME 77 9586
TEST_TIME 78 9578
TEST_TIME 79 9640
TEST_TIME 80 9619
TEST_TIME 81 9616
TEST_TIME 82 9682
TEST_TIME 83 9606
TEST_TIME 84 9585
TEST_TIME 85 9527
TEST_TIME 86 9520
TEST_TIME 87 9435
TEST_TIME 88 9428
TEST_TIME 89 9414
TEST_TIME 90 9403
TEST_TIME 91 9407
TEST_TIME 92 9402
TEST_TIME 93 9388
TEST_TIME 94 9373
TEST_TIME 95 9448
TEST_TIME 96 9455
TEST_TIME 97 9513
TEST_TIME 98 9495
TEST_TIME 99 9569
TEST_TIME 100 9562
TEST_TIME 101 9545
TEST_TIME 102 9601
TEST_TIME 103 9547
TEST_TIME 104 9458
TEST_TIME 105 9491
TEST_TIME 106 9478
TEST_TIME 107 9467
TEST_TIME 108 9464
TEST_TIME 109 9421
TEST_TIME 110 9389
TEST_TIME 111 9352
TEST_TIME 112 9349
TEST_TIME 113 9356
TEST_TIME 114 9393
TEST_TIME 115 9378
TEST_TIME 116 9354
TEST_TIME 117 9409
TEST_TIME 118 9469
TEST_TIME 119 9456
TEST_TIME 120 9381

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-10-2021 10:38:02 to 02-10-2021 10:38:05.

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 1566578 1566578 0 0.00 0 18785 6 0 0
doc_list_biud 1566578 1566578 0 0.00 0 37521 29 0 0
sp_add_invoice_to_stock 5348 5165 183 3.42 28 172365 16996 0 183
sp_cancel_adding_invoice 5724 5724 0 0.00 5 67773 807 0 0
sp_cancel_client_order 1828 1828 0 0.00 4 14247 329 0 0
sp_cancel_customer_reserve 562694 562694 0 0.00 1 28766 63 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 5159 69 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 4618 72 0 0
sp_cancel_supplier_invoice 6396 6383 13 0.20 1 37527 1035 0 13
sp_cancel_supplier_order 428 428 0 0.00 9 998029 107423 0 0
sp_cancel_write_off 8984 8984 0 0.00 1 14524 117 0 0
sp_client_order 21382 21382 0 0.00 4 21790 396 0 0
sp_customer_reserve 476107 476107 0 0.00 0 42005 168 0 0
sp_fill_shopping_cart 32589 32589 0 0.00 1 17097 47 0 0
sp_fill_shopping_cart_clo_res 476107 476107 0 0.00 0 22999 16 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 26411 375 0 0
sp_kill_cost_storno 15842 15842 0 0.00 0 6828 57 0 0
sp_kill_qstorno_ret_qs2qd 1141183 1141183 0 0.00 0 37520 35 0 0
sp_kill_qty_storno 601395 601395 0 0.00 0 37521 76 0 0
sp_lock_selected_doc 21292 21292 0 0.00 0 8739 5 0 0
sp_make_cost_storno 28512 28512 0 0.00 0 18773 52 0 0
sp_multiply_rows_for_pdistr 28512 28512 0 0.00 0 8407 29 0 0
sp_multiply_rows_for_qdistr 21382 21382 0 0.00 0 19387 190 0 0
sp_pay_from_customer 7216 7216 0 0.00 4 23202 108 0 0
sp_pay_to_supplier 5942 5942 0 0.00 4 8104 109 0 0
sp_payment_common 13158 13158 0 0.00 1 9420 21 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 14241 306 0 0
sp_qd_handle_on_invoice_upd_sts 10888 10888 0 0.00 0 32156 369 0 0
sp_qd_handle_on_reserve_upd_sts 10190 10190 0 0.00 0 12962 57 0 0
sp_reserve_write_off 10190 10190 0 0.00 7 18888 175 0 0
sp_supplier_invoice 5659 5659 0 0.00 7 100764 3752 0 0
sp_supplier_order 5576 5576 0 0.00 6 29151 1325 0 0
srv_aggregate_perf_data 124 123 1 0.80 2616 207292 15039 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1140 141299 47455 0 0
srv_find_qd_qs_mism 1076849 1076849 0 0.00 0 11958 5 0 0
srv_make_invnt_saldo 469 469 0 0.00 54 96070 3299 0 0
srv_make_money_saldo 769 769 0 0.00 1 16602 415 0 0
srv_recalc_idx_stat 5 5 0 0.00 166355 381804 259081 0 0
t$perf-abend:sp_add_invoice_to_stock 183 183 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5165 5165 0 0.00 0 14067 146 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 2353 74 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 3951 13 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 893 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2693 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2109 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 5667 49 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 22701 1329 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 2709 7 0 0
t$perf-norm:sp_client_order 21382 21382 0 0.00 0 6483 16 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 322 3 0 0
t$perf-norm:sp_pay_from_customer 7216 7216 0 0.00 0 3562 8 0 0
t$perf-norm:sp_pay_to_supplier 5942 5942 0 0.00 0 8273 11 0 0
t$perf-norm:sp_reserve_write_off 10190 10190 0 0.00 0 6406 10 0 0
t$perf-norm:sp_supplier_invoice 5659 5659 0 0.00 0 6913 16 0 0
t$perf-norm:sp_supplier_order 5576 5576 0 0.00 0 6648 15 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 210 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 154 3 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 1244 8 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 488 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 13 4 0 0
v_add_invoice_to_stock 10694 10328 366 3.42 0 6396 17 0 366
v_all_customers 38180 38180 0 0.00 0 128 1 0 0
v_all_suppliers 30842 30842 0 0.00 0 15 1 0 0
v_all_wares 42756 42756 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 1045 7 0 0
v_cancel_client_order 3656 3656 0 0.00 0 1160 4 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1078 7 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 5882 11 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1048 7 0 0
v_cancel_supplier_invoice 868 842 26 2.99 0 7093 30 0 26
v_cancel_supplier_order 856 856 0 0.00 0 7115 14 0 0
v_cancel_write_off 2038 2038 0 0.00 0 6496 16 0 0
v_max_id_clo_ord 5574 5574 0 0.00 0 4583 3 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 4719 17 0 0
v_max_id_ord_sup 5633 5633 0 0.00 0 5791 6 0 0
v_max_non_paid_invoice 1728 1728 0 0.00 0 723 3 0 0
v_max_non_paid_realizn 4061 4061 0 0.00 0 4051 3 0 0
v_min_id_clo_ord 5574 5574 0 0.00 0 16786 10 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 5009 17 0 0
v_min_id_ord_sup 5633 5633 0 0.00 0 6385 8 0 0
v_min_non_paid_invoice 5942 5942 0 0.00 0 8005 13 0 0
v_min_non_paid_realizn 7216 7216 0 0.00 0 9416 15 0 0
v_our_firm 10886 10886 0 0.00 0 15 1 0 0
v_reserve_write_off 20380 20380 0 0.00 0 4583 6 0 0
x_lock_depdocs_on_canc_invoice 11686 11686 0 0.00 0 11200 102 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 10816 303 0 0
x_make_qstorno_1000_1200 5576 5576 0 0.00 2 29144 1244 0 0
x_make_qstorno_1200_2000 5659 5659 0 0.00 3 96914 3561 0 0
x_make_qstorno_2100_3300 476107 476107 0 0.00 0 41999 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-10-2021 10:38:06 to 02-10-2021 10:38:10.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 26484 8192000000 9728841344 10031673344 803059088 8.927 100 85 171 0 830673792 7162848 20451856
08:09:48 34049 8192000000 9769392432 10076749824 839944032 9.299 100 91 182 0 876814512 15283808 21586672
08:11:30 16682 8192000000 9811721952 10127790080 866965632 9.570 100 85 170 1 917177008 30733232 19478144
08:12:51 13525 8192000000 9860318400 10193641472 908141920 9.979 100 73 147 0 975810288 51550672 16117696
08:14:08 7419 8192000000 9908812432 10252021760 962340624 10.512 100 79 158 2 1033225920 54022400 16862896
08:15:19 4694 8192000000 9964195312 10321235968 1005219552 10.929 100 81 160 2 1081716048 60420064 16076432
08:16:26 8221 8192000000 10009858816 10376019968 1035524832 11.222 100 81 161 1 1130955936 79007344 16423760
08:17:36 2188 8192000000 10006660208 10375389184 1060868032 11.465 100 92 181 0 1123648640 43528352 19252256
08:18:39 7688 8192000000 10005400064 10388160512 1069296448 11.545 100 59 118 0 1128075392 46688320 12090624
08:19:57 3946 8192000000 9989927520 10381365248 1075431280 11.604 100 46 89 2 1105368352 20187312 9749760
08:21:03 6488 8192000000 10014930608 10416484352 1087522016 11.719 100 36 71 3 1124268048 28873104 7872928
08:22:11 5593 8192000000 10029885952 10423083008 1095934288 11.799 100 36 70 0 1152008336 48234208 7839840
08:23:17 30801 8192000000 10020524960 10415816704 1099666960 11.834 100 67 136 3 1130524288 15160688 15696640
08:24:49 7072 8192000000 10026062608 10434695168 1104732288 11.883 100 31 63 2 1143576688 32216464 6627936
08:25:56 3236 8192000000 10033074544 10436771840 1102155728 11.858 100 52 101 2 1146197728 32539120 11502880
08:27:04 10616 8192000000 9996191680 10395885568 1084240688 11.688 99 31 62 5 1108110240 17176144 6693408
08:28:55 4346 8192000000 9973937776 10362544128 1028120352 11.150 96 52 103 0 1054721712 15136912 11464448
08:30:01 4953 8192000000 9939128448 10322944000 979892320 10.683 94 50 99 4 999186016 8971344 10322352
08:31:11 6010 8192000000 9944005152 10327089152 1023324912 11.104 99 53 109 1 1046833680 11575120 11933648
08:32:21 51896 8192000000 9953694528 10354827264 1014804336 11.022 97 28 54 2 1048364560 27331952 6228272
08:34:13 7103 8192000000 9976262688 10361167872 1053178272 11.391 100 46 91 0 1078330944 14596560 10556112
08:35:23 6892 8192000000 9981750704 10371588096 1060617056 11.462 100 53 107 1 1094419824 21264800 12537968
08:36:37 8718 8192000000 9986160992 10390978560 1063519424 11.490 100 37 74 3 1082679968 11328944 7831600
08:37:55 57586 8192000000 9996552176 10394972160 1083664816 11.682 100 39 78 2 1109241824 16848208 8728800
08:39:58 37444 8192000000 10064045456 10469613568 1110215968 11.934 100 44 88 0 1171938208 52413984 9308256
08:41:38 25572 8192000000 10108370224 10522791936 1130372576 12.125 100 41 80 0 1214584992 75834368 8378048
08:43:04 21055 8192000000 10147074048 10563137536 1139329728 12.209 100 44 87 0 1260233264 111389072 9514464
08:44:28 8767 8192000000 10187353792 10599153664 1151927648 12.328 100 51 100 0 1294226912 131629232 10670032
08:45:40 5071 8192000000 10228177296 10636124160 1155944544 12.365 100 62 122 1 1330057856 160539904 13573408
08:46:47 9645 8192000000 10270079584 10679349248 1162901232 12.430 100 68 136 0 1373497904 195383248 15213424
08:48:00 1138 8192000000 10295028432 10701062144 1173205408 12.527 100 89 175 0 1394887888 202214960 19467520
08:49:04 3407 8192000000 10311225392 10721882112 1178190480 12.573 100 78 155 1 1414352960 219293312 16869168
08:50:12 1264 8192000000 10363880688 10772836352 1188428416 12.669 100 88 175 1 1466619392 259039232 19151744
08:51:16 9826 8192000000 10327109360 10749579264 1149834160 12.308 98 61 122 0 1421561280 257306768 14420352
08:52:32 1927 8192000000 10367020704 10774908928 1172339792 12.519 100 89 178 1 1458944512 266223824 20380896
08:53:39 3700 8192000000 10335321856 10754535424 1158951728 12.393 99 59 118 1 1434217856 261507152 13758976
08:54:48 3316 8192000000 10388047680 10790043648 1153164736 12.339 100 86 172 0 1487604192 314005344 20434112
08:55:55 12310 8192000000 10393894704 10825048064 1137112288 12.188 99 45 90 1 1493261152 344504496 11644368
08:57:14 7325 8192000000 10347024352 10765176832 1152830448 12.336 100 41 82 0 1448749120 285557328 10361344
08:58:39 4968 8192000000 10358120032 10772336640 1125138528 12.076 99 48 96 0 1450212768 311773568 13300672
08:59:48 21289 8192000000 10371820928 10832769024 1133155120 12.151 100 38 76 0 1488088464 344450960 10482384
09:01:12 18055 8192000000 10317282928 10799075328 1137840144 12.195 100 24 48 0 1450491584 305894960 6756480
09:02:44 29612 8192000000 10252695392 10732134400 1121033872 12.037 98 33 66 0 1356313824 227192944 8087008
09:04:33 63311 8192000000 10036928400 10529411072 1075884000 11.608 97 33 64 1 1153781488 70634176 7263312
09:06:38 46461 8192000000 10047245520 10464980992 1101663536 11.853 99 32 60 5 1123235840 15186064 6386240
09:08:33 16638 8192000000 10065298816 10497572864 1124496016 12.069 100 32 64 2 1155578048 24935248 6146784
09:09:53 65312 8192000000 10053766400 10484637696 1084059008 11.686 95 24 49 1 1133404384 44575440 4769936
09:11:59 112861 8192000000 10085050032 10513559552 1087181024 11.716 95 20 38 0 1118516928 27640784 3695120
09:14:52 121176 8192000000 10150206112 10582446080 1129011952 12.112 99 34 67 1 1210288832 73608352 7668528
09:18:28 30212 8192000000 10142922272 10625810432 1144884176 12.261 100 8 15 1 1243317152 96237792 2195184
09:20:02 35614 8192000000 10115366272 10554966016 1150102240 12.310 100 35 70 0 1246188208 87390464 8695504
09:21:47 49903 8192000000 10121097424 10593574912 1161696864 12.419 100 8 15 1 1232087216 68217072 2173280
09:23:47 47047 8192000000 10144498704 10617757696 1156296960 12.369 99 19 38 1 1237908368 76974544 4636864
09:26:11 64283 8192000000 10084127872 10590396416 1147193776 12.283 99 14 28 0 1212680976 62310048 3177152
09:29:06 72327 8192000000 10166436992 10627457024 1167948000 12.478 100 19 36 0 1240858560 68781728 4128832
09:31:19 49779 8192000000 10159333600 10640539648 1158215712 12.387 99 15 30 0 1262482944 101028880 3238352
09:33:10 71651 8192000000 10091426800 10553790464 1129756736 12.119 97 12 24 2 1160608784 28590080 2261968
09:35:29 33584 8192000000 10158327264 10607546368 1148872896 12.299 98 47 94 0 1228662048 69899344 9889808
09:37:05 96453 8192000000 10089630480 10591014912 1125343104 12.077 97 14 28 2 1174669936 45689696 3637136
09:39:48 75646 8192000000 10058074032 10522730496 1064995904 11.504 93 23 45 1 1134067680 64028432 5043344
09:42:04 79463 8192000000 10042387728 10512658432 1059612976 11.453 94 25 50 1 1106481200 42013520 4854704
09:44:29 47856 8192000000 10026104224 10468777984 1084665296 11.692 97 10 20 0 1116863792 30431984 1766512
09:46:22 91894 8192000000 10006571152 10478747648 1023931152 11.110 92 13 26 0 1078006560 50915648 3159760
09:48:54 25801 8192000000 10049440544 10485587968 1066133104 11.515 96 25 50 2 1119101216 47405328 5562784
09:50:20 16070 8192000000 10073746960 10502008832 1103654352 11.872 100 58 117 1 1162850304 47579008 11616944
09:51:36 24746 8192000000 10105981440 10532556800 1075422000 11.604 97 43 86 1 1164267344 79839216 9006128
09:53:02 29839 8192000000 10074570240 10509361152 1079712576 11.645 97 38 76 0 1157829808 70214192 7903040
09:54:35 61590 8192000000 10072415280 10522501120 1096224384 11.802 98 23 46 1 1171287792 70529936 4533472
09:56:57 32065 8192000000 10057537344 10491645952 1108946928 11.922 99 39 78 1 1149071472 31152640 8971904
09:58:34 65635 8192000000 10151534256 10626072576 1131966000 12.140 99 26 52 0 1228485840 90872624 5647216
10:00:41 103374 8192000000 10065479680 10499063808 1124904640 12.073 99 25 50 1 1157609856 27941920 4763296
10:03:24 72670 8192000000 10106780352 10583326720 1139679712 12.213 100 13 26 0 1195569184 53220672 2668800
10:05:38 72816 8192000000 10068119552 10525560832 1121353696 12.040 98 9 18 0 1180903904 57684048 1866160
10:07:55 83419 8192000000 10088881728 10556747776 1148069008 12.291 100 27 54 1 1210422240 56552368 5800864
10:10:20 64731 8192000000 10133302800 10572259328 1097915264 11.818 95 26 52 0 1158405184 55616992 4872928
10:12:29 103069 8192000000 10138706544 10611556352 1127446832 12.097 98 19 35 2 1223139024 91816176 3876016
10:15:27 141299 8192000000 10092853296 10572775424 1133655488 12.156 98 18 36 0 1185870656 48887760 3327408
10:18:54 79410 8192000000 10130372832 10622857216 1134751424 12.166 98 14 28 0 1201718928 64193856 2773648
10:21:14 113075 8192000000 10111233952 10616307712 1106884384 11.903 96 23 46 0 1184714672 72938976 4891312
10:24:39 47595 8192000000 10103780032 10607853568 1148218704 12.293 100 10 20 0 1217921840 67744832 1958304
10:26:27 43186 8192000000 10102876992 10550050816 1108271152 11.916 96 17 34 0 1162502544 50515552 3715840
10:28:11 65727 8192000000 10142777872 10621857792 1152995472 12.338 100 16 32 0 1250645088 93957552 3692064
10:30:25 61779 8192000000 10104418288 10561679360 1124259760 12.067 98 8 16 0 1188443552 62528944 1654848
10:33:09 47528 8192000000 10147826080 10598526976 1101614304 11.853 96 27 54 1 1196535792 88849344 6072144
10:34:57 40280 8192000000 10115623200 10566852608 1081122096 11.658 96 27 54 0 1187852096 100788768 5941232
10:36:37 39089 8192000000 9098295360 9366401024 237988560 2.823 23 5 9 0 251085360 12291616 805184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-10-2021 10:38:12 to 02-10-2021 10:38:27.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-10-2021 10:38:28 to 02-10-2021 10:38:30.

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          241085
MON$OLDEST_ACTIVE               241536
MON$OLDEST_SNAPSHOT             241536
MON$NEXT_TRANSACTION            241536
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                       2067744
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             815
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2621 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2621 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2621 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2621 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 ---
:765: 
:766: mscs-test-161.budzdorov.ru    Sat Oct  2 10:37:23 2021
:767:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:768: 
--- end of diff output ---

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

02.10.21 10:38:31. 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-10-02 10:38:34 No severe PSQL-related problems occured
02.10.21 10:38:35. End of report.