Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2021 13:28
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 16729968 kB
MemAvailable 31202100 kB
Buffers 0 kB
Cached 14983796 kB
SwapCached 4772 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-14 13:26:57
DTS_END                         2021-02-14 13:26:57.9710
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/50730
TRN_ID                          225979
ATT_ID                          679
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: 8714.80 30379 1045776
customer order: creation 154.16 424 18499
customer order: refuse 14.10 378 1692
order to supplier: creation 41.85 1457 5022
order to supplier: removal 3.31 145197 397
invoice (draft): creation 41.98 4196 5038
invoice (draft): removal 48.58 1289 5829
invoice accept: apply 39.88 21999 4786
invoice accept: cancel 43.92 1083 5270
customer reserve: creation 3739.65 212 448758
customer reserve: removal 4321.88 85 518625
realization accept: apply 76.59 184 9191
realization accept: cancel 69.50 153 8340
payment from customer: creation 55.01 107 6601
payment from customer: removal 5.53 113 664
payment to supplier: creation 45.26 120 5431
payment to supplier: removal 3.19 93 383
service: total inventory turnovers 3.83 3733 460
service: total monetary turnovers 5.78 580 694
service: aggregate perf. data 0.76 18214 91
service: refresh index statistics 0.04 407955 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-02-2021 13:28:33 to 14-02-2021 13:28:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 355
TEST_TIME 2 1038
TEST_TIME 3 1642
TEST_TIME 4 2801
TEST_TIME 5 3251
TEST_TIME 6 3741
TEST_TIME 7 4264
TEST_TIME 8 4963
TEST_TIME 9 4913
TEST_TIME 10 4978
TEST_TIME 11 5275
TEST_TIME 12 5685
TEST_TIME 13 5911
TEST_TIME 14 6022
TEST_TIME 15 6053
TEST_TIME 16 6181
TEST_TIME 17 6356
TEST_TIME 18 6387
TEST_TIME 19 6735
TEST_TIME 20 7280
TEST_TIME 21 7311
TEST_TIME 22 7228
TEST_TIME 23 7364
TEST_TIME 24 7654
TEST_TIME 25 7950
TEST_TIME 26 8007
TEST_TIME 27 8073
TEST_TIME 28 8298
TEST_TIME 29 8528
TEST_TIME 30 8823
TEST_TIME 31 10082
TEST_TIME 32 10619
TEST_TIME 33 10305
TEST_TIME 34 10212
TEST_TIME 35 10040
TEST_TIME 36 10052
TEST_TIME 37 9864
TEST_TIME 38 9841
TEST_TIME 39 9742
TEST_TIME 40 9620
TEST_TIME 41 9478
TEST_TIME 42 9475
TEST_TIME 43 9451
TEST_TIME 44 9277
TEST_TIME 45 9139
TEST_TIME 46 9181
TEST_TIME 47 9100
TEST_TIME 48 9079
TEST_TIME 49 9026
TEST_TIME 50 9056
TEST_TIME 51 9095
TEST_TIME 52 8989
TEST_TIME 53 8973
TEST_TIME 54 9223
TEST_TIME 55 9160
TEST_TIME 56 9109
TEST_TIME 57 9187
TEST_TIME 58 9318
TEST_TIME 59 9413
TEST_TIME 60 9387
TEST_TIME 61 9387
TEST_TIME 62 9346
TEST_TIME 63 9206
TEST_TIME 64 9156
TEST_TIME 65 9084
TEST_TIME 66 9036
TEST_TIME 67 8951
TEST_TIME 68 8937
TEST_TIME 69 8907
TEST_TIME 70 8934
TEST_TIME 71 9025
TEST_TIME 72 8999
TEST_TIME 73 9001
TEST_TIME 74 8977
TEST_TIME 75 8988
TEST_TIME 76 8971
TEST_TIME 77 8993
TEST_TIME 78 8986
TEST_TIME 79 9036
TEST_TIME 80 9053
TEST_TIME 81 8968
TEST_TIME 82 8881
TEST_TIME 83 8928
TEST_TIME 84 8858
TEST_TIME 85 8838
TEST_TIME 86 8817
TEST_TIME 87 8802
TEST_TIME 88 8810
TEST_TIME 89 8800
TEST_TIME 90 8805
TEST_TIME 91 8784
TEST_TIME 92 8765
TEST_TIME 93 8890
TEST_TIME 94 9019
TEST_TIME 95 9049
TEST_TIME 96 9051
TEST_TIME 97 9012
TEST_TIME 98 8989
TEST_TIME 99 8922
TEST_TIME 100 8894
TEST_TIME 101 8856
TEST_TIME 102 8817
TEST_TIME 103 8789
TEST_TIME 104 8786
TEST_TIME 105 8763
TEST_TIME 106 8736
TEST_TIME 107 8707
TEST_TIME 108 8730
TEST_TIME 109 8854
TEST_TIME 110 8891
TEST_TIME 111 8882
TEST_TIME 112 8939
TEST_TIME 113 8911
TEST_TIME 114 8903
TEST_TIME 115 8847
TEST_TIME 116 8818
TEST_TIME 117 8758
TEST_TIME 118 8761
TEST_TIME 119 8738
TEST_TIME 120 8718

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-02-2021 13:28:36 to 14-02-2021 13:28:40.

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 1456878 1456878 0 0.00 0 28403 8 0 0
doc_list_biud 1456877 1456877 0 0.00 0 29185 39 0 0
sp_add_invoice_to_stock 4903 4786 117 2.38 33 184175 21999 0 117
sp_cancel_adding_invoice 5270 5270 0 0.00 4 88467 1083 0 0
sp_cancel_client_order 1692 1692 0 0.00 5 11944 378 0 0
sp_cancel_customer_reserve 518625 518625 0 0.00 1 25704 86 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 11556 114 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 8696 93 0 0
sp_cancel_supplier_invoice 5836 5829 7 0.11 2 25574 1290 0 7
sp_cancel_supplier_order 397 397 0 0.00 7 1247041 145198 0 0
sp_cancel_write_off 8340 8340 0 0.00 1 10045 153 0 0
sp_client_order 18499 18499 0 0.00 4 66059 424 0 0
sp_customer_reserve 448758 448758 0 0.00 0 103133 212 0 0
sp_fill_shopping_cart 28554 28554 0 0.00 1 20455 48 0 0
sp_fill_shopping_cart_clo_res 448755 448755 0 0.00 0 26211 21 0 0
sp_get_clo_for_invoice 4776 4776 0 0.00 0 50604 465 0 0
sp_kill_cost_storno 14657 14657 0 0.00 0 12451 78 0 0
sp_kill_qstorno_ret_qs2qd 1051816 1051816 0 0.00 0 25698 47 0 0
sp_kill_qty_storno 554118 554118 0 0.00 0 29184 100 0 0
sp_lock_selected_doc 19424 19424 0 0.00 0 11691 6 0 0
sp_make_cost_storno 25998 25998 0 0.00 0 22046 61 0 0
sp_multiply_rows_for_pdistr 25998 25998 0 0.00 0 7826 34 0 0
sp_multiply_rows_for_qdistr 18499 18499 0 0.00 0 62785 242 0 0
sp_pay_from_customer 6601 6601 0 0.00 4 11287 108 0 0
sp_pay_to_supplier 5431 5431 0 0.00 5 12026 120 0 0
sp_payment_common 12032 12032 0 0.00 2 10308 21 0 0
sp_qd_handle_on_cancel_clo 1692 1692 0 0.00 0 11925 355 0 0
sp_qd_handle_on_invoice_upd_sts 10045 10045 0 0.00 0 29183 481 0 0
sp_qd_handle_on_reserve_upd_sts 9190 9190 0 0.00 0 8497 48 0 0
sp_reserve_write_off 9191 9191 0 0.00 7 22057 185 0 0
sp_supplier_invoice 5038 5038 0 0.00 7 107034 4197 0 0
sp_supplier_order 5022 5022 0 0.00 6 37725 1458 0 0
srv_aggregate_perf_data 92 91 1 1.08 2548 251579 18215 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1192 134144 41843 0 0
srv_find_qd_qs_mism 998961 998961 0 0.00 0 28402 7 0 0
srv_make_invnt_saldo 460 460 0 0.00 45 124778 3734 0 0
srv_make_money_saldo 694 694 0 0.00 1 11231 581 0 0
srv_recalc_idx_stat 5 5 0 0.00 171242 685157 407955 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4786 4786 0 0.00 0 23780 248 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 5284 104 0 0
t$perf-norm:sp_cancel_client_order 1692 1692 0 0.00 0 2442 15 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 763 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 8775 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 1942 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 726 18 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 24875 1859 0 0
t$perf-norm:sp_cancel_write_off 908 908 0 0.00 0 7580 20 0 0
t$perf-norm:sp_client_order 18499 18499 0 0.00 0 30061 25 0 0
t$perf-norm:sp_customer_reserve 926 926 0 0.00 0 421 4 0 0
t$perf-norm:sp_pay_from_customer 6601 6601 0 0.00 0 7359 10 0 0
t$perf-norm:sp_pay_to_supplier 5431 5431 0 0.00 0 24027 11 0 0
t$perf-norm:sp_reserve_write_off 9191 9191 0 0.00 0 24173 16 0 0
t$perf-norm:sp_supplier_invoice 5038 5038 0 0.00 0 12956 23 0 0
t$perf-norm:sp_supplier_order 5022 5022 0 0.00 0 5598 20 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 438 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 166 5 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 4031 29 0 0
t$perf-norm:srv_make_money_saldo 694 694 0 0.00 0 806 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 15 5 0 0
v_add_invoice_to_stock 9784 9550 234 2.39 0 9668 17 0 234
v_all_customers 34068 34068 0 0.00 0 99 1 0 0
v_all_suppliers 28018 28018 0 0.00 0 37 1 0 0
v_all_wares 36998 36998 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1301 8 0 0
v_cancel_client_order 3384 3384 0 0.00 0 647 4 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 4260 13 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 656 4 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 3664 10 0 0
v_cancel_supplier_invoice 784 770 14 1.78 0 2109 19 0 14
v_cancel_supplier_order 794 794 0 0.00 0 1020 12 0 0
v_cancel_write_off 1816 1816 0 0.00 0 7861 20 0 0
v_max_id_clo_ord 5019 5019 0 0.00 0 209 1 0 0
v_max_id_clo_res 926 926 0 0.00 0 1332 10 0 0
v_max_id_ord_sup 5036 5036 0 0.00 0 11750 11 0 0
v_max_non_paid_invoice 1474 1474 0 0.00 0 88 2 0 0
v_max_non_paid_realizn 3379 3379 0 0.00 0 2265 5 0 0
v_min_id_clo_ord 5019 5019 0 0.00 0 2568 6 0 0
v_min_id_clo_res 926 926 0 0.00 0 3441 11 0 0
v_min_id_ord_sup 5036 5036 0 0.00 0 7992 15 0 0
v_min_non_paid_invoice 5431 5431 0 0.00 0 5721 11 0 0
v_min_non_paid_realizn 6601 6601 0 0.00 0 10303 15 0 0
v_our_firm 9374 9374 0 0.00 0 32 1 0 0
v_reserve_write_off 18380 18380 0 0.00 0 4207 5 0 0
x_lock_depdocs_on_canc_invoice 10714 10714 0 0.00 0 14959 155 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3045 364 0 0
x_make_qstorno_1000_1200 5022 5022 0 0.00 2 37714 1371 0 0
x_make_qstorno_1200_2000 5038 5038 0 0.00 4 106992 4012 0 0
x_make_qstorno_2100_3300 448758 448758 0 0.00 0 103131 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-02-2021 13:28:41 to 14-02-2021 13:28:45.

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:57:01 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:03 34507 8192000000 9725295280 10021498880 799795776 8.894 100 83 165 0 829555184 10235584 19523824
10:59:40 26897 8192000000 9769703696 10076389376 842878640 9.329 100 87 174 0 876308512 12644800 20785072
11:01:11 16815 8192000000 9805568896 10121424896 865530512 9.555 100 80 160 0 913470688 29751984 18188192
11:02:47 15861 8192000000 9875288112 10212331520 924969616 10.145 100 81 162 0 983161456 40662880 17528960
11:04:07 8334 8192000000 9921632496 10266279936 967238128 10.560 100 80 160 0 1030954336 46747136 16969072
11:05:17 3167 8192000000 9969937408 10321682432 996727152 10.847 100 81 163 2 1081464496 67862816 16874528
11:06:23 3155 8192000000 9995537904 10361024512 1027679712 11.146 100 90 175 1 1115795296 69458896 18656688
11:07:28 4892 8192000000 10016723328 10384265216 1040800608 11.272 100 80 158 0 1131319264 75059024 15459632
11:08:36 1851 8192000000 10027243776 10398146560 1061051440 11.467 100 83 166 2 1128330528 50481312 16797776
11:09:38 1619 8192000000 10043169504 10416984064 1073203136 11.583 100 86 169 4 1146503584 56354992 16945456
11:10:41 4086 8192000000 10009878400 10393845760 1077120976 11.620 100 46 92 2 1113946880 27295408 9530496
11:11:47 6701 8192000000 10027183584 10423160832 1083040896 11.676 100 46 93 0 1139543472 47517168 8985408
11:12:55 37198 8192000000 9996890960 10401296384 1093379312 11.775 100 52 104 3 1118942784 13973200 11590272
11:14:33 6579 8192000000 10021232320 10416795648 1101019696 11.847 100 38 76 2 1140949776 31671024 8259056
11:15:42 15479 8192000000 10021735120 10443407360 1106723632 11.901 100 32 64 1 1138283088 24237136 7322320
11:16:58 9995 8192000000 10012634688 10413838336 1088873616 11.732 99 20 39 0 1119722640 25981536 4867488
11:18:09 27050 8192000000 10002080928 10408177664 1067104768 11.524 97 22 44 0 1104568192 32723392 4740032
11:19:38 7575 8192000000 9975420816 10380255232 1066163760 11.515 99 36 72 2 1092118336 17984016 7970560
11:20:49 6375 8192000000 9925606448 10315837440 998199584 10.861 97 27 55 0 1013443840 9267600 5976656
11:21:56 34957 8192000000 9924238448 10320650240 989661216 10.778 96 35 68 1 1028125984 30785312 7679456
11:24:05 9770 8192000000 9951531072 10344275968 1029746416 11.166 99 25 50 3 1054870832 19423552 5700864
11:25:17 3746 8192000000 9974085328 10365284352 1056845696 11.426 100 56 113 0 1088311680 19302640 12163344
11:26:24 5893 8192000000 9978888256 10376667136 1067438448 11.528 100 46 92 1 1091510432 13746624 10325360
11:27:30 76593 8192000000 10019415984 10415837184 1074999152 11.600 100 50 100 1 1111778496 25975952 10803392
11:30:03 19076 8192000000 10042695600 10456748032 1090661456 11.749 100 37 74 1 1152628688 53656784 8310448
11:31:28 12483 8192000000 10095505792 10510938112 1106635040 11.901 100 54 107 1 1204420592 86396256 11389296
11:32:47 5184 8192000000 10142749424 10544730112 1117332752 12.002 100 62 125 1 1246076672 114838208 13905712
11:33:58 9454 8192000000 10163341328 10567147520 1130119200 12.122 100 62 123 1 1275516912 132170192 13227520
11:35:11 3390 8192000000 10176290688 10578067456 1143220048 12.246 100 71 142 1 1285620112 126997440 15402624
11:36:18 12617 8192000000 10232819568 10644287488 1148926384 12.299 100 57 115 0 1335859472 173999024 12934064
11:37:36 3326 8192000000 10234013904 10646642688 1162615296 12.428 100 71 142 0 1336403936 156824608 16964032
11:38:43 11040 8192000000 10261752544 10687819776 1175891344 12.552 100 59 115 2 1359962032 171561584 12509104
11:40:02 1673 8192000000 10281936736 10686365696 1174047472 12.535 99 94 186 0 1381553792 187906736 19599584
11:41:08 3467 8192000000 10318499104 10728202240 1186759328 12.653 100 82 161 3 1418222592 214214928 17248336
11:42:18 2847 8192000000 10379678736 10788048896 1199616928 12.773 100 92 184 0 1481922960 261432416 20873616
11:43:26 2440 8192000000 10394390048 10798739456 1173512752 12.530 100 90 179 1 1490570864 296049520 21008592
11:44:34 1188 8192000000 10409677760 10821226496 1163966464 12.440 100 80 160 0 1506437120 323103728 19366928
11:45:41 3131 8192000000 10394070176 10847293440 1166871808 12.468 100 76 150 1 1510135792 325490608 17773376
11:46:50 2268 8192000000 10436813920 10854785024 1170105120 12.498 100 94 188 0 1532789216 340961808 21722288
11:47:59 3394 8192000000 10446270496 10851053568 1169633728 12.493 100 77 154 2 1543966448 355689872 18642848
11:49:05 7734 8192000000 10497597248 10904989696 1171867312 12.514 100 77 154 1 1597573728 406795280 18911136
11:50:17 2783 8192000000 10494622768 10900029440 1184650336 12.634 99 88 176 1 1586717168 381164800 20902032
11:51:22 9325 8192000000 10508563488 10959032320 1189219808 12.676 100 51 101 1 1600565824 398641872 12704144
11:52:36 8142 8192000000 10520921600 10975363072 1155579472 12.362 99 40 80 1 1625254512 458715072 10959968
11:53:47 10374 8192000000 10533259856 11013062656 1159534784 12.399 100 39 78 0 1646130784 476047088 10548912
11:55:15 19907 8192000000 10519357840 10981056512 1139273792 12.209 100 38 76 0 1625090944 475257168 10559984
11:56:48 17663 8192000000 10447143984 10886860800 1095424528 11.794 98 27 54 0 1530647392 427521504 7701360
11:58:09 16288 8192000000 10068513776 10541518848 1118885840 12.016 99 14 28 1 1180315360 58479216 2950304
11:59:29 42186 8192000000 10073319456 10501779456 1132276208 12.143 100 60 121 0 1164785312 19326288 13182816
12:01:14 54241 8192000000 10079690928 10520666112 1127297104 12.096 100 46 93 1 1168402704 31285504 9820096
12:03:16 44064 8192000000 10067238784 10524213248 1108153856 11.915 99 14 27 1 1158540736 47231600 3155280
12:05:00 56667 8192000000 10039328352 10488823808 1117905680 12.007 100 18 38 0 1137370576 15577744 3887152
12:06:58 60849 8192000000 10115097408 10554568704 1109916416 11.932 97 25 50 1 1186298576 70143408 6238752
12:09:06 48979 8192000000 10106332240 10564751360 1140584928 12.221 100 24 49 1 1224463104 78907120 4971056
12:10:56 92785 8192000000 10065933376 10567393280 1123121632 12.056 99 23 45 0 1215522384 87448608 4952144
12:13:37 38772 8192000000 10088989184 10556366848 1132463088 12.145 100 8 16 0 1193943184 59684480 1795616
12:15:24 48343 8192000000 10071969936 10550665216 1131658912 12.137 99 20 39 2 1193660560 57716240 4285408
12:17:30 69730 8192000000 10146901280 10595483648 1153066256 12.338 100 14 28 0 1214503616 58493584 2943776
12:20:03 48673 8192000000 10130418976 10599522304 1141959024 12.234 99 16 32 1 1254103872 108194784 3950064
12:22:21 23595 8192000000 10131075984 10566381568 1154570480 12.352 100 35 70 1 1220716320 59302800 6843040
12:23:47 55456 8192000000 10147732272 10621296640 1109571744 11.928 96 21 41 2 1189415648 75773344 4070560
12:25:49 55457 8192000000 10097813088 10524897280 1124859600 12.073 98 39 78 0 1177091264 43364960 8866704
12:27:49 132525 8192000000 10066065984 10542514176 1120820192 12.035 98 21 42 0 1156615280 31489904 4305184
12:31:02 69862 8192000000 10105263776 10566033408 1131212320 12.133 99 16 32 0 1207228128 72248960 3766848
12:33:13 59325 8192000000 10061078272 10539094016 1112694176 11.958 98 7 14 0 1181767056 67447040 1625840
12:35:25 55179 8192000000 10098507712 10574184448 1127617888 12.099 99 18 36 0 1209472416 77826752 4027776
12:37:51 114716 8192000000 10067631440 10520580096 1021909728 11.090 90 26 50 1 1055730416 28704192 5116496
12:40:54 34268 8192000000 10051412656 10478927872 1079931344 11.647 96 28 56 0 1117042656 31326640 5784672
12:42:30 18885 8192000000 10090914688 10515116032 1088424304 11.728 98 52 104 0 1162066000 62758224 10883472
12:43:52 51537 8192000000 10069331232 10493693952 1071822016 11.569 96 25 50 1 1134675760 57936336 4917408
12:45:48 58489 8192000000 10078448080 10527395840 1080357040 11.651 96 29 58 0 1143035408 57244064 5434304
12:47:48 119133 8192000000 10027229520 10499301376 1072931408 11.580 96 24 48 1 1124346672 46471328 4943936
12:50:48 27663 8192000000 10036713280 10491969536 1114968272 11.979 100 7 14 0 1139066240 22939136 1158832
12:52:17 74362 8192000000 10074904160 10513563648 1093737552 11.778 97 14 28 1 1159893104 62367408 3788144
12:54:33 35536 8192000000 10102706048 10551902208 1116241328 11.991 98 33 65 0 1164358128 40852944 7263856
12:56:14 48259 8192000000 10137206720 10609201152 1119910224 12.026 99 24 48 0 1232366992 106928992 5527776
12:58:04 77681 8192000000 10179814624 10684112896 1108891936 11.922 98 21 42 0 1284131136 170003872 5235328
13:00:25 59865 8192000000 10103410976 10605150208 1130326720 12.124 100 23 46 2 1206157040 71398560 4431760
13:02:25 58410 8192000000 10053571600 10505863168 1092707648 11.768 96 16 32 1 1144678704 48575312 3395744
13:04:24 134144 8192000000 10076751184 10536779776 1118621392 12.014 99 35 70 0 1163338656 36820976 7896288
13:08:02 19604 8192000000 10070898064 10539241472 1137248368 12.190 100 5 10 2 1179024256 40833488 942400
13:09:22 61007 8192000000 10100718608 10577686528 1135052896 12.169 99 15 30 2 1189816160 51684752 3078512
13:11:37 75718 8192000000 10159530976 10608087040 1140674608 12.222 98 27 54 1 1229111136 82668976 5767552
13:14:05 41747 8192000000 10214713392 10678333440 1145221648 12.265 99 36 70 2 1278245632 125037600 7986384
13:15:48 77435 8192000000 10083596944 10578341888 1119677424 12.024 98 9 18 0 1185467904 63636352 2154128
13:18:05 53567 8192000000 10069664288 10568744960 1105966400 11.894 97 16 32 3 1176689200 67393872 3328928
13:20:10 25072 8192000000 10100327952 10531393536 1129546064 12.117 99 38 76 1 1172042160 35544080 6952016
13:21:35 67762 8192000000 10086524256 10550722560 1140971280 12.225 100 20 40 1 1193552064 48555360 4025424
13:23:44 90714 8192000000 10059618480 10544480256 1105909968 11.894 97 6 12 0 1162203904 54982624 1311312
13:26:15 55264 8192000000 9131123968 9406930944 280563648 3.311 25 1 2 0 291259600 10660544 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-02-2021 13:28:47 to 14-02-2021 13:29:02.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-02-2021 13:29:03 to 14-02-2021 13:29:05.

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          226389
MON$OLDEST_ACTIVE               226390
MON$OLDEST_SNAPSHOT             226390
MON$NEXT_TRANSACTION            226390
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                       2077344
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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'

14.02.21 13:29:06. 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-14 13:29:08 No severe PSQL-related problems occured
14.02.21 13:29:10. End of report.