Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2021 13: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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 17068100 kB
MemAvailable 31539404 kB
Buffers 0 kB
Cached 14573648 kB
SwapCached 0 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-03-16 13:26:55
DTS_END                         2021-03-16 13:26:55.0000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44666
TRN_ID                          225603
ATT_ID                          586
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: 8651.06 21112 1038127
customer order: creation 165.88 417 19905
customer order: refuse 14.08 392 1689
order to supplier: creation 43.32 1297 5198
order to supplier: removal 3.25 128148 390
invoice (draft): creation 43.21 3703 5185
invoice (draft): removal 48.13 1020 5776
invoice accept: apply 39.43 19405 4732
invoice accept: cancel 43.90 977 5268
customer reserve: creation 3660.18 187 439221
customer reserve: removal 4306.64 77 516797
realization accept: apply 79.96 193 9595
realization accept: cancel 80.28 134 9634
payment from customer: creation 57.03 109 6843
payment from customer: removal 5.38 120 646
payment to supplier: creation 46.63 137 5596
payment to supplier: removal 3.24 95 389
service: total inventory turnovers 3.63 3938 435
service: total monetary turnovers 5.97 560 716
service: aggregate perf. data 0.90 15346 108
service: refresh index statistics 0.03 245979 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-03-2021 13:27:27 to 16-03-2021 13:27:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1202
TEST_TIME 2 1769
TEST_TIME 3 2299
TEST_TIME 4 3504
TEST_TIME 5 3773
TEST_TIME 6 3716
TEST_TIME 7 4297
TEST_TIME 8 4391
TEST_TIME 9 4341
TEST_TIME 10 4483
TEST_TIME 11 4735
TEST_TIME 12 5208
TEST_TIME 13 5259
TEST_TIME 14 5586
TEST_TIME 15 5830
TEST_TIME 16 5944
TEST_TIME 17 6173
TEST_TIME 18 6560
TEST_TIME 19 6778
TEST_TIME 20 6741
TEST_TIME 21 7189
TEST_TIME 22 7407
TEST_TIME 23 7896
TEST_TIME 24 8072
TEST_TIME 25 8092
TEST_TIME 26 8182
TEST_TIME 27 8434
TEST_TIME 28 8863
TEST_TIME 29 9622
TEST_TIME 30 10599
TEST_TIME 31 10633
TEST_TIME 32 10597
TEST_TIME 33 10478
TEST_TIME 34 10265
TEST_TIME 35 10085
TEST_TIME 36 9946
TEST_TIME 37 9827
TEST_TIME 38 9784
TEST_TIME 39 9660
TEST_TIME 40 9511
TEST_TIME 41 9514
TEST_TIME 42 9383
TEST_TIME 43 9318
TEST_TIME 44 9278
TEST_TIME 45 9194
TEST_TIME 46 9118
TEST_TIME 47 9026
TEST_TIME 48 8924
TEST_TIME 49 8861
TEST_TIME 50 8789
TEST_TIME 51 8753
TEST_TIME 52 8720
TEST_TIME 53 8725
TEST_TIME 54 8884
TEST_TIME 55 8937
TEST_TIME 56 8903
TEST_TIME 57 8970
TEST_TIME 58 8972
TEST_TIME 59 9050
TEST_TIME 60 9043
TEST_TIME 61 8979
TEST_TIME 62 9142
TEST_TIME 63 9089
TEST_TIME 64 9101
TEST_TIME 65 9147
TEST_TIME 66 9047
TEST_TIME 67 8956
TEST_TIME 68 8905
TEST_TIME 69 8831
TEST_TIME 70 8760
TEST_TIME 71 8659
TEST_TIME 72 8606
TEST_TIME 73 8622
TEST_TIME 74 8685
TEST_TIME 75 8654
TEST_TIME 76 8645
TEST_TIME 77 8611
TEST_TIME 78 8633
TEST_TIME 79 8714
TEST_TIME 80 8737
TEST_TIME 81 8725
TEST_TIME 82 8716
TEST_TIME 83 8709
TEST_TIME 84 8748
TEST_TIME 85 8821
TEST_TIME 86 8736
TEST_TIME 87 8640
TEST_TIME 88 8602
TEST_TIME 89 8602
TEST_TIME 90 8633
TEST_TIME 91 8609
TEST_TIME 92 8594
TEST_TIME 93 8583
TEST_TIME 94 8598
TEST_TIME 95 8627
TEST_TIME 96 8668
TEST_TIME 97 8675
TEST_TIME 98 8701
TEST_TIME 99 8742
TEST_TIME 100 8715
TEST_TIME 101 8705
TEST_TIME 102 8735
TEST_TIME 103 8775
TEST_TIME 104 8734
TEST_TIME 105 8666
TEST_TIME 106 8629
TEST_TIME 107 8615
TEST_TIME 108 8575
TEST_TIME 109 8522
TEST_TIME 110 8575
TEST_TIME 111 8584
TEST_TIME 112 8550
TEST_TIME 113 8556
TEST_TIME 114 8553
TEST_TIME 115 8569
TEST_TIME 116 8595
TEST_TIME 117 8594
TEST_TIME 118 8618
TEST_TIME 119 8625
TEST_TIME 120 8651

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-03-2021 13:27:31 to 16-03-2021 13:27:35.

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 1444507 1444507 0 0.00 0 25223 7 0 0
doc_list_biud 1444507 1444507 0 0.00 0 85522 35 0 0
sp_add_invoice_to_stock 4837 4732 105 2.17 27 209488 19406 0 105
sp_cancel_adding_invoice 5268 5268 0 0.00 4 96180 978 0 0
sp_cancel_client_order 1689 1689 0 0.00 4 10584 392 0 0
sp_cancel_customer_reserve 516797 516797 0 0.00 1 85524 77 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 7362 121 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 9047 96 0 0
sp_cancel_supplier_invoice 5781 5776 5 0.08 2 29813 1021 0 5
sp_cancel_supplier_order 390 390 0 0.00 7 1312115 128149 0 0
sp_cancel_write_off 9634 9634 0 0.00 1 22132 135 0 0
sp_client_order 19905 19905 0 0.00 4 81020 417 0 0
sp_customer_reserve 439221 439221 0 0.00 0 78826 187 0 0
sp_fill_shopping_cart 30280 30280 0 0.00 1 16715 49 0 0
sp_fill_shopping_cart_clo_res 439220 439220 0 0.00 0 28314 19 0 0
sp_get_clo_for_invoice 4731 4731 0 0.00 0 44539 504 0 0
sp_kill_cost_storno 15937 15937 0 0.00 0 9044 71 0 0
sp_kill_qstorno_ret_qs2qd 1049394 1049394 0 0.00 0 85512 42 0 0
sp_kill_qty_storno 553880 553880 0 0.00 0 85522 89 0 0
sp_lock_selected_doc 19762 19762 0 0.00 0 8899 8 0 0
sp_make_cost_storno 26765 26765 0 0.00 0 19119 66 0 0
sp_multiply_rows_for_pdistr 26765 26765 0 0.00 0 11157 34 0 0
sp_multiply_rows_for_qdistr 19905 19905 0 0.00 0 81012 242 0 0
sp_pay_from_customer 6843 6843 0 0.00 4 27114 109 0 0
sp_pay_to_supplier 5596 5596 0 0.00 5 20898 137 0 0
sp_payment_common 12439 12439 0 0.00 1 11182 22 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 10576 361 0 0
sp_qd_handle_on_invoice_upd_sts 9999 9999 0 0.00 0 35717 426 0 0
sp_qd_handle_on_reserve_upd_sts 9595 9595 0 0.00 0 6563 50 0 0
sp_reserve_write_off 9595 9595 0 0.00 7 19602 193 0 0
sp_supplier_invoice 5185 5185 0 0.00 6 85620 3704 0 0
sp_supplier_order 5198 5198 0 0.00 6 28019 1298 0 0
srv_aggregate_perf_data 109 108 1 0.91 2638 151081 15347 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 864 178332 49261 0 0
srv_find_qd_qs_mism 992459 992459 0 0.00 0 25222 6 0 0
srv_make_invnt_saldo 435 435 0 0.00 35 97784 3938 0 0
srv_make_money_saldo 716 716 0 0.00 1 13711 560 0 0
srv_recalc_idx_stat 4 4 0 0.00 120938 378581 245979 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 4732 4732 0 0.00 0 9339 163 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 2211 80 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 7396 20 0 0
t$perf-norm:sp_cancel_customer_reserve 607 607 0 0.00 0 2640 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 785 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 184 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 822 15 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 44619 1668 0 0
t$perf-norm:sp_cancel_write_off 930 930 0 0.00 0 1051 5 0 0
t$perf-norm:sp_client_order 19905 19905 0 0.00 0 12894 20 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 514 3 0 0
t$perf-norm:sp_pay_from_customer 6843 6843 0 0.00 0 28215 12 0 0
t$perf-norm:sp_pay_to_supplier 5596 5596 0 0.00 0 28195 17 0 0
t$perf-norm:sp_reserve_write_off 9595 9595 0 0.00 0 9050 10 0 0
t$perf-norm:sp_supplier_invoice 5185 5185 0 0.00 0 20561 24 0 0
t$perf-norm:sp_supplier_order 5198 5198 0 0.00 0 5036 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 941 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 360 12 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 960 10 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 1653 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 9672 9462 210 2.17 0 3250 15 0 210
v_all_customers 36356 36356 0 0.00 0 17 1 0 0
v_all_suppliers 28804 28804 0 0.00 0 27 1 0 0
v_all_wares 39810 39810 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1644 9 0 0
v_cancel_client_order 3378 3378 0 0.00 0 1645 3 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 1481 6 0 0
v_cancel_customer_reserve 1214 1214 0 0.00 0 565 5 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 191 3 0 0
v_cancel_supplier_invoice 750 740 10 1.33 0 2639 19 0 10
v_cancel_supplier_order 780 780 0 0.00 0 2476 7 0 0
v_cancel_write_off 1860 1860 0 0.00 0 2349 17 0 0
v_max_id_clo_ord 5198 5198 0 0.00 0 1991 2 0 0
v_max_id_clo_res 941 941 0 0.00 0 2854 7 0 0
v_max_id_ord_sup 5175 5175 0 0.00 0 7475 4 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 593 2 0 0
v_max_non_paid_realizn 3524 3524 0 0.00 0 4689 4 0 0
v_min_id_clo_ord 5198 5198 0 0.00 0 3566 6 0 0
v_min_id_clo_res 941 941 0 0.00 0 8443 21 0 0
v_min_id_ord_sup 5175 5175 0 0.00 0 4750 7 0 0
v_min_non_paid_invoice 5596 5596 0 0.00 0 11178 16 0 0
v_min_non_paid_realizn 6843 6843 0 0.00 0 9740 16 0 0
v_our_firm 10092 10092 0 0.00 0 18 1 0 0
v_reserve_write_off 19190 19190 0 0.00 0 2938 6 0 0
x_lock_depdocs_on_canc_invoice 10674 10674 0 0.00 0 19811 123 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 7935 333 0 0
x_make_qstorno_1000_1200 5198 5198 0 0.00 2 27998 1220 0 0
x_make_qstorno_1200_2000 5185 5185 0 0.00 3 85614 3499 0 0
x_make_qstorno_2100_3300 439221 439221 0 0.00 0 78793 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-03-2021 13:27:36 to 16-03-2021 13:27:40.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:58 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:57:59 26984 8192000000 9710494896 10007670784 790382464 8.799 100 82 164 0 817818608 7457728 19978416
10:59:35 28172 8192000000 9764428784 10071023616 839386912 9.294 100 89 178 1 872671136 12233088 21051136
11:01:08 17421 8192000000 9794935568 10111811584 867084624 9.571 100 90 180 0 912052672 23686240 21281808
11:02:33 12132 8192000000 9848631216 10182762496 908122688 9.979 100 71 139 1 962598688 39344512 15131488
11:03:48 11261 8192000000 9918429488 10261696512 954239680 10.433 100 77 154 5 1027762016 56896128 16626208
11:05:03 7888 8192000000 9964590096 10316550144 992072320 10.802 100 74 148 1 1075903168 68953632 14877216
11:06:12 3641 8192000000 9993139216 10353238016 1022167680 11.093 100 86 172 0 1109258288 69518016 17572592
11:07:18 3096 8192000000 10012215696 10382286848 1041844928 11.282 100 91 182 1 1133809440 73487664 18476848
11:08:23 1575 8192000000 10038084672 10406121472 1057430560 11.432 100 86 170 1 1148097120 72756176 17910384
11:09:27 15377 8192000000 10032275472 10413096960 1065284848 11.507 100 70 141 2 1139925632 60088800 14551984
11:10:43 5857 8192000000 9993604688 10378756096 1068110464 11.534 100 38 75 0 1110574800 35204752 7259584
11:11:49 7802 8192000000 10017024944 10403844096 1074375744 11.594 100 38 76 0 1105579712 23278480 7925488
11:12:58 5662 8192000000 9979377440 10379100160 1082402576 11.670 100 45 87 2 1102575568 10452816 9720176
11:14:06 13524 8192000000 10011285952 10393911296 1083477808 11.681 100 58 118 0 1108097152 11539104 13080240
11:15:20 8033 8192000000 10012790816 10413907968 1086239808 11.707 100 34 70 4 1122674080 29196528 7237744
11:16:31 4497 8192000000 9992847792 10392047616 1076938784 11.618 99 41 82 2 1101941664 15040736 9962144
11:17:37 37597 8192000000 9997805456 10408710144 1075522752 11.605 99 18 36 0 1104566704 25025024 4018928
11:19:17 6802 8192000000 9989697200 10383421440 1061126208 11.467 99 29 58 1 1085577152 18844800 5606144
11:20:26 4182 8192000000 9945134416 10321039360 995727072 10.837 96 55 109 3 1026532272 19358320 11446880
11:21:31 66041 8192000000 9918504928 10318090240 949840960 10.390 93 23 46 1 968892560 14493184 4558416
11:23:37 11554 8192000000 9954283232 10346999808 1037642176 11.242 100 20 40 0 1065988672 24122288 4224208
11:24:51 9380 8192000000 9960842672 10352005120 1033507056 11.202 99 44 88 1 1057074528 12910416 10657056
11:26:02 5229 8192000000 9980220000 10378690560 1071262832 11.564 100 38 74 0 1099898208 20725360 7910016
11:27:07 69089 8192000000 10009022080 10408071168 1088436720 11.728 100 39 78 0 1109165536 12659168 8069648
11:29:21 32078 8192000000 10053505488 10457600000 1103180080 11.868 100 38 76 2 1173003040 61988240 7834720
11:30:58 8460 8192000000 10060930992 10470117376 1120164608 12.029 100 40 80 1 1171339888 42030624 9144656
11:32:10 3853 8192000000 10115683072 10518470656 1136291776 12.181 100 63 125 1 1221527456 71732384 13503296
11:33:19 24048 8192000000 10175418688 10602459136 1148326064 12.294 100 50 100 0 1267470832 108493552 10651216
11:34:49 2746 8192000000 10187987520 10594803712 1160447632 12.407 100 77 151 3 1288198608 112022352 15728624
11:35:59 8156 8192000000 10219753360 10630467584 1177700128 12.569 100 54 107 1 1333418448 143101088 12617232
11:37:14 4260 8192000000 10301213200 10718621696 1181816352 12.607 100 69 138 1 1409783616 212464896 15502368
11:38:26 1780 8192000000 10315222496 10731737088 1184759680 12.635 100 79 158 0 1412163984 210435856 16968448
11:39:36 1969 8192000000 10324888448 10741198848 1181020896 12.600 100 72 145 2 1427698576 231451696 15225984
11:40:46 3830 8192000000 10346324352 10788265984 1192445584 12.706 100 66 128 1 1462652256 255080512 15126160
11:41:57 1438 8192000000 10376781728 10785902592 1189894480 12.682 100 89 176 0 1474125808 264560368 19670960
11:43:03 1922 8192000000 10408167456 10821263360 1192355232 12.705 100 74 148 2 1513058704 302527168 18176304
11:44:13 862 8192000000 10418827952 10821623808 1189628736 12.680 100 89 178 0 1509531568 300291504 19611328
11:45:21 1042 8192000000 10428612336 10829733888 1166163152 12.461 100 93 186 0 1522736752 336499344 20074256
11:46:25 3854 8192000000 10439294992 10863423488 1155014896 12.357 100 57 114 1 1542054640 372200368 14839376
11:47:37 2328 8192000000 10447645632 10877931520 1145403744 12.266 100 74 146 0 1542417440 378004432 19009264
11:48:48 3189 8192000000 10495934352 10901991424 1161110160 12.414 100 81 161 0 1584142592 403959376 19073056
11:49:55 11611 8192000000 10450139184 10881585152 1144192480 12.255 99 49 99 1 1558917216 402129696 12595040
11:51:10 12863 8192000000 10453841776 10890182656 1144071648 12.254 100 44 89 0 1545346112 390266992 11007472
11:52:36 22475 8192000000 10475540976 10932350976 1147718272 12.288 100 29 57 1 1576105632 420469376 7917984
11:54:05 19653 8192000000 10433373024 10968772608 1118832224 12.016 100 20 40 0 1574314240 449486048 5995968
11:55:33 15272 8192000000 10263569824 10777554944 1113172016 11.962 100 17 34 1 1378887408 261416480 4298912
11:56:53 42183 8192000000 10072019680 10524352512 1095836832 11.798 97 19 38 1 1151259296 51121376 4301088
11:58:37 47978 8192000000 10066716688 10550439936 1120030880 12.027 99 20 40 0 1152732832 28978176 3723776
12:00:26 113071 8192000000 10102112224 10549694464 1127175408 12.095 99 23 46 0 1168316368 36785152 4355808
12:03:23 45456 8192000000 10094526784 10574172160 1137843648 12.195 100 14 26 3 1201780128 61155408 2781072
12:05:09 54882 8192000000 10074789472 10537877504 1141971344 12.234 100 18 36 0 1195010784 49213312 3826128
12:07:09 86398 8192000000 10061630032 10495877120 1081617168 11.663 95 26 51 0 1113744320 27146672 4980480
12:09:37 26163 8192000000 10089343888 10523332608 1115625728 11.986 99 47 94 4 1178106848 51521536 10959584
12:11:07 178332 8192000000 10059656016 10530250752 1111917328 11.951 99 32 64 1 1171951968 52727616 7307024
12:15:11 38650 8192000000 10133367728 10573750272 1137247392 12.190 100 36 71 3 1229112960 84664512 7201056
12:16:50 69449 8192000000 10135011904 10598957056 1126503776 12.088 99 17 34 1 1217797680 87558528 3735376
12:19:33 65496 8192000000 10075349360 10532900864 1120263200 12.029 99 12 24 0 1166770736 43883856 2623680
12:21:50 80358 8192000000 10122144592 10579394560 1127519840 12.098 99 26 52 0 1182913808 49811632 5582336
12:24:22 88168 8192000000 10090804800 10573606912 1123693008 12.062 98 15 30 0 1215050640 87911312 3446320
12:27:02 79129 8192000000 10101678800 10619498496 1153544064 12.343 100 11 22 0 1225799776 70149760 2105952
12:29:21 74816 8192000000 10122274608 10577448960 1150278176 12.312 100 18 35 1 1188635440 35251440 3105824
12:31:38 124695 8192000000 10061043552 10539855872 1131457600 12.135 99 10 20 0 1167911696 34496384 1957712
12:34:52 51145 8192000000 10049581024 10501079040 1096085088 11.800 96 8 16 0 1120271008 22957200 1228720
12:36:51 64022 8192000000 10075853840 10509258752 1108886096 11.922 98 18 36 0 1169208480 56432640 3889744
12:38:55 49536 8192000000 10089550768 10568634368 1118881984 12.016 99 11 22 1 1185370208 64074336 2413888
12:41:25 19733 8192000000 10069037120 10511110144 1100609152 11.843 97 55 110 0 1144422144 31941712 11871280
12:42:47 38591 8192000000 10093555328 10529923072 1093342832 11.774 97 29 56 2 1159063040 59406528 6313680
12:44:28 62367 8192000000 10145636272 10655752192 1138416176 12.201 100 11 22 1 1285448064 144545712 2486176
12:46:35 57465 8192000000 10060300704 10497560576 1084188432 11.687 96 35 70 0 1116324736 25406096 6730208
12:48:44 21662 8192000000 10085942192 10516586496 1115881744 11.988 100 52 104 1 1180561472 54248640 10431088
12:50:17 72220 8192000000 10112614432 10588381184 1114373120 11.974 99 23 46 1 1192462800 73524848 4564832
12:52:30 163210 8192000000 10103055792 10590429184 1131218288 12.133 99 19 38 0 1211592864 76471648 3902928
12:57:50 50631 8192000000 10120765680 10551652352 1139578176 12.212 99 24 48 1 1181191472 36713472 4899824
12:59:53 100844 8192000000 10152076304 10615685120 1111438176 11.946 96 15 30 1 1189011968 73899792 3674000
13:02:34 82386 8192000000 10119524800 10562519040 1105191968 11.887 96 17 34 0 1165639056 56969424 3477664
13:04:57 49388 8192000000 10118242336 10548232192 1125036160 12.075 99 34 68 0 1183516416 51804048 6676208
13:06:46 52845 8192000000 10104939648 10546528256 1120874336 12.035 98 30 60 1 1195836032 68989824 5971872
13:08:41 62866 8192000000 10074847792 10531315712 1111587712 11.947 97 24 48 1 1161046448 44914112 4544624
13:10:45 113605 8192000000 10038970960 10490523648 1079944224 11.647 95 16 32 0 1100989856 17531056 3514576
13:13:43 97107 8192000000 10086078496 10538336256 1125347808 12.077 99 10 19 0 1165459200 37678864 2432528
13:16:20 106325 8192000000 10076599168 10637156352 1104152688 11.877 98 5 10 0 1183922880 78828944 941248
13:19:10 30387 8192000000 10090713488 10536280064 1121340384 12.040 99 26 52 0 1176464224 49514208 5609632
13:20:42 70002 8192000000 10104786672 10550329344 1140078560 12.216 100 22 44 0 1200484496 55645280 4760656
13:22:52 94676 8192000000 10079477792 10520932352 1071923568 11.570 94 12 24 0 1130163056 55782320 2457168
13:25:47 71085 8192000000 9221736672 9516851200 348132800 4.076 31 7 14 0 361345744 11928800 1284144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-03-2021 13:27:42 to 16-03-2021 13:27:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 105 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-03-2021 13:27:57 to 16-03-2021 13:27:59.

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          226300
MON$OLDEST_ACTIVE               226301
MON$OLDEST_SNAPSHOT             226301
MON$NEXT_TRANSACTION            226301
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                       2078048
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 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.2387 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 ---
--- end of diff output ---

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

16.03.21 13:28:00. 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-03-16 13:28:03 No severe PSQL-related problems occured
16.03.21 13:28:04. End of report.