Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2021 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 16668032 kB
MemAvailable 31173184 kB
Buffers 0 kB
Cached 14883236 kB
SwapCached 4868 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-01-21 10:26:54
DTS_END                         2021-01-21 10:26:54.1240
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/46524
TRN_ID                          226522
ATT_ID                          594
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: 8834.27 28326 1060112
customer order: creation 161.90 429 19428
customer order: refuse 14.28 327 1714
order to supplier: creation 43.30 1321 5196
order to supplier: removal 3.10 120412 372
invoice (draft): creation 44.78 3521 5373
invoice (draft): removal 50.69 840 6083
invoice accept: apply 39.76 19273 4771
invoice accept: cancel 45.21 895 5425
customer reserve: creation 3697.65 187 443718
customer reserve: removal 4456.08 67 534730
realization accept: apply 78.70 173 9444
realization accept: cancel 76.92 123 9230
payment from customer: creation 57.25 105 6870
payment from customer: removal 5.17 70 620
payment to supplier: creation 45.21 126 5425
payment to supplier: removal 3.11 88 373
service: total inventory turnovers 4.01 3116 481
service: total monetary turnovers 6.32 515 758
service: aggregate perf. data 0.82 16767 98
service: refresh index statistics 0.03 398156 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2021 10:27:55 to 21-01-2021 10:27:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6706
TEST_TIME 2 4667
TEST_TIME 3 5837
TEST_TIME 4 4970
TEST_TIME 5 5187
TEST_TIME 6 5282
TEST_TIME 7 5152
TEST_TIME 8 5289
TEST_TIME 9 5466
TEST_TIME 10 5445
TEST_TIME 11 5678
TEST_TIME 12 5760
TEST_TIME 13 6428
TEST_TIME 14 6482
TEST_TIME 15 6685
TEST_TIME 16 6803
TEST_TIME 17 7399
TEST_TIME 18 7664
TEST_TIME 19 7582
TEST_TIME 20 7785
TEST_TIME 21 8297
TEST_TIME 22 8325
TEST_TIME 23 8604
TEST_TIME 24 8513
TEST_TIME 25 9202
TEST_TIME 26 9823
TEST_TIME 27 10448
TEST_TIME 28 10795
TEST_TIME 29 10630
TEST_TIME 30 10301
TEST_TIME 31 10208
TEST_TIME 32 9992
TEST_TIME 33 10094
TEST_TIME 34 10083
TEST_TIME 35 9944
TEST_TIME 36 9780
TEST_TIME 37 9694
TEST_TIME 38 9535
TEST_TIME 39 9588
TEST_TIME 40 9563
TEST_TIME 41 9553
TEST_TIME 42 9451
TEST_TIME 43 9440
TEST_TIME 44 9462
TEST_TIME 45 9730
TEST_TIME 46 9630
TEST_TIME 47 9626
TEST_TIME 48 9540
TEST_TIME 49 9659
TEST_TIME 50 9672
TEST_TIME 51 9800
TEST_TIME 52 9827
TEST_TIME 53 9660
TEST_TIME 54 9532
TEST_TIME 55 9432
TEST_TIME 56 9384
TEST_TIME 57 9260
TEST_TIME 58 9200
TEST_TIME 59 9161
TEST_TIME 60 9207
TEST_TIME 61 9219
TEST_TIME 62 9200
TEST_TIME 63 9182
TEST_TIME 64 9166
TEST_TIME 65 9232
TEST_TIME 66 9292
TEST_TIME 67 9209
TEST_TIME 68 9240
TEST_TIME 69 9240
TEST_TIME 70 9278
TEST_TIME 71 9263
TEST_TIME 72 9364
TEST_TIME 73 9350
TEST_TIME 74 9239
TEST_TIME 75 9145
TEST_TIME 76 9132
TEST_TIME 77 9065
TEST_TIME 78 8994
TEST_TIME 79 8943
TEST_TIME 80 8981
TEST_TIME 81 8949
TEST_TIME 82 8908
TEST_TIME 83 8909
TEST_TIME 84 9065
TEST_TIME 85 8992
TEST_TIME 86 9085
TEST_TIME 87 9191
TEST_TIME 88 9185
TEST_TIME 89 9341
TEST_TIME 90 9315
TEST_TIME 91 9400
TEST_TIME 92 9334
TEST_TIME 93 9243
TEST_TIME 94 9209
TEST_TIME 95 9191
TEST_TIME 96 9121
TEST_TIME 97 9073
TEST_TIME 98 9080
TEST_TIME 99 9089
TEST_TIME 100 9040
TEST_TIME 101 9036
TEST_TIME 102 9005
TEST_TIME 103 9014
TEST_TIME 104 9052
TEST_TIME 105 9070
TEST_TIME 106 9051
TEST_TIME 107 9033
TEST_TIME 108 9069
TEST_TIME 109 8996
TEST_TIME 110 8933
TEST_TIME 111 8944
TEST_TIME 112 8905
TEST_TIME 113 8903
TEST_TIME 114 8872
TEST_TIME 115 8862
TEST_TIME 116 8845
TEST_TIME 117 8858
TEST_TIME 118 8820
TEST_TIME 119 8874
TEST_TIME 120 8840
TEST_TIME 121 8767

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-01-2021 10:27:58 to 21-01-2021 10:28:02.

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 1471309 1471309 0 0.00 0 23609 7 0 0
doc_list_biud 1471309 1471309 0 0.00 0 80750 31 0 0
sp_add_invoice_to_stock 4832 4771 61 1.26 32 170473 19273 0 61
sp_cancel_adding_invoice 5425 5425 0 0.00 5 74310 896 0 0
sp_cancel_client_order 1714 1714 0 0.00 5 12119 327 0 0
sp_cancel_customer_reserve 534730 534730 0 0.00 1 80751 68 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 3 7156 70 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 6864 89 0 0
sp_cancel_supplier_invoice 6090 6083 7 0.11 2 40198 841 0 7
sp_cancel_supplier_order 372 372 0 0.00 13 1278546 120412 0 0
sp_cancel_write_off 9230 9230 0 0.00 1 18421 123 0 0
sp_client_order 19428 19428 0 0.00 4 22216 430 0 0
sp_customer_reserve 443718 443718 0 0.00 0 75527 188 0 0
sp_fill_shopping_cart 29978 29978 0 0.00 1 19031 47 0 0
sp_fill_shopping_cart_clo_res 443711 443711 0 0.00 0 20150 20 0 0
sp_get_clo_for_invoice 4763 4763 0 0.00 0 43878 533 0 0
sp_kill_cost_storno 15648 15648 0 0.00 0 11107 55 0 0
sp_kill_qstorno_ret_qs2qd 1085145 1085145 0 0.00 0 80746 36 0 0
sp_kill_qty_storno 571761 571761 0 0.00 0 80750 78 0 0
sp_lock_selected_doc 19712 19712 0 0.00 0 8233 5 0 0
sp_make_cost_storno 26502 26502 0 0.00 0 15080 59 0 0
sp_multiply_rows_for_pdistr 26502 26502 0 0.00 0 14749 31 0 0
sp_multiply_rows_for_qdistr 19428 19428 0 0.00 0 19078 261 0 0
sp_pay_from_customer 6870 6870 0 0.00 4 12014 106 0 0
sp_pay_to_supplier 5425 5425 0 0.00 5 14256 126 0 0
sp_payment_common 12295 12295 0 0.00 1 11507 25 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 12111 297 0 0
sp_qd_handle_on_invoice_upd_sts 10188 10188 0 0.00 0 34701 378 0 0
sp_qd_handle_on_reserve_upd_sts 9444 9444 0 0.00 0 9330 53 0 0
sp_reserve_write_off 9444 9444 0 0.00 7 18238 173 0 0
sp_supplier_invoice 5373 5373 0 0.00 6 113869 3522 0 0
sp_supplier_order 5196 5196 0 0.00 6 32963 1322 0 0
srv_aggregate_perf_data 98 98 0 0.00 2882 210253 16767 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1225 181059 49860 0 0
srv_find_qd_qs_mism 1014900 1014900 0 0.00 0 23608 6 0 0
srv_make_invnt_saldo 481 481 0 0.00 51 87699 3116 0 0
srv_make_money_saldo 758 758 0 0.00 1 11628 515 0 0
srv_recalc_idx_stat 3 3 0 0.00 272889 496498 398156 0 0
t$perf-abend:sp_add_invoice_to_stock 61 61 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 4771 4771 0 0.00 0 11720 149 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 1573 74 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 7475 13 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 735 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 2324 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 306 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 987 11 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 16378 1461 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 1274 6 0 0
t$perf-norm:sp_client_order 19428 19428 0 0.00 0 7551 15 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 2883 7 0 0
t$perf-norm:sp_pay_from_customer 6870 6870 0 0.00 0 3562 6 0 0
t$perf-norm:sp_pay_to_supplier 5425 5425 0 0.00 0 8896 11 0 0
t$perf-norm:sp_reserve_write_off 9444 9444 0 0.00 0 4991 10 0 0
t$perf-norm:sp_supplier_invoice 5373 5373 0 0.00 0 4034 15 0 0
t$perf-norm:sp_supplier_order 5196 5196 0 0.00 0 2904 13 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 221 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 339 9 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 4022 17 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 2886 10 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9648 9526 122 1.26 0 5099 17 0 122
v_all_customers 35624 35624 0 0.00 0 12 1 0 0
v_all_suppliers 29266 29266 0 0.00 0 20 1 0 0
v_all_wares 38856 38856 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 684 7 0 0
v_cancel_client_order 3428 3428 0 0.00 0 2042 4 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 1685 5 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 3208 11 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 1559 8 0 0
v_cancel_supplier_invoice 784 770 14 1.78 0 2103 17 0 14
v_cancel_supplier_order 744 744 0 0.00 0 2557 7 0 0
v_cancel_write_off 1936 1936 0 0.00 0 2480 13 0 0
v_max_id_clo_ord 5190 5190 0 0.00 0 2830 2 0 0
v_max_id_clo_res 925 925 0 0.00 0 1077 13 0 0
v_max_id_ord_sup 5359 5359 0 0.00 0 8483 4 0 0
v_max_non_paid_invoice 1341 1341 0 0.00 0 571 3 0 0
v_max_non_paid_realizn 3568 3568 0 0.00 0 3115 5 0 0
v_min_id_clo_ord 5190 5190 0 0.00 0 7204 6 0 0
v_min_id_clo_res 925 925 0 0.00 0 7725 24 0 0
v_min_id_ord_sup 5359 5359 0 0.00 0 4633 7 0 0
v_min_non_paid_invoice 5425 5425 0 0.00 0 5328 13 0 0
v_min_non_paid_realizn 6870 6870 0 0.00 0 11505 20 0 0
v_our_firm 9836 9836 0 0.00 0 14 1 0 0
v_reserve_write_off 18888 18888 0 0.00 0 4159 5 0 0
x_lock_depdocs_on_canc_invoice 11123 11123 0 0.00 0 15644 108 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 20419 477 0 0
x_make_qstorno_1000_1200 5196 5196 0 0.00 2 32921 1244 0 0
x_make_qstorno_1200_2000 5373 5373 0 0.00 3 113843 3338 0 0
x_make_qstorno_2100_3300 443718 443718 0 0.00 0 75524 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2021 10:28:03 to 21-01-2021 10:28:07.

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:57 9 8192000000 8802388192 8814911488 7535120 0.091 3 1 2 0 7651232 67632 48480
07:57:58 23916 8192000000 9703385104 9994985472 781832704 8.712 100 86 172 0 810380960 7354304 21193952
07:59:26 14608 8192000000 9745280816 10044149760 821629072 9.115 100 90 180 0 855128752 11908240 21591440
08:00:46 21712 8192000000 9791038416 10102034432 855276064 9.453 100 88 176 0 894329872 18671408 20382400
08:02:22 13891 8192000000 9845264960 10177908736 906920048 9.967 100 73 146 0 958583584 35890320 15773216
08:03:42 6090 8192000000 9917810560 10258583552 956504288 10.455 100 92 184 1 1035418336 59155536 19758512
08:04:52 4491 8192000000 9959546720 10306670592 987604848 10.758 100 89 178 1 1071404432 65696096 18103488
08:05:58 2323 8192000000 10015014544 10370658304 1011503184 10.990 100 89 176 0 1124893408 95791424 17598800
08:07:02 2392 8192000000 10014577984 10378248192 1033250960 11.200 100 82 164 0 1125600352 75529856 16819536
08:08:07 2457 8192000000 10036359856 10407170048 1061979440 11.475 100 94 190 1 1145184576 64190512 19014624
08:09:11 40553 8192000000 10002351280 10378350592 1068818576 11.541 100 64 128 1 1111598128 29169552 13610000
08:10:53 10068 8192000000 9977549504 10385321984 1078292960 11.631 100 26 52 3 1101416896 16932112 6191824
08:12:03 10033 8192000000 10005908832 10407047168 1085939904 11.704 100 25 49 5 1113474768 22045280 5489584
08:13:18 8383 8192000000 10017619120 10425942016 1092510160 11.767 100 39 78 2 1133606720 32982256 8114304
08:14:27 42853 8192000000 10012017888 10430902272 1090974480 11.752 100 27 52 1 1117419728 20731280 5713968
08:16:10 9501 8192000000 10007409712 10415685632 1092211248 11.764 100 47 94 2 1118738272 15880640 10646384
08:17:24 8199 8192000000 9985107872 10374209536 1071422544 11.566 99 36 73 3 1095611088 15300016 8888528
08:18:33 6740 8192000000 9980052576 10377539584 1062552896 11.481 100 36 68 0 1083026432 12275776 8197760
08:19:41 46310 8192000000 9952125616 10358902784 996706848 10.847 94 34 66 2 1022630240 18250000 7673392
08:21:28 5319 8192000000 9947606016 10331164672 1010140352 10.977 98 46 93 5 1051529264 31344816 10044096
08:22:34 8489 8192000000 9956616896 10351857664 1017368064 11.047 98 48 96 1 1053634944 25247440 11019440
08:23:44 7834 8192000000 9958806512 10350686208 1042851664 11.292 100 44 88 2 1068370752 15627984 9891104
08:24:54 45791 8192000000 9964821440 10372423680 1057220672 11.430 100 28 55 1 1076357952 13444048 5693232
08:26:42 6582 8192000000 9992247376 10391916544 1083479280 11.681 100 30 61 0 1115376432 25716304 6180848
08:27:54 43667 8192000000 10065328912 10462412800 1104285984 11.878 100 56 111 1 1161274240 45240304 11747952
08:29:43 11139 8192000000 10065100320 10465476608 1111261104 11.944 99 56 112 0 1174807760 51995904 11550752
08:30:56 37104 8192000000 10124590048 10535899136 1128789856 12.110 100 44 87 1 1221354608 83286096 9278656
08:32:46 13354 8192000000 10133032592 10553929728 1144526720 12.258 100 49 96 1 1235114064 80458960 10128384
08:34:06 22880 8192000000 10175856048 10603319296 1157999776 12.385 100 46 92 0 1296305488 128419088 9886624
08:35:34 5119 8192000000 10213713056 10622611456 1164877280 12.449 100 54 107 0 1309143760 133134176 11132304
08:36:45 5073 8192000000 10246011648 10655744000 1169705360 12.494 100 67 132 0 1349292112 164757168 14829584
08:38:00 6241 8192000000 10270755616 10684805120 1177783968 12.570 100 64 128 1 1372849616 180448016 14617632
08:39:12 3977 8192000000 10307470512 10731941888 1183913952 12.627 100 73 146 1 1404586176 204110544 16561680
08:40:21 1698 8192000000 10318014304 10722803712 1187349744 12.659 100 73 146 1 1413482656 209668768 16464144
08:41:26 3838 8192000000 10371679120 10786553856 1176515120 12.558 99 73 146 2 1462729920 268828496 17386304
08:42:37 5354 8192000000 10381538000 10788331520 1180263824 12.593 100 71 142 1 1472683440 276081232 16338384
08:43:47 3124 8192000000 10363712608 10776276992 1172638064 12.521 99 67 129 0 1451135280 263559184 14938032
08:44:55 4081 8192000000 10380289232 10795945984 1179121696 12.582 100 57 113 0 1487288128 294830512 13335920
08:46:07 1223 8192000000 10480814400 10883334144 1165303136 12.453 100 87 174 0 1574431888 389031424 20097328
08:47:13 12372 8192000000 10419589872 10839433216 1160400800 12.407 100 44 86 2 1531666496 360349008 10916688
08:48:31 12579 8192000000 10410868480 10859884544 1134782960 12.166 99 40 79 0 1503659520 358799376 10077184
08:49:51 6309 8192000000 10353750368 10761953280 1107770416 11.911 98 53 104 1 1431648528 311170528 12707584
08:51:14 9161 8192000000 10367755136 10860453888 1114387888 11.974 100 32 64 2 1498881984 375490544 9003552
08:52:30 24688 8192000000 10242888960 10729771008 1092250448 11.764 99 18 35 2 1334745696 236959440 5535808
08:53:55 27822 8192000000 10035206080 10472648704 1100298784 11.840 99 16 30 2 1205665888 101908944 3458160
08:55:31 115437 8192000000 10083939152 10517012480 1106509680 11.899 99 43 84 6 1189939168 74398000 9031488
08:58:28 102006 8192000000 10096127616 10583748608 1103896960 11.875 98 23 45 1 1189343568 80142992 5303616
09:01:12 53817 8192000000 10060358544 10499682304 1106198768 11.896 99 10 20 0 1150274864 42281696 1794400
09:03:43 99723 8192000000 10077346768 10528063488 1098278592 11.821 98 15 30 0 1178805360 77106864 3419904
09:06:47 44561 8192000000 10053436496 10481344512 1094660784 11.787 98 25 50 2 1136473328 36117056 5695488
09:08:36 28704 8192000000 10136960672 10566291456 1132465184 12.145 100 33 67 3 1205508400 65736656 7306560
09:10:05 73270 8192000000 10124540560 10621063168 1118014032 12.008 99 22 44 0 1244929008 122012048 4902928
09:12:27 30318 8192000000 10089122528 10517983232 1131937008 12.140 100 32 64 0 1163657680 25329008 6391664
09:14:08 43992 8192000000 10130328592 10547851264 1146840144 12.280 100 50 100 0 1207308400 49558448 10909808
09:15:55 101482 8192000000 10147405968 10638381056 1149277216 12.303 100 14 28 1 1211915744 59530112 3108416
09:18:38 110247 8192000000 10071543856 10563436544 1128386784 12.106 99 12 24 0 1170751168 40102832 2261552
09:21:37 101045 8192000000 10087370240 10574381056 1146752672 12.279 100 10 20 1 1199590704 50702576 2135456
09:25:03 127003 8192000000 10092998352 10613456896 1145117344 12.264 99 18 36 1 1206930064 57657424 4155296
09:28:19 45843 8192000000 10065664208 10504769536 1120564976 12.032 98 29 58 0 1151927712 25478048 5884688
09:30:10 89584 8192000000 10103066848 10562334720 1058901872 11.446 92 20 40 0 1115111216 51721264 4488080
09:32:50 39289 8192000000 10077544704 10505269248 1115665200 11.986 98 32 64 0 1155468688 33457456 6346032
09:34:32 23267 8192000000 10089435568 10519707648 1114827632 11.978 99 35 70 1 1166326992 44638160 6861200
09:35:55 14998 8192000000 10084828800 10513420288 1102096944 11.858 97 37 74 4 1150810064 41242816 7470304
09:37:19 40455 8192000000 10051103136 10470600704 1044532992 11.308 93 21 42 0 1075887264 26187296 5166976
09:39:04 167480 8192000000 10030343376 10457530368 1080211216 11.649 97 12 24 0 1124228224 41440160 2576848
09:42:51 44559 8192000000 10039451072 10496348160 1112601024 11.957 100 3 6 0 1137575056 24493712 480320
09:44:54 73469 8192000000 10065132560 10526437376 1081735280 11.664 96 16 32 0 1142353264 56708608 3909376
09:47:07 43049 8192000000 10082608800 10513059840 1103384480 11.870 98 28 56 1 1166992928 57193856 6414592
09:48:59 64775 8192000000 10157495520 10680979456 1113858784 11.969 99 29 58 1 1271000368 150392800 6748784
09:51:32 53556 8192000000 10121944576 10555498496 1080238832 11.650 96 31 62 1 1165646416 78500368 6907216
09:53:51 96469 8192000000 10101381312 10653691904 1065667904 11.511 96 5 10 0 1180170384 113396848 1105632
09:57:44 150066 8192000000 10055296304 10505510912 1119259264 12.020 100 19 38 1 1159043632 36192128 3592240
10:01:25 53849 8192000000 10041527728 10528309248 1120082624 12.028 100 4 8 0 1158630928 38004032 544272
10:03:36 86169 8192000000 10083263616 10552193024 1091310848 11.755 96 12 24 0 1131869440 38588432 1970160
10:06:02 48547 8192000000 10085584160 10530013184 1135600288 12.174 100 18 36 0 1177606976 38540688 3466000
10:07:51 37861 8192000000 10116521664 10546049024 1140870336 12.224 100 30 60 0 1183015904 35801488 6344080
10:09:30 114994 8192000000 10110569264 10610950144 1135919040 12.177 99 18 36 1 1208237984 68832800 3486144
10:12:27 19390 8192000000 10120187360 10557591552 1151205168 12.321 100 41 82 1 1198629744 39124432 8300144
10:13:47 12964 8192000000 10131371888 10550435840 1151349232 12.322 100 61 122 2 1214720288 51347136 12023920
10:15:01 181058 8192000000 10113945456 10637246464 1145212128 12.265 99 12 24 0 1238981632 91149808 2619696
10:20:46 21613 8192000000 10065405152 10511065088 1138897744 12.205 100 21 42 0 1170992224 26901152 5193328
10:22:13 37341 8192000000 10091706464 10536783872 1109653232 11.929 97 12 24 1 1147169600 34983056 2533312
10:23:51 75132 8192000000 10125865856 10560561152 1097118768 11.810 96 21 42 1 1174380032 72371888 4889376
10:26:06 63376 8192000000 9196573152 9497808896 341995216 4.007 30 5 10 0 389112208 46044768 1072224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-01-2021 10:28:09 to 21-01-2021 10:28:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 61 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-01-2021 10:28:24 to 21-01-2021 10:28:26.

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          227194
MON$OLDEST_ACTIVE               227195
MON$OLDEST_SNAPSHOT             227195
MON$NEXT_TRANSACTION            227195
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                       2078944
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             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2335 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.2335 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 ---
:39: 
:40: mscs-test-161.budzdorov.ru    Thu Jan 21 10:27:11 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
--- end of diff output ---

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

21.01.21 10:28:27. 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-01-21 10:28:30 No severe PSQL-related problems occured
21.01.21 10:28:31. End of report.