Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.11.2021 04:39
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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
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 4793.92
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 16126624 kB
MemAvailable 30792528 kB
Buffers 20 kB
Cached 15565420 kB
SwapCached 2456 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.1
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-11-10 04:37:09
DTS_END                         2021-11-10 04:37:09.6270
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/35412
TRN_ID                          235183
ATT_ID                          643
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: 8760.23 22597 1051228
customer order: creation 175.13 387 21015
customer order: refuse 14.67 323 1760
order to supplier: creation 47.21 1393 5665
order to supplier: removal 3.22 100139 386
invoice (draft): creation 46.56 3042 5587
invoice (draft): removal 47.56 934 5707
invoice accept: apply 42.88 18612 5145
invoice accept: cancel 42.98 926 5157
customer reserve: creation 3871.78 188 464613
customer reserve: removal 4185.25 61 502230
realization accept: apply 84.08 185 10090
realization accept: cancel 69.29 124 8315
payment from customer: creation 59.68 107 7162
payment from customer: removal 5.78 64 694
payment to supplier: creation 49.71 129 5965
payment to supplier: removal 3.49 111 419
service: total inventory turnovers 3.99 3319 479
service: total monetary turnovers 6.02 450 722
service: aggregate perf. data 0.93 17725 112
service: refresh index statistics 0.04 303729 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-11-2021 04:39:29 to 10-11-2021 04:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3933
TEST_TIME 2 4225
TEST_TIME 3 5787
TEST_TIME 4 5331
TEST_TIME 5 5624
TEST_TIME 6 5725
TEST_TIME 7 5913
TEST_TIME 8 5800
TEST_TIME 9 6160
TEST_TIME 10 6629
TEST_TIME 11 7161
TEST_TIME 12 7241
TEST_TIME 13 7942
TEST_TIME 14 8351
TEST_TIME 15 8472
TEST_TIME 16 8777
TEST_TIME 17 8925
TEST_TIME 18 9027
TEST_TIME 19 9307
TEST_TIME 20 9233
TEST_TIME 21 9080
TEST_TIME 22 9102
TEST_TIME 23 9367
TEST_TIME 24 9663
TEST_TIME 25 9924
TEST_TIME 26 10188
TEST_TIME 27 11406
TEST_TIME 28 11025
TEST_TIME 29 10798
TEST_TIME 30 10578
TEST_TIME 31 10513
TEST_TIME 32 10328
TEST_TIME 33 10224
TEST_TIME 34 10044
TEST_TIME 35 10122
TEST_TIME 36 10072
TEST_TIME 37 9891
TEST_TIME 38 9708
TEST_TIME 39 9583
TEST_TIME 40 9776
TEST_TIME 41 9633
TEST_TIME 42 9767
TEST_TIME 43 9730
TEST_TIME 44 9664
TEST_TIME 45 9587
TEST_TIME 46 9642
TEST_TIME 47 9547
TEST_TIME 48 9543
TEST_TIME 49 9588
TEST_TIME 50 9643
TEST_TIME 51 9571
TEST_TIME 52 9515
TEST_TIME 53 9515
TEST_TIME 54 9685
TEST_TIME 55 9561
TEST_TIME 56 9423
TEST_TIME 57 9306
TEST_TIME 58 9482
TEST_TIME 59 9407
TEST_TIME 60 9385
TEST_TIME 61 9369
TEST_TIME 62 9329
TEST_TIME 63 9295
TEST_TIME 64 9246
TEST_TIME 65 9160
TEST_TIME 66 9137
TEST_TIME 67 9131
TEST_TIME 68 9099
TEST_TIME 69 9088
TEST_TIME 70 9064
TEST_TIME 71 9040
TEST_TIME 72 9074
TEST_TIME 73 9085
TEST_TIME 74 9096
TEST_TIME 75 9158
TEST_TIME 76 9162
TEST_TIME 77 9190
TEST_TIME 78 9196
TEST_TIME 79 9241
TEST_TIME 80 9198
TEST_TIME 81 9163
TEST_TIME 82 9109
TEST_TIME 83 9046
TEST_TIME 84 9041
TEST_TIME 85 8968
TEST_TIME 86 8926
TEST_TIME 87 8923
TEST_TIME 88 8908
TEST_TIME 89 8914
TEST_TIME 90 8874
TEST_TIME 91 8925
TEST_TIME 92 8975
TEST_TIME 93 8987
TEST_TIME 94 8946
TEST_TIME 95 8978
TEST_TIME 96 9015
TEST_TIME 97 9021
TEST_TIME 98 9079
TEST_TIME 99 9013
TEST_TIME 100 8978
TEST_TIME 101 8926
TEST_TIME 102 8873
TEST_TIME 103 8831
TEST_TIME 104 8875
TEST_TIME 105 8897
TEST_TIME 106 8881
TEST_TIME 107 8878
TEST_TIME 108 8888
TEST_TIME 109 8928
TEST_TIME 110 8891
TEST_TIME 111 8940
TEST_TIME 112 8943
TEST_TIME 113 8955
TEST_TIME 114 8932
TEST_TIME 115 8957
TEST_TIME 116 8890
TEST_TIME 117 8859
TEST_TIME 118 8868
TEST_TIME 119 8832
TEST_TIME 120 8764

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-11-2021 04:39:32 to 10-11-2021 04:39:36.

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 1480021 1480021 0 0.00 0 29237 7 0 0
doc_list_biud 1480014 1480014 0 0.00 0 40575 27 0 0
sp_add_invoice_to_stock 5243 5145 98 1.86 47 205524 18612 0 98
sp_cancel_adding_invoice 5157 5157 0 0.00 5 65409 926 0 0
sp_cancel_client_order 1760 1760 0 0.00 5 14309 324 0 0
sp_cancel_customer_reserve 502230 502230 0 0.00 1 40576 62 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 3821 65 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 8321 111 0 0
sp_cancel_supplier_invoice 5711 5707 4 0.07 2 27667 934 0 4
sp_cancel_supplier_order 386 386 0 0.00 8 934995 100139 0 0
sp_cancel_write_off 8315 8315 0 0.00 1 8356 124 0 0
sp_client_order 21015 21015 0 0.00 4 36892 387 0 0
sp_customer_reserve 464613 464613 0 0.00 0 90910 189 0 0
sp_fill_shopping_cart 32266 32266 0 0.00 1 22149 45 0 0
sp_fill_shopping_cart_clo_res 464602 464602 0 0.00 0 38655 17 0 0
sp_get_clo_for_invoice 5134 5134 0 0.00 0 28260 389 0 0
sp_kill_cost_storno 14585 14585 0 0.00 0 29229 59 0 0
sp_kill_qstorno_ret_qs2qd 1018868 1018868 0 0.00 0 40570 34 0 0
sp_kill_qty_storno 538778 538778 0 0.00 0 40574 73 0 0
sp_lock_selected_doc 21084 21084 0 0.00 0 8896 5 0 0
sp_make_cost_storno 28351 28351 0 0.00 0 17545 51 0 0
sp_multiply_rows_for_pdistr 28351 28351 0 0.00 0 8311 26 0 0
sp_multiply_rows_for_qdistr 21015 21015 0 0.00 0 36879 212 0 0
sp_pay_from_customer 7162 7162 0 0.00 4 14179 108 0 0
sp_pay_to_supplier 5965 5965 0 0.00 5 20346 130 0 0
sp_payment_common 13126 13126 0 0.00 1 7228 17 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 14301 295 0 0
sp_qd_handle_on_invoice_upd_sts 10290 10290 0 0.00 0 24364 323 0 0
sp_qd_handle_on_reserve_upd_sts 10090 10090 0 0.00 0 32018 66 0 0
sp_reserve_write_off 10090 10090 0 0.00 6 32048 185 0 0
sp_supplier_invoice 5587 5587 0 0.00 8 87705 3043 0 0
sp_supplier_order 5665 5665 0 0.00 5 40999 1393 0 0
srv_aggregate_perf_data 112 112 0 0.00 2406 207141 17725 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 4493 154306 52812 0 0
srv_find_qd_qs_mism 1002283 1002283 0 0.00 0 13979 7 0 0
srv_make_invnt_saldo 479 479 0 0.00 60 101838 3319 0 0
srv_make_money_saldo 722 722 0 0.00 1 20732 450 0 0
srv_recalc_idx_stat 5 5 0 0.00 254100 372762 303729 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5145 5145 0 0.00 0 16104 163 0 0
t$perf-norm:sp_cancel_adding_invoice 474 474 0 0.00 0 4833 99 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 5777 15 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 2153 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3490 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 1237 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 789 14 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 19614 1200 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 3952 12 0 0
t$perf-norm:sp_client_order 21015 21015 0 0.00 0 6208 16 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 2090 5 0 0
t$perf-norm:sp_pay_from_customer 7162 7162 0 0.00 0 21400 14 0 0
t$perf-norm:sp_pay_to_supplier 5965 5965 0 0.00 0 3742 10 0 0
t$perf-norm:sp_reserve_write_off 10090 10090 0 0.00 0 7560 12 0 0
t$perf-norm:sp_supplier_invoice 5587 5587 0 0.00 0 7210 18 0 0
t$perf-norm:sp_supplier_order 5665 5665 0 0.00 0 5935 15 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 529 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 5 1 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 1732 7 0 0
t$perf-norm:srv_make_money_saldo 722 722 0 0.00 0 829 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5334 1072 0 0
v_add_invoice_to_stock 10460 10264 196 1.87 0 4648 16 0 196
v_all_customers 38330 38330 0 0.00 0 78 1 0 0
v_all_suppliers 30978 30978 0 0.00 0 17 1 0 0
v_all_wares 42030 42030 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 948 948 0 0.00 0 1623 8 0 0
v_cancel_client_order 3520 3520 0 0.00 0 1619 4 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3790 7 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 1775 5 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 2917 16 0 0
v_cancel_supplier_invoice 810 802 8 0.98 0 1812 15 0 8
v_cancel_supplier_order 772 772 0 0.00 0 1619 6 0 0
v_cancel_write_off 2044 2044 0 0.00 0 2072 13 0 0
v_max_id_clo_ord 5665 5665 0 0.00 0 5738 3 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 4841 20 0 0
v_max_id_ord_sup 5586 5586 0 0.00 0 7899 7 0 0
v_max_non_paid_invoice 1727 1727 0 0.00 0 408 2 0 0
v_max_non_paid_realizn 3757 3757 0 0.00 0 1257 2 0 0
v_min_id_clo_ord 5665 5665 0 0.00 0 4473 7 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 2308 8 0 0
v_min_id_ord_sup 5586 5586 0 0.00 0 9793 11 0 0
v_min_non_paid_invoice 5965 5965 0 0.00 0 7223 12 0 0
v_min_non_paid_realizn 7161 7161 0 0.00 0 4524 8 0 0
v_our_firm 10508 10508 0 0.00 0 17 1 0 0
v_reserve_write_off 20180 20180 0 0.00 0 6913 7 0 0
x_lock_depdocs_on_canc_invoice 10463 10463 0 0.00 0 30356 108 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 3030 276 0 0
x_make_qstorno_1000_1200 5665 5665 0 0.00 2 40991 1309 0 0
x_make_qstorno_1200_2000 5587 5587 0 0.00 4 86577 2871 0 0
x_make_qstorno_2100_3300 464613 464613 0 0.00 0 90909 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2021 04:39:37 to 10-11-2021 04:39:41.

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
02:07:13 9 8192000000 8804720112 8818384896 9360592 0.114 4 1 2 0 9494800 71984 62224
02:08:13 4016 8192000000 9718414864 10012749824 801595392 8.912 100 89 178 0 830515840 7269440 21651008
02:09:24 14357 8192000000 9744380368 10046840832 821872672 9.117 100 86 172 0 852747184 10380544 20493968
02:10:49 23598 8192000000 9786261344 10102861824 849934720 9.399 100 81 162 0 888668816 19620736 19113360
02:12:19 14930 8192000000 9847698528 10180501504 907976592 9.977 100 77 154 1 956943360 31921024 17045744
02:13:41 7942 8192000000 9909024576 10254667776 946025296 10.352 100 77 154 1 1025890944 63148944 16716704
02:14:51 7629 8192000000 9950378112 10303930368 992486688 10.806 100 68 136 0 1063041024 56534384 14019952
02:16:01 5362 8192000000 9974114672 10340225024 1018674480 11.059 100 79 158 0 1095957760 61965568 15317712
02:17:08 2009 8192000000 10025018544 10390122496 1048155632 11.343 100 93 186 0 1129999696 63337328 18506736
02:18:12 1692 8192000000 10035373056 10407346176 1063584720 11.491 100 90 179 0 1144243872 62221008 18438144
02:19:15 2947 8192000000 10051246880 10430181376 1070676032 11.559 100 74 148 0 1165109392 78553456 15879904
02:20:23 7591 8192000000 10002555296 10394800128 1082872576 11.675 100 40 80 2 1116278688 24943904 8462208
02:21:31 71632 8192000000 9994312960 10399186944 1088653936 11.730 100 29 58 0 1115446272 20774320 6018016
02:23:47 9025 8192000000 10016399376 10430431232 1098163696 11.820 100 35 70 0 1128481120 21600928 8716496
02:24:57 6383 8192000000 10036413312 10446135296 1102536640 11.862 100 30 60 1 1156508896 47763680 6208576
02:26:04 42713 8192000000 10019141600 10413121536 1107327376 11.907 100 42 84 2 1127026608 10351856 9347376
02:27:56 5917 8192000000 10031146288 10429100032 1085712144 11.702 98 43 86 0 1125313280 30224816 9376320
02:29:04 7685 8192000000 9995330192 10399059968 1060899456 11.465 98 21 42 1 1081956096 16264208 4792432
02:30:16 2457 8192000000 9937756464 10307964928 996909040 10.849 96 56 110 3 1022918240 13722000 12287200
02:31:20 34006 8192000000 9956597792 10350727168 989938032 10.781 95 34 66 1 1020877040 23818944 7120064
02:32:54 8378 8192000000 9954894256 10341314560 1000690720 10.885 97 40 80 0 1036942112 27746944 8504448
02:34:04 8191 8192000000 9951542352 10341269504 1040774816 11.272 100 31 60 1 1081442192 33722976 6944400
02:35:13 6714 8192000000 9965987136 10366648320 1062942864 11.485 100 30 62 0 1090929664 20963600 7023200
02:36:23 36825 8192000000 9992505616 10393296896 1067976624 11.533 100 25 50 1 1094361984 20900176 5485184
02:38:09 43588 8192000000 10046366912 10440331264 1097474592 11.814 100 42 84 0 1152073984 45459184 9140208
02:39:58 22258 8192000000 10108977408 10513743872 1116709680 11.996 100 53 105 0 1210645136 82671696 11263760
02:41:33 14835 8192000000 10130831056 10534944768 1131139808 12.132 100 52 104 0 1233864944 91518656 11206480
02:42:51 14360 8192000000 10143609536 10550755328 1138522864 12.202 100 57 112 0 1250990784 100710448 11757472
02:44:15 8188 8192000000 10167450176 10577608704 1150517424 12.314 100 46 92 0 1276112592 115865200 9729968
02:45:25 7165 8192000000 10197680272 10606387200 1150565632 12.315 100 64 128 0 1303279648 139120432 13593584
02:46:40 22471 8192000000 10207912256 10631393280 1159756160 12.401 100 55 110 0 1305395376 133879776 11759440
02:48:09 5020 8192000000 10254934496 10668019712 1175107904 12.545 100 79 158 0 1360063744 167951024 17004816
02:49:18 15496 8192000000 10239458512 10671693824 1166372112 12.463 100 37 72 2 1351478528 176318848 8787568
02:50:36 6322 8192000000 10264141856 10688548864 1171381168 12.510 100 54 106 0 1369328688 185753120 12194400
02:51:47 4747 8192000000 10236903296 10660937728 1169805088 12.495 100 62 122 1 1337362256 153415808 14141360
02:52:56 5101 8192000000 10251397792 10657402880 1169083024 12.488 100 80 158 0 1345237104 158881792 17272288
02:54:04 4492 8192000000 10229535584 10643161088 1147518544 12.286 100 49 96 1 1330211872 171086496 11606832
02:55:12 6799 8192000000 10242350176 10662854656 1130322256 12.124 99 33 66 0 1341921136 203366960 8231920
02:56:22 37925 8192000000 10236099568 10674831360 1116660272 11.995 97 24 48 0 1337894096 215153968 6079856
02:58:03 9106 8192000000 10301489200 10724921344 1146164064 12.273 100 34 69 1 1396059504 241830592 8064848
02:59:19 18172 8192000000 10309453472 10766409728 1125250816 12.077 100 29 58 1 1439306000 306367472 7687712
03:01:03 20114 8192000000 10359679632 10785521664 1127529824 12.098 100 30 59 1 1462484976 327110144 7845008
03:02:24 32408 8192000000 10314820592 10761678848 1115106224 11.981 99 17 34 0 1409806032 290142416 4557392
03:04:00 37362 8192000000 10120580416 10552754176 1090956064 11.752 99 13 26 0 1311240624 217240640 3043920
03:05:38 144832 8192000000 10033143488 10454945792 1081190656 11.659 98 30 60 0 1099757408 12691008 5875744
03:09:04 154306 8192000000 10096276256 10550259712 1064981840 11.504 95 13 26 1 1123449728 55578672 2889216
03:12:38 29538 8192000000 10065921104 10558812160 1100344416 11.841 98 11 23 0 1213179824 110400032 2435376
03:14:19 134749 8192000000 10143045296 10599755776 1124532960 12.070 99 26 52 0 1236602320 105918544 6150816
03:17:48 97424 8192000000 10101422944 10566397952 1133385920 12.153 99 21 42 1 1211726384 73535952 4804512
03:20:28 30971 8192000000 10060721424 10511237120 1134480800 12.164 99 18 36 0 1154759728 16785936 3492992
03:21:59 26592 8192000000 10076841296 10508918784 1140983632 12.225 100 41 80 0 1180192256 31023728 8184896
03:23:33 44859 8192000000 10102057152 10543792128 1146566688 12.277 100 27 55 0 1204088224 51947376 5574160
03:25:24 91924 8192000000 10160326128 10674135040 1159284176 12.397 100 35 70 0 1272882928 106127648 7471104
03:28:18 105940 8192000000 10152429584 10628243456 1155397984 12.360 100 15 30 1 1196928368 38422800 3107584
03:31:05 63630 8192000000 10093582992 10573377536 1136375120 12.181 98 8 16 0 1231055152 93123440 1556592
03:33:09 138713 8192000000 10151251920 10649792512 1150398800 12.313 99 24 47 1 1245576016 89648864 5528352
03:36:48 90145 8192000000 10056439728 10539380736 1101112240 11.848 96 14 28 0 1136655184 32387760 3155184
03:39:20 48992 8192000000 10092671104 10547941376 1149574752 12.306 100 21 42 0 1192041776 38060368 4406656
03:41:11 50486 8192000000 10064711296 10532380672 1140416704 12.219 99 16 32 0 1169356496 25088864 3850928
03:43:01 43405 8192000000 10094037936 10561359872 1152043616 12.329 100 20 41 3 1199621856 43997744 3580496
03:44:46 50331 8192000000 10075368112 10593280000 1129655120 12.118 99 7 14 0 1184689776 53652736 1381920
03:46:44 27662 8192000000 10059816224 10490388480 1109992608 11.932 97 18 36 0 1148414384 34745088 3676688
03:48:15 43399 8192000000 10082599616 10509504512 1074991008 11.600 95 26 51 0 1139253136 58928544 5333584
03:50:02 26774 8192000000 10058096640 10486525952 1052020368 11.380 95 57 112 2 1104701872 41410640 11270864
03:51:30 16484 8192000000 10058786624 10475462656 1090151408 11.744 99 50 100 1 1149428848 49362448 9914992
03:52:47 106666 8192000000 10049698256 10509201408 1028002816 11.149 93 24 48 0 1083886000 51230544 4652640
03:55:43 72275 8192000000 10038545248 10512961536 1090093696 11.744 99 34 68 1 1162131440 64914688 7123056
03:58:14 135084 8192000000 10009439136 10445357056 1066441664 11.518 96 28 56 0 1094140640 21617344 6081632
04:01:53 52467 8192000000 10054342240 10509590528 1108537664 11.919 100 8 15 1 1155241104 45048432 1655008
04:04:39 47510 8192000000 10088739216 10546135040 1122493184 12.051 100 19 38 0 1184848016 58295872 4058960
04:06:33 33149 8192000000 10118459296 10554093568 1126381376 12.087 99 35 70 0 1201649568 67384224 7883968
04:08:07 77641 8192000000 10098490560 10553229312 1113937168 11.970 98 25 51 0 1189926672 70512320 5477184
04:10:36 31431 8192000000 10042953984 10473861120 1092828704 11.770 97 17 33 1 1115777504 19503664 3445136
04:12:12 36862 8192000000 10100998064 10557100032 1126996416 12.093 99 32 64 2 1191562496 57888992 6677088
04:13:55 89706 8192000000 10108180016 10573844480 1130751216 12.128 98 12 24 0 1165438032 32190544 2496272
04:16:25 62981 8192000000 10097229536 10536939520 1152812608 12.336 100 46 89 3 1185979216 23733568 9433040
04:18:38 79482 8192000000 10132004912 10592043008 1157192544 12.377 100 8 16 0 1230649392 71694368 1762480
04:21:15 84985 8192000000 10137440704 10614415360 1139506848 12.211 98 26 53 0 1225935520 81473888 4954784
04:23:40 70544 8192000000 10123996128 10583613440 1162212112 12.424 100 27 54 0 1238452144 70578592 5661440
04:25:55 49192 8192000000 10137315712 10582249472 1134981072 12.168 98 25 50 0 1206000880 65767856 5251952
04:27:44 122151 8192000000 10067062832 10555322368 1096190064 11.801 96 13 26 0 1175576240 76980640 2405536
04:30:46 53386 8192000000 10040182512 10468433920 1074585040 11.596 94 22 42 2 1105347072 26865152 3896880
04:32:41 119464 8192000000 10071064256 10526515200 1110491440 11.937 98 21 42 0 1185239552 70257696 4490416
04:35:43 46607 8192000000 10071900400 10521231360 1109121616 11.924 97 22 44 1 1172373520 57757696 5494208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-11-2021 04:39:43 to 10-11-2021 04:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-11-2021 04:39:58 to 10-11-2021 04:40:00.

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          232356
MON$OLDEST_ACTIVE               235302
MON$OLDEST_SNAPSHOT             235302
MON$NEXT_TRANSACTION            235302
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                       2023552
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             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2654 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2654 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2654 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2654 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Wed Nov 10 04:37:21 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Nov 10 04:37:28 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Wed Nov 10 04:37:31 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Wed Nov 10 04:37:31 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
--- end of diff output ---

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

10.11.21 04:40:01. 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-11-10 04:40:03 No severe PSQL-related problems occured
10.11.21 04:40:04. End of report.