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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16920372 kB
MemAvailable 31358784 kB
Buffers 0 kB
Cached 14638188 kB
SwapCached 3700 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 2020-12-23 10:26:54
DTS_END                         2020-12-23 10:26:54.9150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49280
TRN_ID                          232248
ATT_ID                          602
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: 8590.76 23351 1030891
customer order: creation 164.19 401 19703
customer order: refuse 14.22 325 1706
order to supplier: creation 43.06 1392 5167
order to supplier: removal 3.31 107721 397
invoice (draft): creation 44.08 3721 5290
invoice (draft): removal 48.48 856 5818
invoice accept: apply 40.02 16853 4802
invoice accept: cancel 43.18 875 5182
customer reserve: creation 3681.87 165 441824
customer reserve: removal 4227.26 68 507271
realization accept: apply 80.78 186 9693
realization accept: cancel 77.38 115 9286
payment from customer: creation 57.59 127 6911
payment from customer: removal 5.71 76 685
payment to supplier: creation 46.58 143 5589
payment to supplier: removal 3.16 78 379
service: total inventory turnovers 3.65 3156 438
service: total monetary turnovers 5.41 500 649
service: aggregate perf. data 0.81 18939 97
service: refresh index statistics 0.03 311317 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2020 10:28:04 to 23-12-2020 10:28:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3313
TEST_TIME 2 3114
TEST_TIME 3 3425
TEST_TIME 4 3904
TEST_TIME 5 4453
TEST_TIME 6 4848
TEST_TIME 7 4843
TEST_TIME 8 5049
TEST_TIME 9 5407
TEST_TIME 10 5615
TEST_TIME 11 6095
TEST_TIME 12 6422
TEST_TIME 13 6726
TEST_TIME 14 6810
TEST_TIME 15 7373
TEST_TIME 16 7506
TEST_TIME 17 7718
TEST_TIME 18 7777
TEST_TIME 19 8237
TEST_TIME 20 9009
TEST_TIME 21 9189
TEST_TIME 22 9359
TEST_TIME 23 9789
TEST_TIME 24 10666
TEST_TIME 25 10437
TEST_TIME 26 10346
TEST_TIME 27 10230
TEST_TIME 28 10094
TEST_TIME 29 9936
TEST_TIME 30 9931
TEST_TIME 31 9690
TEST_TIME 32 9613
TEST_TIME 33 9434
TEST_TIME 34 9489
TEST_TIME 35 9383
TEST_TIME 36 9244
TEST_TIME 37 9315
TEST_TIME 38 9312
TEST_TIME 39 9133
TEST_TIME 40 9124
TEST_TIME 41 9137
TEST_TIME 42 9107
TEST_TIME 43 9355
TEST_TIME 44 9412
TEST_TIME 45 9410
TEST_TIME 46 9481
TEST_TIME 47 9461
TEST_TIME 48 9476
TEST_TIME 49 9531
TEST_TIME 50 9522
TEST_TIME 51 9500
TEST_TIME 52 9355
TEST_TIME 53 9215
TEST_TIME 54 9156
TEST_TIME 55 9078
TEST_TIME 56 8993
TEST_TIME 57 8964
TEST_TIME 58 8967
TEST_TIME 59 8876
TEST_TIME 60 8812
TEST_TIME 61 8799
TEST_TIME 62 8823
TEST_TIME 63 8820
TEST_TIME 64 8822
TEST_TIME 65 9149
TEST_TIME 66 9140
TEST_TIME 67 9137
TEST_TIME 68 9114
TEST_TIME 69 9140
TEST_TIME 70 9191
TEST_TIME 71 9151
TEST_TIME 72 9183
TEST_TIME 73 9190
TEST_TIME 74 9079
TEST_TIME 75 8982
TEST_TIME 76 9034
TEST_TIME 77 8961
TEST_TIME 78 8934
TEST_TIME 79 8922
TEST_TIME 80 8876
TEST_TIME 81 8849
TEST_TIME 82 8840
TEST_TIME 83 8832
TEST_TIME 84 8894
TEST_TIME 85 8940
TEST_TIME 86 8894
TEST_TIME 87 8969
TEST_TIME 88 9001
TEST_TIME 89 8953
TEST_TIME 90 9024
TEST_TIME 91 8996
TEST_TIME 92 8903
TEST_TIME 93 8866
TEST_TIME 94 8847
TEST_TIME 95 8801
TEST_TIME 96 8791
TEST_TIME 97 8777
TEST_TIME 98 8713
TEST_TIME 99 8692
TEST_TIME 100 8636
TEST_TIME 101 8686
TEST_TIME 102 8664
TEST_TIME 103 8671
TEST_TIME 104 8693
TEST_TIME 105 8705
TEST_TIME 106 8695
TEST_TIME 107 8740
TEST_TIME 108 8709
TEST_TIME 109 8701
TEST_TIME 110 8762
TEST_TIME 111 8774
TEST_TIME 112 8791
TEST_TIME 113 8734
TEST_TIME 114 8665
TEST_TIME 115 8651
TEST_TIME 116 8612
TEST_TIME 117 8600
TEST_TIME 118 8582
TEST_TIME 119 8609
TEST_TIME 120 8594

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-12-2020 10:28:07 to 23-12-2020 10:28:11.

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 1439800 1439800 0 0.00 0 26877 7 0 0
doc_list_biud 1439799 1439799 0 0.00 0 63760 30 0 0
sp_add_invoice_to_stock 4950 4802 148 2.98 34 176946 16853 0 148
sp_cancel_adding_invoice 5182 5182 0 0.00 5 58958 875 0 0
sp_cancel_client_order 1706 1706 0 0.00 4 10180 326 0 0
sp_cancel_customer_reserve 507271 507271 0 0.00 1 63761 68 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 2364 76 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 3986 78 0 0
sp_cancel_supplier_invoice 5831 5818 13 0.22 2 20341 856 0 13
sp_cancel_supplier_order 397 397 0 0.00 6 1064005 107722 0 0
sp_cancel_write_off 9286 9286 0 0.00 1 17081 116 0 0
sp_client_order 19703 19703 0 0.00 4 34320 401 0 0
sp_customer_reserve 441824 441824 0 0.00 0 45595 165 0 0
sp_fill_shopping_cart 30150 30150 0 0.00 1 27279 51 0 0
sp_fill_shopping_cart_clo_res 441818 441818 0 0.00 0 21564 18 0 0
sp_get_clo_for_invoice 4796 4796 0 0.00 0 56276 493 0 0
sp_kill_cost_storno 15532 15532 0 0.00 0 17069 56 0 0
sp_kill_qstorno_ret_qs2qd 1030043 1030043 0 0.00 0 63752 37 0 0
sp_kill_qty_storno 544149 544149 0 0.00 0 63759 79 0 0
sp_lock_selected_doc 20033 20033 0 0.00 0 7002 4 0 0
sp_make_cost_storno 26989 26989 0 0.00 0 24648 62 0 0
sp_multiply_rows_for_pdistr 26989 26989 0 0.00 0 26497 36 0 0
sp_multiply_rows_for_qdistr 19703 19703 0 0.00 0 34313 241 0 0
sp_pay_from_customer 6911 6911 0 0.00 4 23605 127 0 0
sp_pay_to_supplier 5589 5589 0 0.00 5 24127 144 0 0
sp_payment_common 12499 12499 0 0.00 1 22066 39 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 10174 292 0 0
sp_qd_handle_on_invoice_upd_sts 9978 9978 0 0.00 0 31053 373 0 0
sp_qd_handle_on_reserve_upd_sts 9693 9693 0 0.00 0 9320 50 0 0
sp_reserve_write_off 9693 9693 0 0.00 7 29275 187 0 0
sp_supplier_invoice 5290 5290 0 0.00 6 95054 3722 0 0
sp_supplier_order 5167 5167 0 0.00 4 62485 1392 0 0
srv_aggregate_perf_data 97 97 0 0.00 2665 513324 18940 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1644 174421 48539 0 0
srv_find_qd_qs_mism 985196 985196 0 0.00 0 14743 6 0 0
srv_make_invnt_saldo 438 438 0 0.00 66 93388 3156 0 0
srv_make_money_saldo 649 649 0 0.00 1 11526 501 0 0
srv_recalc_idx_stat 4 4 0 0.00 121800 438175 311318 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 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-norm:sp_add_invoice_to_stock 4803 4803 0 0.00 0 10664 145 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 6680 100 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 5275 13 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 1664 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1379 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 5628 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1480 18 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 15956 1204 0 0
t$perf-norm:sp_cancel_write_off 906 906 0 0.00 0 4787 14 0 0
t$perf-norm:sp_client_order 19703 19703 0 0.00 0 5535 16 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 4613 10 0 0
t$perf-norm:sp_pay_from_customer 6911 6911 0 0.00 0 7200 8 0 0
t$perf-norm:sp_pay_to_supplier 5589 5589 0 0.00 0 6938 10 0 0
t$perf-norm:sp_reserve_write_off 9693 9693 0 0.00 0 6456 7 0 0
t$perf-norm:sp_supplier_invoice 5290 5290 0 0.00 0 5059 15 0 0
t$perf-norm:sp_supplier_order 5167 5167 0 0.00 0 4125 14 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 452 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 957 8 0 0
t$perf-norm:srv_make_money_saldo 649 649 0 0.00 0 5424 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9886 9590 296 2.99 0 7364 16 0 296
v_all_customers 35906 35906 0 0.00 0 18 1 0 0
v_all_suppliers 28888 28888 0 0.00 0 73 1 0 0
v_all_wares 39404 39404 0 0.00 0 49 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 6113 17 0 0
v_cancel_client_order 3412 3412 0 0.00 0 3277 7 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 1046 4 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 75 3 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 3981 13 0 0
v_cancel_supplier_invoice 856 830 26 3.03 0 2259 18 0 26
v_cancel_supplier_order 794 794 0 0.00 0 798 6 0 0
v_cancel_write_off 1812 1812 0 0.00 0 1326 12 0 0
v_max_id_clo_ord 5164 5164 0 0.00 0 874 1 0 0
v_max_id_clo_res 939 939 0 0.00 0 1841 6 0 0
v_max_id_ord_sup 5284 5284 0 0.00 0 4789 9 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 584 2 0 0
v_max_non_paid_realizn 3722 3722 0 0.00 0 1425 3 0 0
v_min_id_clo_ord 5164 5164 0 0.00 0 6251 10 0 0
v_min_id_clo_res 939 939 0 0.00 0 5384 28 0 0
v_min_id_ord_sup 5284 5284 0 0.00 0 9455 17 0 0
v_min_non_paid_invoice 5588 5588 0 0.00 0 21966 30 0 0
v_min_non_paid_realizn 6911 6911 0 0.00 0 22059 36 0 0
v_our_firm 9876 9876 0 0.00 0 29 1 0 0
v_reserve_write_off 19386 19386 0 0.00 0 5908 5 0 0
x_lock_depdocs_on_canc_invoice 10585 10585 0 0.00 0 17595 111 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 7399 316 0 0
x_make_qstorno_1000_1200 5167 5167 0 0.00 1 62471 1316 0 0
x_make_qstorno_1200_2000 5290 5290 0 0.00 3 94984 3516 0 0
x_make_qstorno_2100_3300 441824 441824 0 0.00 0 38773 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2020 10:28:12 to 23-12-2020 10:28:16.

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
07:56:58 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:57:59 34224 8192000000 9730474176 10026536960 800952096 8.906 100 82 164 1 828844048 8051984 19839968
07:59:40 25537 8192000000 9762383424 10069340160 838779696 9.288 100 87 174 0 873003632 13526592 20697344
08:01:07 18032 8192000000 9791295440 10108702720 862763152 9.528 100 81 162 1 902506496 21167344 18576000
08:02:30 15422 8192000000 9874462192 10204061696 911855984 10.016 100 81 162 2 974844416 45657072 17331360
08:03:50 5497 8192000000 9918926336 10262290432 961991776 10.508 100 78 154 3 1031547648 53296720 16259152
08:04:57 3856 8192000000 9975028720 10329952256 994642352 10.827 100 80 156 2 1082878288 71834224 16401712
08:06:02 1944 8192000000 10002378992 10363543552 1023099472 11.102 100 82 162 2 1118931392 79457760 16374160
08:07:06 1645 8192000000 10025805584 10389807104 1053650880 11.396 100 93 186 0 1131568912 58922144 18995888
08:08:09 24329 8192000000 10035964176 10412392448 1065893840 11.513 100 65 130 1 1138257920 58475152 13888928
08:09:42 3228 8192000000 10000930256 10385461248 1074229168 11.592 100 63 130 0 1115222528 27855200 13138160
08:10:47 10416 8192000000 10002621696 10395312128 1080757712 11.655 100 33 66 2 1111027440 22880544 7389184
08:11:59 12229 8192000000 10017464064 10425761792 1086093520 11.705 100 27 54 3 1118680352 27077056 5509776
08:13:12 24267 8192000000 10004704688 10413027328 1087996432 11.724 100 20 40 0 1120288352 28149072 4142848
08:14:38 5889 8192000000 10017330288 10411532288 1093936752 11.780 100 46 93 1 1125501696 20535184 11029760
08:15:45 9609 8192000000 10014791776 10424639488 1083898048 11.685 99 32 64 0 1127892800 37481744 6513008
08:16:55 59650 8192000000 9982993824 10396188672 1086493152 11.709 100 31 62 1 1127861040 34652512 6715376
08:18:55 10513 8192000000 9965951664 10365480960 1046352352 11.326 98 23 46 0 1068215840 16592064 5271424
08:20:08 7531 8192000000 9915366480 10291326976 956256352 10.452 94 39 78 2 983479488 18508768 8714368
08:21:16 7141 8192000000 9935272096 10318172160 1003802128 10.915 100 36 73 4 1045493536 33982848 7708560
08:22:24 51274 8192000000 9939688928 10333466624 1013877984 11.013 98 31 61 3 1035995152 14942704 7174464
08:24:15 6670 8192000000 9970617072 10369564672 1032194864 11.190 99 38 76 1 1072327360 31745312 8387184
08:25:25 5844 8192000000 9965315456 10358554624 1050914064 11.369 100 44 87 2 1075011744 15339360 8758320
08:26:33 8301 8192000000 10003869616 10406334464 1075816096 11.608 100 28 56 3 1094462928 12859040 5787792
08:27:48 111031 8192000000 10040478400 10449108992 1097883792 11.818 100 32 64 2 1146662000 42010496 6767712
08:30:45 9496 8192000000 10084159360 10485723136 1118983568 12.017 100 49 97 1 1190465920 61547968 9934384
08:32:13 11115 8192000000 10099791856 10505232384 1138703408 12.203 100 42 84 1 1207406112 59247952 9454752
08:33:28 11432 8192000000 10164508128 10565763072 1150388144 12.313 100 60 120 2 1266755856 103072560 13295152
08:34:42 5944 8192000000 10215027984 10625003520 1164915072 12.449 100 62 124 0 1320324176 141981936 13427168
08:35:53 4518 8192000000 10249881424 10654138368 1177365056 12.566 100 66 130 3 1350308560 159110016 13833488
08:37:00 2096 8192000000 10308607632 10710892544 1180594336 12.596 100 83 165 0 1406904032 208876704 17432992
08:38:07 2098 8192000000 10340639008 10748583936 1181632016 12.605 100 76 152 0 1440164512 241050080 17482416
08:39:15 1641 8192000000 10324614768 10732584960 1158500816 12.389 99 78 156 1 1419534320 243284672 17748832
08:40:22 3302 8192000000 10331697584 10748854272 1168469792 12.483 100 54 108 1 1439611632 258805312 12336528
08:41:30 1878 8192000000 10331103856 10732912640 1142406624 12.238 99 62 123 0 1427586592 270814640 14365328
08:42:39 10760 8192000000 10338327584 10765156352 1152462480 12.333 100 38 76 1 1434503696 272630064 9411152
08:43:55 12442 8192000000 10364286192 10775535616 1125593648 12.080 98 52 102 1 1448793344 310449216 12750480
08:45:09 1827 8192000000 10340600224 10745151488 1112025376 11.952 100 67 132 2 1442765472 313603840 17136256
08:46:14 3436 8192000000 10315995104 10717241344 1099899136 11.837 99 60 119 2 1413604976 300755680 12950160
08:47:20 20568 8192000000 10228604112 10656976896 1081661312 11.663 97 25 48 2 1307499152 220097536 5740304
08:49:08 30804 8192000000 10171897296 10623537152 1102835856 11.865 100 14 28 0 1341504640 234800880 3867904
08:50:53 38010 8192000000 10092877984 10514452480 1101377952 11.851 99 20 40 0 1194793136 88851712 4563472
08:52:32 51925 8192000000 10085763328 10544140288 1111658336 11.948 100 20 40 2 1202272928 86038960 4575632
08:54:31 26621 8192000000 10099568400 10563719168 1114149632 11.972 100 17 33 3 1191250736 72957280 4143824
08:56:28 104708 8192000000 10097377168 10537201664 1108575264 11.919 99 25 50 0 1179468768 65833536 5059968
08:59:15 35251 8192000000 10103863024 10527150080 1108753824 11.921 98 39 76 0 1184356656 67546816 8056016
09:01:00 47216 8192000000 10104291872 10544566272 1127919568 12.102 100 15 30 0 1208182784 77157152 3106064
09:02:48 46237 8192000000 10136313520 10578657280 1121602656 12.042 99 25 50 1 1231428880 104197952 5628272
09:04:55 47478 8192000000 10109113216 10555183104 1108192624 11.915 98 15 31 0 1203838976 92236128 3410224
09:07:14 61490 8192000000 10136771232 10587193344 1127050784 12.094 99 17 34 1 1234845888 103403632 4391472
09:09:16 47201 8192000000 10077436560 10597466112 1103322336 11.869 98 7 14 1 1179632208 74828432 1481440
09:12:10 69242 8192000000 10077528240 10524098560 1133751040 12.157 100 14 28 1 1195607184 59108576 2747568
09:14:20 19936 8192000000 10089921488 10534838272 1132415168 12.144 100 37 74 1 1191263904 51064880 7783856
09:15:44 174415 8192000000 10054068416 10515005440 1099006576 11.828 96 16 32 1 1124065504 22019232 3039696
09:20:45 29675 8192000000 10049371456 10501775360 1110156576 11.934 98 13 27 0 1134580432 22056480 2367376
09:22:16 61993 8192000000 10078900272 10544054272 1127718576 12.100 99 14 28 0 1191395648 60546048 3131024
09:24:18 55891 8192000000 10083409696 10556252160 1143094608 12.245 100 28 55 2 1190751264 41051168 6605488
09:26:14 41756 8192000000 10138527584 10580045824 1139506880 12.211 99 17 34 0 1232704608 89707936 3489792
09:27:56 64775 8192000000 10156125344 10595409920 1108018224 11.914 96 21 42 2 1200466192 87394816 5053152
09:30:09 53349 8192000000 10242072528 10713522176 1129935984 12.121 98 21 42 3 1309559376 174694384 4929008
09:32:55 29895 8192000000 10089883728 10516566016 1118714288 12.015 99 23 46 2 1148077632 24130560 5232784
09:34:27 51283 8192000000 10086275392 10544132096 1106563024 11.900 98 46 92 0 1179931120 63897696 9470400
09:36:24 76506 8192000000 10070719872 10493337600 1085153200 11.697 97 18 36 2 1118807920 30567824 3086896
09:38:41 62747 8192000000 10092466160 10583760896 1095332992 11.793 98 18 36 0 1172695744 73738592 3624160
09:40:51 75811 8192000000 10045705648 10521378816 1077172464 11.621 97 13 26 2 1163615072 83613216 2829392
09:43:19 42429 8192000000 10046175024 10516434944 1100911088 11.846 99 7 14 0 1133099968 30232336 1956544
09:45:07 50259 8192000000 10042046080 10502729728 1088532112 11.729 97 22 44 2 1130577488 37390416 4654960
09:47:14 46784 8192000000 10078794048 10509737984 1099937440 11.837 98 19 39 3 1155449664 51717696 3794528
09:49:05 39953 8192000000 10088226320 10519695360 1098179056 11.820 99 20 40 0 1174501840 71958848 4363936
09:50:53 61358 8192000000 10113436352 10562785280 1119694336 12.024 100 29 57 3 1197087200 71038864 6354000
09:52:57 99254 8192000000 10069582528 10513907712 1098369664 11.822 97 12 23 2 1153984976 53457632 2157680
09:55:38 40542 8192000000 10138529968 10556903424 1113676608 11.967 98 33 66 1 1181360752 60991952 6692192
09:57:19 146351 8192000000 10110638896 10562949120 1138968704 12.206 100 24 48 1 1206464208 62676592 4818912
10:00:54 49253 8192000000 10100396880 10580586496 1143542464 12.249 100 9 17 1 1188352592 43119744 1690384
10:02:44 43950 8192000000 10050095504 10505969664 1114024544 11.971 98 9 18 1 1151496960 35964896 1507520
10:04:36 93464 8192000000 10089504032 10548535296 1118409584 12.012 98 8 16 0 1160363168 40544640 1408944
10:07:27 76126 8192000000 10095688288 10535292928 1105539104 11.890 96 18 36 1 1153426800 44031904 3855792
10:09:45 54248 8192000000 10068953520 10513883136 1122023184 12.046 98 29 58 1 1161207648 32577888 6606576
10:11:41 18867 8192000000 10083485104 10524200960 1126908784 12.092 99 41 82 1 1171736560 36312912 8514864
10:13:06 58763 8192000000 10070303552 10504757248 1127951104 12.102 99 26 50 0 1183852960 51158720 4743136
10:15:07 39159 8192000000 10114991840 10550845440 1099636464 11.834 96 43 86 1 1170544912 62176736 8731712
10:16:46 121684 8192000000 10086058592 10575179776 1075166048 11.601 94 14 28 0 1141810464 63627504 3016912
10:19:48 124549 8192000000 10047031776 10517491712 1114745392 11.977 98 18 36 0 1159055904 40652416 3658096
10:22:53 52542 8192000000 10047762784 10508713984 1104971248 11.885 98 10 20 0 1140006112 33060144 1974720
10:24:46 83462 8192000000 10064083440 10529947648 1111914384 11.951 98 15 30 1 1157045936 41423664 3707888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-12-2020 10:28:18 to 23-12-2020 10:28:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 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 23-12-2020 10:28:33 to 23-12-2020 10:28:35.

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          230682
MON$OLDEST_ACTIVE               232371
MON$OLDEST_SNAPSHOT             232371
MON$NEXT_TRANSACTION            232371
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                       2076576
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             794
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2302 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.2302 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Dec 23 10:26:57 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

23.12.20 10:28:36. There are 6 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
2020-12-23 10:28:39 No severe PSQL-related problems occured
23.12.20 10:28:40. End of report.