Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.01.2022 04:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 15950636 kB
MemAvailable 31219308 kB
Buffers 20 kB
Cached 15644892 kB
SwapCached 2312 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 2022-01-15 04:37:13
DTS_END                         2022-01-15 04:37:13.0390
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/44502
TRN_ID                          207297
ATT_ID                          619
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: 8581.33 24525 1029760
customer order: creation 159.39 496 19127
customer order: refuse 12.86 457 1543
order to supplier: creation 41.90 1502 5028
order to supplier: removal 3.32 186305 398
invoice (draft): creation 42.45 3314 5094
invoice (draft): removal 50.58 1078 6069
invoice accept: apply 38.40 28514 4608
invoice accept: cancel 44.88 1397 5386
customer reserve: creation 3560.40 269 427248
customer reserve: removal 4355.21 110 522625
realization accept: apply 76.77 301 9212
realization accept: cancel 76.91 182 9229
payment from customer: creation 54.12 193 6494
payment from customer: removal 5.16 157 619
payment to supplier: creation 44.46 215 5335
payment to supplier: removal 2.98 187 357
service: total inventory turnovers 3.49 4802 419
service: total monetary turnovers 7.13 404 855
service: aggregate perf. data 0.91 17476 109
service: refresh index statistics 0.04 243132 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-01-2022 04:38:24 to 15-01-2022 04:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8208
TEST_TIME 2 6185
TEST_TIME 3 6931
TEST_TIME 4 6047
TEST_TIME 5 6088
TEST_TIME 6 6042
TEST_TIME 7 6162
TEST_TIME 8 6089
TEST_TIME 9 6404
TEST_TIME 10 6505
TEST_TIME 11 6666
TEST_TIME 12 6624
TEST_TIME 13 6594
TEST_TIME 14 6906
TEST_TIME 15 6850
TEST_TIME 16 6840
TEST_TIME 17 6871
TEST_TIME 18 7071
TEST_TIME 19 7055
TEST_TIME 20 7260
TEST_TIME 21 7276
TEST_TIME 22 7236
TEST_TIME 23 7351
TEST_TIME 24 7380
TEST_TIME 25 7511
TEST_TIME 26 7577
TEST_TIME 27 8286
TEST_TIME 28 8399
TEST_TIME 29 8580
TEST_TIME 30 8529
TEST_TIME 31 8760
TEST_TIME 32 9195
TEST_TIME 33 9545
TEST_TIME 34 9923
TEST_TIME 35 9871
TEST_TIME 36 10081
TEST_TIME 37 10065
TEST_TIME 38 10005
TEST_TIME 39 10405
TEST_TIME 40 10355
TEST_TIME 41 10193
TEST_TIME 42 9989
TEST_TIME 43 9884
TEST_TIME 44 9841
TEST_TIME 45 9995
TEST_TIME 46 9880
TEST_TIME 47 9788
TEST_TIME 48 9802
TEST_TIME 49 9718
TEST_TIME 50 9668
TEST_TIME 51 9539
TEST_TIME 52 9528
TEST_TIME 53 9515
TEST_TIME 54 9567
TEST_TIME 55 9464
TEST_TIME 56 9384
TEST_TIME 57 9340
TEST_TIME 58 9211
TEST_TIME 59 9146
TEST_TIME 60 9108
TEST_TIME 61 9125
TEST_TIME 62 9107
TEST_TIME 63 9105
TEST_TIME 64 9098
TEST_TIME 65 9053
TEST_TIME 66 9058
TEST_TIME 67 8992
TEST_TIME 68 9095
TEST_TIME 69 9087
TEST_TIME 70 9001
TEST_TIME 71 8986
TEST_TIME 72 8981
TEST_TIME 73 8951
TEST_TIME 74 9088
TEST_TIME 75 9023
TEST_TIME 76 8996
TEST_TIME 77 9037
TEST_TIME 78 9066
TEST_TIME 79 8977
TEST_TIME 80 8939
TEST_TIME 81 8873
TEST_TIME 82 8843
TEST_TIME 83 8827
TEST_TIME 84 8805
TEST_TIME 85 8765
TEST_TIME 86 8775
TEST_TIME 87 8699
TEST_TIME 88 8685
TEST_TIME 89 8644
TEST_TIME 90 8645
TEST_TIME 91 8634
TEST_TIME 92 8664
TEST_TIME 93 8657
TEST_TIME 94 8640
TEST_TIME 95 8714
TEST_TIME 96 8722
TEST_TIME 97 8705
TEST_TIME 98 8715
TEST_TIME 99 8699
TEST_TIME 100 8742
TEST_TIME 101 8904
TEST_TIME 102 8871
TEST_TIME 103 8908
TEST_TIME 104 8862
TEST_TIME 105 8829
TEST_TIME 106 8799
TEST_TIME 107 8801
TEST_TIME 108 8792
TEST_TIME 109 8785
TEST_TIME 110 8832
TEST_TIME 111 8799
TEST_TIME 112 8768
TEST_TIME 113 8752
TEST_TIME 114 8694
TEST_TIME 115 8660
TEST_TIME 116 8641
TEST_TIME 117 8608
TEST_TIME 118 8629
TEST_TIME 119 8649
TEST_TIME 120 8609

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-01-2022 04:38:28 to 15-01-2022 04:38:31.

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 1423166 1423166 0 0.00 0 19414 14 0 0
doc_list_biud 1423159 1423159 0 0.00 0 52130 47 0 0
sp_add_invoice_to_stock 4698 4608 90 1.91 27 219306 28515 0 90
sp_cancel_adding_invoice 5386 5386 0 0.00 6 95269 1397 0 0
sp_cancel_client_order 1543 1543 0 0.00 6 6889 457 0 0
sp_cancel_customer_reserve 522625 522625 0 0.00 1 47591 111 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 4 5739 157 0 0
sp_cancel_pay_to_supplier 357 357 0 0.00 4 10183 188 0 0
sp_cancel_supplier_invoice 6081 6069 12 0.19 3 52149 1078 0 12
sp_cancel_supplier_order 398 398 0 0.00 15 1450858 186305 0 0
sp_cancel_write_off 9229 9229 0 0.00 2 27655 182 0 0
sp_client_order 19127 19127 0 0.00 6 42350 496 0 0
sp_customer_reserve 427248 427248 0 0.00 1 62820 269 0 0
sp_fill_shopping_cart 29242 29242 0 0.00 2 19747 88 0 0
sp_fill_shopping_cart_clo_res 427227 427227 0 0.00 0 33342 23 0 0
sp_get_clo_for_invoice 4563 4563 0 0.00 0 29984 351 0 0
sp_kill_cost_storno 15591 15591 0 0.00 0 19410 85 0 0
sp_kill_qstorno_ret_qs2qd 1060946 1060946 0 0.00 0 52128 48 0 0
sp_kill_qty_storno 559024 559024 0 0.00 0 52130 111 0 0
sp_lock_selected_doc 18969 18969 0 0.00 0 8644 8 0 0
sp_make_cost_storno 25604 25604 0 0.00 0 12800 82 0 0
sp_multiply_rows_for_pdistr 25603 25603 0 0.00 0 11475 43 0 0
sp_multiply_rows_for_qdistr 19127 19127 0 0.00 0 11681 183 0 0
sp_pay_from_customer 6494 6494 0 0.00 6 12821 193 0 0
sp_pay_to_supplier 5335 5335 0 0.00 7 14741 216 0 0
sp_payment_common 11827 11827 0 0.00 2 9694 49 0 0
sp_qd_handle_on_cancel_clo 1543 1543 0 0.00 0 5539 381 0 0
sp_qd_handle_on_invoice_upd_sts 9949 9949 0 0.00 0 33594 595 0 0
sp_qd_handle_on_reserve_upd_sts 9211 9211 0 0.00 0 15269 72 0 0
sp_reserve_write_off 9212 9212 0 0.00 8 15292 301 0 0
sp_supplier_invoice 5094 5094 0 0.00 8 73525 3315 0 0
sp_supplier_order 5028 5028 0 0.00 9 44697 1503 0 0
srv_aggregate_perf_data 109 109 0 0.00 2231 158943 17476 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 748 120529 35270 0 0
srv_find_qd_qs_mism 984703 984703 0 0.00 0 19410 10 0 0
srv_make_invnt_saldo 419 419 0 0.00 43 64876 4803 0 0
srv_make_money_saldo 855 855 0 0.00 1 16323 405 0 0
srv_recalc_idx_stat 5 5 0 0.00 50749 531523 243132 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4608 4608 0 0.00 0 14779 1047 0 0
t$perf-norm:sp_cancel_adding_invoice 351 351 0 0.00 1 12778 665 0 0
t$perf-norm:sp_cancel_client_order 1543 1543 0 0.00 0 982 30 0 0
t$perf-norm:sp_cancel_customer_reserve 610 610 0 0.00 0 3731 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 3479 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 357 357 0 0.00 0 688 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1099 40 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 108979 7754 0 0
t$perf-norm:sp_cancel_write_off 918 918 0 0.00 0 10144 35 0 0
t$perf-norm:sp_client_order 19127 19127 0 0.00 0 30543 43 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1750 16 0 0
t$perf-norm:sp_pay_from_customer 6494 6494 0 0.00 0 5713 22 0 0
t$perf-norm:sp_pay_to_supplier 5335 5335 0 0.00 0 8704 22 0 0
t$perf-norm:sp_reserve_write_off 9212 9212 0 0.00 0 6867 27 0 0
t$perf-norm:sp_supplier_invoice 5094 5094 0 0.00 0 8831 37 0 0
t$perf-norm:sp_supplier_order 5028 5028 0 0.00 0 2812 37 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 2088 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 198 6 0 0
t$perf-norm:srv_make_invnt_saldo 419 419 0 0.00 0 902 19 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 912 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 14 204 109 0 0
v_add_invoice_to_stock 9302 9122 180 1.93 0 6464 30 0 180
v_all_customers 34860 34860 0 0.00 0 260 4 0 0
v_all_suppliers 28110 28110 0 0.00 0 105 4 0 0
v_all_wares 38252 38252 0 0.00 0 103 4 0 0
v_cancel_adding_invoice 702 702 0 0.00 0 5771 21 0 0
v_cancel_client_order 3086 3086 0 0.00 0 1317 10 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 1907 14 0 0
v_cancel_customer_reserve 1220 1220 0 0.00 0 2682 14 0 0
v_cancel_payment_to_supplier 714 714 0 0.00 0 745 11 0 0
v_cancel_supplier_invoice 824 800 24 2.91 0 3555 32 0 24
v_cancel_supplier_order 796 796 0 0.00 0 2656 21 0 0
v_cancel_write_off 1836 1836 0 0.00 0 5317 30 0 0
v_max_id_clo_ord 5028 5028 0 0.00 0 4724 7 0 0
v_max_id_clo_res 972 972 0 0.00 0 3357 20 0 0
v_max_id_ord_sup 5087 5087 0 0.00 0 7060 11 0 0
v_max_non_paid_invoice 1395 1395 0 0.00 0 207 8 0 0
v_max_non_paid_realizn 3394 3394 0 0.00 0 1212 8 0 0
v_min_id_clo_ord 5028 5028 0 0.00 0 19742 16 0 0
v_min_id_clo_res 972 972 0 0.00 0 1123 10 0 0
v_min_id_ord_sup 5087 5087 0 0.00 0 3591 8 0 0
v_min_non_paid_invoice 5335 5335 0 0.00 0 9682 20 0 0
v_min_non_paid_realizn 6492 6492 0 0.00 0 5867 19 0 0
v_our_firm 9588 9588 0 0.00 0 197 5 0 0
v_reserve_write_off 18422 18422 0 0.00 0 2871 10 0 0
x_lock_depdocs_on_canc_invoice 11055 11055 0 0.00 0 14355 126 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4862 397 0 0
x_make_qstorno_1000_1200 5028 5028 0 0.00 3 44686 1311 0 0
x_make_qstorno_1200_2000 5094 5094 0 0.00 3 70517 3078 0 0
x_make_qstorno_2100_3300 427248 427248 0 0.00 0 62815 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-01-2022 04:38:32 to 15-01-2022 04:38:36.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:17 18857 8192000000 9723731312 10020691968 805856336 8.956 100 86 172 0 834314112 7541776 20916000
02:09:46 30182 8192000000 9770756928 10074443776 839477840 9.295 100 92 184 0 874790272 13410976 21901456
02:11:25 17415 8192000000 9798121456 10111975424 859537472 9.496 100 84 168 0 901658400 22473136 19647792
02:12:58 6706 8192000000 9870065792 10199298048 912840880 10.025 100 87 174 0 987086368 55192016 19053472
02:14:10 6486 8192000000 9930062608 10270232576 958653648 10.476 100 85 168 0 1046351008 70679264 17018096
02:15:19 2884 8192000000 9971050432 10322788352 991655440 10.798 100 87 169 2 1082167456 73163056 17348960
02:16:25 2461 8192000000 9986891200 10344288256 1018324080 11.056 100 91 173 2 1107014304 71064016 17626208
02:17:30 958 8192000000 10012104352 10377162752 1040616224 11.271 100 99 197 0 1131095008 71455024 19023760
02:18:33 845 8192000000 10027007184 10400890880 1052560640 11.385 100 94 181 0 1150094080 79068224 18465216
02:19:36 1148 8192000000 10059664864 10434863104 1066063136 11.514 100 97 191 0 1181648640 96307360 19278144
02:20:39 1042 8192000000 10040239760 10418585600 1066604688 11.520 100 99 192 0 1159731856 74639984 18487184
02:21:44 1415 8192000000 10042311520 10421649408 1077023568 11.619 100 95 182 2 1160580752 64776048 18781136
02:22:47 1119 8192000000 10065983952 10450071552 1080861584 11.656 100 97 195 0 1185488736 84539408 20087744
02:23:50 1062 8192000000 10054602208 10439909376 1086572992 11.710 100 83 163 0 1174973024 71793392 16606640
02:24:52 1274 8192000000 10060244704 10444460032 1094230864 11.783 100 95 177 1 1187250032 74628992 18390176
02:25:55 1290 8192000000 10069450752 10459353088 1094193920 11.783 100 96 186 3 1193948688 81129296 18625472
02:26:58 1210 8192000000 10062873536 10445225984 1091867856 11.760 100 94 183 0 1186122736 74453824 19801056
02:28:00 6614 8192000000 10059696480 10445230080 1096838912 11.808 100 85 157 1 1178580528 67116672 14624944
02:29:07 1000 8192000000 10045388688 10433982464 1095926176 11.799 100 92 172 0 1168212320 53185488 19100656
02:30:09 1045 8192000000 10035943232 10421141504 1088545632 11.729 100 89 175 0 1154084512 48792880 16746000
02:31:20 1796 8192000000 10024624432 10401435648 1067312704 11.526 100 87 158 3 1141831680 58770688 15748288
02:32:23 1086 8192000000 10041428592 10422276096 1070497472 11.557 100 93 179 0 1162437888 73427184 18513232
02:33:26 1260 8192000000 9976242592 10348040192 1045173248 11.314 100 90 162 1 1097697280 34991872 17532160
02:34:28 1087 8192000000 9987632880 10353647616 1024844320 11.119 99 94 185 0 1107065056 63852176 18368560
02:35:30 1352 8192000000 10003895536 10379100160 1043681952 11.300 100 81 152 6 1120475328 62534176 14259200
02:36:34 1015 8192000000 10009070512 10378371072 1039972416 11.264 100 91 178 0 1126900272 67513104 19414752
02:37:37 955 8192000000 10000867600 10373222400 1049403568 11.355 100 92 159 5 1124469616 58847168 16218880
02:38:50 8762 8192000000 10003904352 10385784832 1055462384 11.413 99 63 120 1 1114671584 47821840 11387360
02:40:03 1392 8192000000 10049717184 10424340480 1056946624 11.427 100 86 158 2 1167855824 94926352 15982848
02:41:13 5560 8192000000 10059737760 10441809920 1083406256 11.680 100 70 136 0 1181452656 83651424 14394976
02:42:25 2070 8192000000 10080780816 10475859968 1094857408 11.789 100 88 161 0 1204183968 91096784 18229776
02:43:30 1799 8192000000 10118116320 10502144000 1106305424 11.897 100 84 161 1 1234764112 111269536 17189152
02:44:36 1645 8192000000 10132165536 10530742272 1117278928 12.001 100 89 170 2 1253173024 116050416 19843680
02:45:41 2932 8192000000 10167729280 10555703296 1130741472 12.128 100 83 162 2 1279419216 131230784 17446960
02:46:49 2580 8192000000 10208325488 10608480256 1142110896 12.235 100 93 181 0 1322731744 160580256 20040592
02:47:55 870 8192000000 10217691936 10617475072 1152793088 12.336 100 88 165 1 1331029984 161158656 17078240
02:48:58 1525 8192000000 10260483184 10655023104 1158302896 12.387 100 87 171 0 1373262544 196279440 18680208
02:50:06 2846 8192000000 10302619392 10702307328 1172658176 12.522 100 84 162 1 1417887824 226366160 18863488
02:51:14 1112 8192000000 10309981424 10734186496 1170294528 12.500 100 89 175 0 1419986048 229958080 19733440
02:52:20 828 8192000000 10344963376 10742083584 1177921728 12.571 100 96 183 2 1451631840 252872720 20837392
02:53:26 950 8192000000 10390922304 10792558592 1185902992 12.645 100 96 189 1 1495147424 287879072 21365360
02:54:31 1573 8192000000 10433681216 10856792064 1200093168 12.777 100 92 181 1 1540155152 317951168 22110816
02:55:38 1024 8192000000 10461886832 10875105280 1203752800 12.811 100 96 186 0 1567270400 340505328 23012272
02:56:45 1677 8192000000 10485228048 10900377600 1210152112 12.871 100 96 185 0 1590347424 356847568 23347744
02:57:50 743 8192000000 10482415168 10904514560 1204949792 12.822 100 93 179 0 1588195872 362325200 20920880
02:58:54 2084 8192000000 10549601872 10961620992 1199167376 12.769 100 81 160 0 1654707200 435798992 19740832
03:00:01 1543 8192000000 10586656208 11012296704 1205206752 12.825 100 96 189 0 1691900896 463700048 22994096
03:01:10 1382 8192000000 10579744496 11004112896 1193477888 12.716 100 94 179 2 1681211888 466586240 21147760
03:02:13 1232 8192000000 10607637168 11054829568 1204109424 12.814 100 93 176 0 1714080784 488671504 21299856
03:03:23 1759 8192000000 10578312864 11070017536 1194372080 12.724 100 77 154 0 1681895088 468361712 19161296
03:04:27 4036 8192000000 10548130448 10991071232 1176850272 12.561 99 62 118 0 1658343392 465487552 16005568
03:05:34 23012 8192000000 10482841504 10933501952 1167236400 12.471 100 38 76 1 1597033424 419831472 9965552
03:07:22 17293 8192000000 10471736848 10931388416 1149639824 12.306 99 30 60 1 1579622160 421589456 8392880
03:08:41 17791 8192000000 10397984512 10919981056 1157407552 12.379 100 37 74 0 1568091872 400945776 9738544
03:10:32 9272 8192000000 10285845680 10817355776 1143433760 12.248 100 35 70 0 1393543168 242131440 7977968
03:11:45 4672 8192000000 10234325184 10686070784 1140041920 12.216 100 56 109 3 1328124224 174584240 13498064
03:13:00 18333 8192000000 10232179088 10716860416 1147390048 12.285 100 31 60 1 1310749424 156268560 7090816
03:14:22 23069 8192000000 10214342416 10643087360 1160284208 12.406 100 48 79 2 1313748032 143933344 9530480
03:15:48 46328 8192000000 10201900352 10685935616 1117756048 12.006 97 18 36 0 1320352640 198058080 4538512
03:17:51 39078 8192000000 10219226480 10650800128 1160455440 12.408 100 68 130 0 1295832080 121134944 14241696
03:19:32 85715 8192000000 10211573280 10673737728 1131710240 12.137 98 50 96 2 1310235360 167831392 10693728
03:22:35 41755 8192000000 10124026784 10566635520 1137411472 12.191 100 14 28 0 1237764416 96502960 3849984
03:24:37 57452 8192000000 10096673760 10598510592 1126690752 12.090 99 11 21 0 1192573920 63527072 2356096
03:27:09 81895 8192000000 10146056560 10595893248 1122690480 12.052 98 20 40 0 1221713200 93684368 5338352
03:29:38 62309 8192000000 10124963600 10605682688 1115196336 11.982 98 22 45 3 1233187680 113159616 4831728
03:31:47 56789 8192000000 10070011488 10536452096 1103466992 11.871 97 16 32 2 1153868832 47136528 3265312
03:33:44 75446 8192000000 10034847408 10511958016 1074251520 11.593 96 20 38 1 1100885360 23276352 3357488
03:37:07 59073 8192000000 10137044704 10622320640 1118981648 12.017 99 30 60 0 1254205664 128308992 6915024
03:39:08 72444 8192000000 10101639664 10581110784 1108331024 11.917 98 21 42 0 1188388624 75349728 4707872
03:42:28 65866 8192000000 10079868160 10551009280 1093240496 11.773 97 20 40 1 1171175872 73993872 3941504
03:44:35 94383 8192000000 10092956864 10588741632 1083406384 11.680 96 15 29 1 1160204320 73588544 3209392
03:47:20 15193 8192000000 10047298336 10477342720 1109908960 11.932 99 47 90 3 1134822704 15146192 9767552
03:48:57 16995 8192000000 10124366528 10558623744 1139702912 12.213 100 51 102 3 1216735392 67008816 10023664
03:50:16 107548 8192000000 10065282816 10536923136 1129433360 12.116 99 20 40 1 1220673088 87045536 4194192
03:53:10 31118 8192000000 10094927104 10527744000 1116661344 11.995 98 53 104 2 1175071888 48768816 9641728
03:54:43 18964 8192000000 10105979920 10548801536 1135346640 12.172 100 28 56 0 1211077312 70026176 5704496
03:56:10 120529 8192000000 10072029440 10538700800 1113883248 11.969 98 35 70 1 1177616992 56463440 7270304
03:59:12 43451 8192000000 10112849456 10564575232 1130610176 12.127 99 30 58 0 1184832512 47931072 6291264
04:00:58 54852 8192000000 10063631920 10531352576 1098429696 11.823 96 12 24 0 1168198320 67494336 2274288
04:02:58 73614 8192000000 10077754656 10563399680 1141217040 12.227 100 16 32 1 1195074240 50124208 3732992
04:05:22 80754 8192000000 10081431392 10576736256 1125949136 12.083 99 16 32 2 1173663248 44242816 3471296
04:07:48 53457 8192000000 10107945104 10560208896 1130247568 12.124 99 26 52 1 1182651952 46668608 5735776
04:09:42 40090 8192000000 10093221680 10554949632 1118753312 12.015 97 19 38 0 1183668752 60574304 4341136
04:11:25 26045 8192000000 10150368256 10588102656 1137080480 12.188 99 40 80 0 1246559008 100534192 8944336
04:12:54 61081 8192000000 10094129712 10550452224 1103697536 11.873 97 23 47 2 1188382928 79903584 4781808
04:14:59 30845 8192000000 10130853200 10554347520 1112606768 11.957 98 38 76 2 1192245184 71458800 8179616
04:16:30 70850 8192000000 10173873280 10668486656 1134325104 12.162 99 23 46 2 1280978848 141360368 5293376
04:18:46 104984 8192000000 10104601104 10574102528 1104763232 11.883 97 14 28 1 1151875408 43622960 3489216
04:21:36 60746 8192000000 10129420096 10584829952 1121360768 12.040 99 57 114 2 1201162416 68136640 11665008
04:23:37 56250 8192000000 10122436608 10634989568 1114716000 11.977 98 18 36 1 1227916176 109489040 3711136
04:25:34 90153 8192000000 10123750640 10634817536 1123442464 12.060 99 20 40 0 1225343872 97524288 4377120
04:28:13 76886 8192000000 10039857072 10494283776 1114767520 11.978 98 14 28 0 1147258560 29408592 3082448
04:30:30 62869 8192000000 10039466960 10486423552 1112151136 11.953 98 9 18 0 1139586160 25548768 1886256
04:32:50 76505 8192000000 10076574592 10541932544 1095742992 11.797 96 18 34 1 1153252560 53968688 3540880
04:35:19 54689 8192000000 10083967120 10534912000 1105362656 11.888 98 14 28 0 1164052928 55620464 3069808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-01-2022 04:38:38 to 15-01-2022 04:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-01-2022 04:38:55 to 15-01-2022 04:38:57.

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          206915
MON$OLDEST_ACTIVE               207528
MON$OLDEST_SNAPSHOT             207528
MON$NEXT_TRANSACTION            207528
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                       2083168
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             757
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:505: 
:506: mscs-test-161.budzdorov.ru    Sat Jan 15 04:37:16 2022
:507:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:508: 
:509: 
:510: mscs-test-161.budzdorov.ru    Sat Jan 15 04:37:20 2022
:511:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:512: 
:513: 
:514: mscs-test-161.budzdorov.ru    Sat Jan 15 04:37:20 2022
:515:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:516: 
:517: 
:518: mscs-test-161.budzdorov.ru    Sat Jan 15 04:37:20 2022
:519:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:520: 
:521: 
:522: mscs-test-161.budzdorov.ru    Sat Jan 15 04:37:20 2022
:523:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:524: 
--- end of diff output ---

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

15.01.22 04:38:58. 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
2022-01-15 04:39:00 No severe PSQL-related problems occured
15.01.22 04:39:02. End of report.