Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.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 16591184 kB
MemAvailable 31154596 kB
Buffers 0 kB
Cached 15121292 kB
SwapCached 3892 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-02-21 13:26:56
DTS_END                         2021-02-21 13:26:56.8380
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/46296
TRN_ID                          237312
ATT_ID                          616
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: 8736.88 27519 1048425
customer order: creation 160.37 432 19244
customer order: refuse 14.38 354 1725
order to supplier: creation 44.31 1463 5317
order to supplier: removal 3.29 120713 395
invoice (draft): creation 44.80 3843 5376
invoice (draft): removal 49.05 1028 5886
invoice accept: apply 40.73 17046 4887
invoice accept: cancel 44.16 948 5299
customer reserve: creation 3750.63 165 450075
customer reserve: removal 4303.54 73 516425
realization accept: apply 80.37 201 9644
realization accept: cancel 77.47 126 9296
payment from customer: creation 57.71 141 6925
payment from customer: removal 5.33 77 640
payment to supplier: creation 46.53 148 5584
payment to supplier: removal 3.37 78 404
service: total inventory turnovers 3.82 3132 458
service: total monetary turnovers 6.08 533 730
service: aggregate perf. data 0.93 16289 111
service: refresh index statistics 0.03 383593 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2184
TEST_TIME 2 3392
TEST_TIME 3 2751
TEST_TIME 4 3237
TEST_TIME 5 3250
TEST_TIME 6 3719
TEST_TIME 7 4332
TEST_TIME 8 4797
TEST_TIME 9 5423
TEST_TIME 10 5603
TEST_TIME 11 5937
TEST_TIME 12 5975
TEST_TIME 13 6110
TEST_TIME 14 6162
TEST_TIME 15 6249
TEST_TIME 16 6449
TEST_TIME 17 6823
TEST_TIME 18 7225
TEST_TIME 19 7726
TEST_TIME 20 7720
TEST_TIME 21 8541
TEST_TIME 22 8510
TEST_TIME 23 8619
TEST_TIME 24 9392
TEST_TIME 25 9638
TEST_TIME 26 11023
TEST_TIME 27 10914
TEST_TIME 28 10671
TEST_TIME 29 10473
TEST_TIME 30 10332
TEST_TIME 31 10287
TEST_TIME 32 10073
TEST_TIME 33 10056
TEST_TIME 34 10023
TEST_TIME 35 9870
TEST_TIME 36 9706
TEST_TIME 37 9552
TEST_TIME 38 9481
TEST_TIME 39 9411
TEST_TIME 40 9294
TEST_TIME 41 9386
TEST_TIME 42 9368
TEST_TIME 43 9291
TEST_TIME 44 9189
TEST_TIME 45 9227
TEST_TIME 46 9170
TEST_TIME 47 9175
TEST_TIME 48 9058
TEST_TIME 49 9153
TEST_TIME 50 9141
TEST_TIME 51 9225
TEST_TIME 52 9213
TEST_TIME 53 9205
TEST_TIME 54 9470
TEST_TIME 55 9460
TEST_TIME 56 9416
TEST_TIME 57 9428
TEST_TIME 58 9465
TEST_TIME 59 9313
TEST_TIME 60 9222
TEST_TIME 61 9156
TEST_TIME 62 9091
TEST_TIME 63 9013
TEST_TIME 64 8997
TEST_TIME 65 9001
TEST_TIME 66 8984
TEST_TIME 67 8920
TEST_TIME 68 8968
TEST_TIME 69 8921
TEST_TIME 70 8905
TEST_TIME 71 8971
TEST_TIME 72 9051
TEST_TIME 73 9016
TEST_TIME 74 9062
TEST_TIME 75 9039
TEST_TIME 76 9129
TEST_TIME 77 9165
TEST_TIME 78 9065
TEST_TIME 79 8970
TEST_TIME 80 8979
TEST_TIME 81 8950
TEST_TIME 82 8922
TEST_TIME 83 8897
TEST_TIME 84 8858
TEST_TIME 85 8844
TEST_TIME 86 8862
TEST_TIME 87 8891
TEST_TIME 88 8872
TEST_TIME 89 8876
TEST_TIME 90 8886
TEST_TIME 91 8883
TEST_TIME 92 8864
TEST_TIME 93 8947
TEST_TIME 94 9067
TEST_TIME 95 9122
TEST_TIME 96 9039
TEST_TIME 97 8992
TEST_TIME 98 8933
TEST_TIME 99 8891
TEST_TIME 100 8883
TEST_TIME 101 8832
TEST_TIME 102 8827
TEST_TIME 103 8838
TEST_TIME 104 8824
TEST_TIME 105 8808
TEST_TIME 106 8827
TEST_TIME 107 8811
TEST_TIME 108 8803
TEST_TIME 109 8760
TEST_TIME 110 8743
TEST_TIME 111 8918
TEST_TIME 112 8982
TEST_TIME 113 8938
TEST_TIME 114 8975
TEST_TIME 115 8904
TEST_TIME 116 8873
TEST_TIME 117 8826
TEST_TIME 118 8795
TEST_TIME 119 8776
TEST_TIME 120 8742

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-02-2021 13:28:35 to 21-02-2021 13:28:38.

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 1463397 1463397 0 0.00 0 20690 6 0 0
doc_list_biud 1463395 1463395 0 0.00 0 86713 33 0 0
sp_add_invoice_to_stock 4967 4887 80 1.61 38 226228 17046 0 80
sp_cancel_adding_invoice 5299 5299 0 0.00 4 99762 949 0 0
sp_cancel_client_order 1725 1725 0 0.00 5 13110 355 0 0
sp_cancel_customer_reserve 516425 516425 0 0.00 1 86716 74 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 3417 77 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 2918 78 0 0
sp_cancel_supplier_invoice 5893 5886 7 0.11 3 29890 1028 0 7
sp_cancel_supplier_order 395 395 0 0.00 10 1267907 120713 0 0
sp_cancel_write_off 9296 9296 0 0.00 1 9302 127 0 0
sp_client_order 19244 19244 0 0.00 4 29584 433 0 0
sp_customer_reserve 450075 450075 0 0.00 0 69148 165 0 0
sp_fill_shopping_cart 29931 29931 0 0.00 1 32559 54 0 0
sp_fill_shopping_cart_clo_res 450070 450070 0 0.00 0 19525 18 0 0
sp_get_clo_for_invoice 4881 4881 0 0.00 0 49468 451 0 0
sp_kill_cost_storno 15639 15639 0 0.00 0 18304 62 0 0
sp_kill_qstorno_ret_qs2qd 1048427 1048427 0 0.00 0 86706 40 0 0
sp_kill_qty_storno 553550 553550 0 0.00 0 86712 86 0 0
sp_lock_selected_doc 20064 20064 0 0.00 0 8162 6 0 0
sp_make_cost_storno 27034 27034 0 0.00 0 18037 61 0 0
sp_multiply_rows_for_pdistr 27033 27033 0 0.00 0 12720 32 0 0
sp_multiply_rows_for_qdistr 19244 19244 0 0.00 0 18812 230 0 0
sp_pay_from_customer 6925 6925 0 0.00 4 16866 141 0 0
sp_pay_to_supplier 5584 5584 0 0.00 5 16876 149 0 0
sp_payment_common 12508 12508 0 0.00 1 12711 31 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 13105 302 0 0
sp_qd_handle_on_invoice_upd_sts 10179 10179 0 0.00 0 24636 402 0 0
sp_qd_handle_on_reserve_upd_sts 9644 9644 0 0.00 0 16704 64 0 0
sp_reserve_write_off 9644 9644 0 0.00 7 17370 201 0 0
sp_supplier_invoice 5376 5376 0 0.00 6 123012 3844 0 0
sp_supplier_order 5317 5317 0 0.00 5 60864 1463 0 0
srv_aggregate_perf_data 111 111 0 0.00 2730 254400 16290 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1876 143825 43116 0 0
srv_find_qd_qs_mism 1001701 1001701 0 0.00 0 19220 5 0 0
srv_make_invnt_saldo 458 458 0 0.00 35 96033 3133 0 0
srv_make_money_saldo 730 730 0 0.00 1 15380 533 0 0
srv_recalc_idx_stat 4 4 0 0.00 196937 611878 383593 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 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-norm:sp_add_invoice_to_stock 4888 4888 0 0.00 0 19613 169 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 3382 103 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 5431 14 0 0
t$perf-norm:sp_cancel_customer_reserve 619 619 0 0.00 0 1149 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 1377 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 6857 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 919 24 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 21972 1341 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 5123 11 0 0
t$perf-norm:sp_client_order 19244 19244 0 0.00 0 8985 15 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 11926 16 0 0
t$perf-norm:sp_pay_from_customer 6925 6925 0 0.00 0 11928 7 0 0
t$perf-norm:sp_pay_to_supplier 5584 5584 0 0.00 0 11899 13 0 0
t$perf-norm:sp_reserve_write_off 9644 9644 0 0.00 0 11941 11 0 0
t$perf-norm:sp_supplier_invoice 5376 5376 0 0.00 0 7077 16 0 0
t$perf-norm:sp_supplier_order 5317 5317 0 0.00 0 2413 15 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 409 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 7023 137 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 337 7 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 3551 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 25 13 0 0
v_add_invoice_to_stock 9920 9760 160 1.61 0 7105 19 0 160
v_all_customers 35306 35306 0 0.00 0 21 1 0 0
v_all_suppliers 29352 29352 0 0.00 0 53 1 0 0
v_all_wares 38484 38484 0 0.00 0 138 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 3376 11 0 0
v_cancel_client_order 3450 3450 0 0.00 0 7257 12 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 3406 9 0 0
v_cancel_customer_reserve 1238 1238 0 0.00 1 8203 14 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1390 8 0 0
v_cancel_supplier_invoice 782 768 14 1.79 0 10198 30 0 14
v_cancel_supplier_order 790 790 0 0.00 0 7957 18 0 0
v_cancel_write_off 1950 1950 0 0.00 0 1068 13 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 341 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 2577 11 0 0
v_max_id_ord_sup 5373 5373 0 0.00 0 7769 8 0 0
v_max_non_paid_invoice 1596 1596 0 0.00 0 846 2 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 3553 4 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 4975 9 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 4786 12 0 0
v_min_id_ord_sup 5373 5373 0 0.00 0 9959 11 0 0
v_min_non_paid_invoice 5583 5583 0 0.00 0 11316 17 0 0
v_min_non_paid_realizn 6925 6925 0 0.00 0 11286 27 0 0
v_our_firm 9548 9548 0 0.00 0 13 1 0 0
v_reserve_write_off 19288 19288 0 0.00 0 4387 7 0 0
x_lock_depdocs_on_canc_invoice 10801 10801 0 0.00 0 11189 106 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 7925 373 0 0
x_make_qstorno_1000_1200 5317 5317 0 0.00 2 59722 1376 0 0
x_make_qstorno_1200_2000 5376 5376 0 0.00 3 122629 3638 0 0
x_make_qstorno_2100_3300 450075 450075 0 0.00 0 69144 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-02-2021 13:28:39 to 21-02-2021 13:28:43.

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:00 945 8192000000 8967633312 9034747904 11181440 0.136 3 2 5 0 11682352 94752 406160
10:58:02 12835 8192000000 9718588640 10013134848 800442672 8.901 100 85 170 0 828857824 7741504 20673648
10:59:22 17971 8192000000 9761145392 10066432000 837482272 9.274 100 88 176 0 870182496 11824256 20875968
11:00:49 16744 8192000000 9794688704 10106167296 860679184 9.507 100 89 178 2 903384848 22046496 20659168
11:02:10 20472 8192000000 9859261728 10188771328 906219456 9.960 100 83 166 0 968258448 43749264 18289728
11:03:36 10830 8192000000 9920847568 10263068672 955334976 10.443 100 84 168 0 1033161728 59337984 18488768
11:04:50 4016 8192000000 9968687376 10323955712 992958208 10.810 100 81 163 1 1083753040 74124688 16670144
11:05:56 3961 8192000000 9989217408 10347958272 1018780976 11.060 100 87 176 0 1106570736 69871344 17918416
11:07:02 1758 8192000000 10012613584 10379161600 1046297376 11.325 100 91 180 0 1129690464 65509904 17883184
11:08:05 1506 8192000000 10037759536 10408030208 1061996256 11.476 100 90 180 0 1147634896 67703424 17935216
11:09:07 13653 8192000000 9997070560 10399707136 1065874848 11.513 100 36 70 3 1111527136 37931600 7720688
11:10:21 10327 8192000000 10006972256 10409676800 1076129312 11.611 100 51 101 2 1114654208 27794224 10730672
11:11:34 7140 8192000000 10000913712 10403565568 1084827808 11.693 100 46 92 1 1115997104 20774144 10395152
11:12:43 7416 8192000000 10015216080 10420985856 1091038464 11.753 100 39 78 0 1129532976 29496416 8998096
11:13:53 17696 8192000000 10018886512 10402656256 1096484624 11.804 100 61 121 1 1120315376 9825856 14004896
11:15:11 7289 8192000000 10026067632 10433929216 1091336144 11.755 99 45 88 1 1125542640 24559536 9646960
11:16:19 4284 8192000000 10017359936 10413920256 1088356224 11.727 99 45 86 0 1112142032 14627984 9157824
11:17:27 14703 8192000000 9963893040 10362105856 1004467232 10.922 93 14 28 0 1029661264 22148512 3045520
11:18:42 28693 8192000000 9956321632 10358460416 1011912992 10.994 95 22 44 1 1035721296 19020880 4787424
11:20:11 5745 8192000000 9924465104 10312699904 990895584 10.790 97 47 95 4 1019960224 17791952 11272688
11:21:22 7131 8192000000 9946176720 10336014336 1016088944 11.034 99 40 80 0 1049510160 23701936 9719280
11:22:29 50362 8192000000 9954460512 10338521088 1041509024 11.279 100 50 100 3 1067879248 15139392 11230832
11:24:20 12849 8192000000 9979689984 10384961536 1060616272 11.462 100 33 66 1 1097503040 29107088 7779680
11:25:33 4737 8192000000 9983697088 10381242368 1067155600 11.525 100 44 89 1 1091731840 14171264 10404976
11:26:39 7095 8192000000 9987827152 10390609920 1080938336 11.656 100 38 76 3 1102994528 12785344 9270848
11:27:54 16952 8192000000 10018259184 10415730688 1097475040 11.814 100 41 81 0 1128938384 22964576 8498768
11:29:17 78303 8192000000 10086248032 10504077312 1115705648 11.986 100 48 96 1 1194097680 67428816 10963216
11:31:39 52223 8192000000 10134532512 10551345152 1124940496 12.074 100 36 72 4 1251089600 117805248 8343856
11:33:35 2381 8192000000 10191820384 10591883264 1141568112 12.230 100 79 159 1 1301476448 143276624 16631712
11:34:43 5395 8192000000 10188702976 10590130176 1137634144 12.193 100 62 122 0 1297530768 147301728 12594896
11:35:54 4691 8192000000 10200306368 10601299968 1140158720 12.217 99 67 132 2 1296035280 140940272 14936288
11:37:01 5560 8192000000 10262795280 10672332800 1170998544 12.506 100 63 124 0 1363746352 178944928 13802880
11:38:09 6663 8192000000 10238300624 10653585408 1159760256 12.401 99 59 116 1 1338917296 166461136 12695904
11:39:21 1872 8192000000 10296357312 10698162176 1142026864 12.235 98 71 142 0 1383639008 225735632 15876512
11:40:28 8505 8192000000 10338439936 10736959488 1158242336 12.387 100 76 151 1 1440908480 264708624 17957520
11:41:43 2027 8192000000 10390000304 10787987456 1160610960 12.409 100 82 163 0 1489616176 310088336 18916880
11:42:51 5181 8192000000 10414595984 10818228224 1149865840 12.308 99 75 147 2 1513125744 346068352 17191552
11:44:06 1880 8192000000 10437585856 10832269312 1152817168 12.336 100 94 187 1 1532346144 357598176 21930800
11:45:11 9017 8192000000 10416354448 10859003904 1171482368 12.511 100 50 99 3 1519331712 335699568 12149776
11:46:27 3342 8192000000 10464954848 10868133888 1148846640 12.299 99 86 171 0 1552228512 383166992 20214880
11:47:34 4045 8192000000 10440787600 10872750080 1161675120 12.419 100 59 119 0 1547002720 370260352 15067248
11:48:42 13880 8192000000 10403382144 10838818816 1144142912 12.254 100 37 74 3 1517122672 362556576 10423184
11:50:07 18360 8192000000 10370742992 10883825664 1133175968 12.151 99 33 66 2 1507617664 365298304 9143392
11:51:37 38235 8192000000 10250448656 10758115328 1131577088 12.136 100 20 40 2 1408958064 272001456 5379520
11:54:31 33450 8192000000 10072121168 10518040576 1125670352 12.081 99 39 76 1 1151850688 18276272 7904064
11:56:16 40833 8192000000 10084679952 10530820096 1124977120 12.074 99 38 79 1 1171028016 38366544 7684352
12:00:02 70954 8192000000 10096772336 10569113600 1146009008 12.272 100 13 25 1 1194143328 45491520 2642800
12:02:13 31903 8192000000 10124012528 10568728576 1141558704 12.230 100 15 30 0 1206598528 61799952 3239872
12:03:47 116009 8192000000 10118680576 10620760064 1141658912 12.231 100 28 56 2 1232879840 84482336 6738592
12:07:02 37564 8192000000 10107646080 10578173952 1136391104 12.182 99 23 46 0 1231703456 90016912 5295440
12:08:54 54480 8192000000 10072119152 10540662784 1144530368 12.258 100 11 22 0 1173575520 27180208 1864944
12:10:48 55953 8192000000 10087332048 10576445440 1155096400 12.357 100 11 22 1 1204168416 47058432 2013584
12:12:45 58837 8192000000 10086904832 10547363840 1134944688 12.168 98 17 34 0 1177860272 39285872 3629712
12:14:46 18669 8192000000 10179954672 10628173824 1177204512 12.564 100 67 134 1 1285136240 92817840 15113888
12:16:14 46674 8192000000 10190151024 10659454976 1154711264 12.354 99 20 40 1 1284990784 125964368 4315152
12:18:01 65736 8192000000 10176243712 10651594752 1121507296 12.041 97 20 40 0 1245693760 119787456 4399008
12:20:18 52358 8192000000 10077462528 10535968768 1114209648 11.972 97 12 24 0 1194930704 78211024 2510032
12:22:19 44107 8192000000 10097854560 10554023936 1130120272 12.122 99 21 43 1 1192542080 57783088 4638720
12:24:06 91436 8192000000 10084971104 10578980864 1120557120 12.032 98 16 32 0 1194951696 71228528 3166048
12:26:51 36579 8192000000 10041266592 10521624576 1082108960 11.668 96 6 12 0 1123721664 40164720 1447984
12:28:28 27157 8192000000 10033593664 10472730624 1105953744 11.894 99 7 14 1 1122091888 14805760 1332384
12:30:18 79346 8192000000 10078637488 10553040896 1122169168 12.047 100 12 24 0 1182118560 57338640 2610752
12:32:39 49592 8192000000 10054683616 10478018560 1061191120 11.468 94 23 46 1 1085774592 19888528 4694944
12:34:40 40548 8192000000 10055874048 10498154496 1079422048 11.642 97 15 30 0 1119876800 36989920 3464832
12:36:40 62055 8192000000 10097662368 10536558592 1083731200 11.683 97 22 45 1 1153128880 65016320 4381360
12:38:43 37982 8192000000 10067323664 10485657600 1090465856 11.747 97 27 53 1 1125494016 29004432 6023728
12:40:23 45640 8192000000 10065905840 10487517184 1087697264 11.721 97 34 68 2 1125056256 30631136 6727856
12:42:11 37646 8192000000 10108865136 10572234752 1115083904 11.981 99 36 71 3 1217710400 95554720 7071776
12:43:57 119812 8192000000 10097882432 10536288256 1103296544 11.869 99 18 36 2 1170001504 62561392 4143568
12:49:06 59340 8192000000 10052148784 10492850176 1105361952 11.888 98 23 46 1 1139415504 29594800 4458752
12:51:10 43867 8192000000 10114259920 10565382144 1124238896 12.067 99 27 54 1 1187108656 56944976 5924784
12:53:01 56010 8192000000 10083236896 10537304064 1129314432 12.115 100 18 36 0 1176610816 43789376 3507008
12:55:08 67459 8192000000 10115123584 10590752768 1128592496 12.108 99 22 44 0 1194795808 61627408 4575904
12:57:16 32402 8192000000 10145922080 10580398080 1119593152 12.023 98 43 86 5 1221942800 92203024 10146624
12:59:05 38220 8192000000 10202948608 10684338176 1143516016 12.249 100 15 30 1 1282522320 135313072 3693232
13:00:51 64073 8192000000 10103813504 10612199424 1129252000 12.114 99 10 20 0 1195023472 63888608 1882864
13:04:07 60432 8192000000 10097448672 10578403328 1135681424 12.175 99 18 36 0 1190074960 50658448 3735088
13:06:12 75529 8192000000 10091388112 10585735168 1149404624 12.304 100 7 13 1 1172716240 22186560 1125056
13:08:29 26783 8192000000 10094176512 10537496576 1141141280 12.226 99 31 62 0 1178813648 31527296 6145072
13:09:57 33450 8192000000 10087239456 10533646336 1144681696 12.260 99 26 53 0 1175675200 26149680 4843824
13:11:33 44907 8192000000 10094046736 10549235712 1113252016 11.963 97 35 68 1 1165750704 45398752 7099936
13:13:28 35884 8192000000 10128292224 10556506112 1129874816 12.120 98 44 86 2 1165800672 26785616 9140240
13:15:06 117651 8192000000 10241179264 10719875072 1112595728 11.957 96 22 44 2 1255408432 137755776 5056928
13:19:01 40485 8192000000 10079989952 10499850240 1077449424 11.623 95 31 62 0 1103885152 20150464 6285264
13:20:42 143825 8192000000 10055428896 10520072192 1113908304 11.969 99 15 28 1 1150654656 33252544 3493808
13:24:21 44239 8192000000 10062895328 10527776768 1128899232 12.111 100 14 27 0 1166735088 34796144 3039712
13:26:14 54702 8192000000 9521235328 9910628352 647656832 7.326 57 6 12 0 679786720 30931760 1198128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 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 21-02-2021 13:29:01 to 21-02-2021 13:29:03.

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          237641
MON$OLDEST_ACTIVE               237642
MON$OLDEST_SNAPSHOT             237642
MON$NEXT_TRANSACTION            237642
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                       2088288
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:36: 
:37: mscs-test-161.budzdorov.ru    Sun Feb 21 13:27:13 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Sun Feb 21 13:27:13 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sun Feb 21 13:27:13 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sun Feb 21 13:27:13 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Sun Feb 21 13:27:13 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

21.02.21 13:29:04. 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-21 13:29:07 No severe PSQL-related problems occured
21.02.21 13:29:08. End of report.