Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2021 10: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 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 15898568 kB
MemAvailable 30709816 kB
Buffers 0 kB
Cached 15836952 kB
SwapCached 3780 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-10-14 10:37:08
DTS_END                         2021-10-14 10:37:08.5510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41430
TRN_ID                          234755
ATT_ID                          623
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: 8722.02 23200 1046642
customer order: creation 164.18 385 19701
customer order: refuse 14.50 343 1740
order to supplier: creation 44.54 1449 5345
order to supplier: removal 3.15 110783 378
invoice (draft): creation 44.78 3831 5373
invoice (draft): removal 48.77 909 5852
invoice accept: apply 40.78 18665 4894
invoice accept: cancel 44.50 786 5340
customer reserve: creation 3669.36 189 440323
customer reserve: removal 4357.48 62 522898
realization accept: apply 81.72 201 9806
realization accept: cancel 83.79 128 10055
payment from customer: creation 57.99 114 6959
payment from customer: removal 5.62 87 674
payment to supplier: creation 47.60 139 5712
payment to supplier: removal 3.29 54 395
service: total inventory turnovers 3.28 4077 394
service: total monetary turnovers 5.87 547 704
service: aggregate perf. data 0.79 21882 95
service: refresh index statistics 0.03 299361 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2021 10:39:08 to 14-10-2021 10:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4318
TEST_TIME 2 4694
TEST_TIME 3 4807
TEST_TIME 4 5100
TEST_TIME 5 4934
TEST_TIME 6 5182
TEST_TIME 7 5411
TEST_TIME 8 5366
TEST_TIME 9 5615
TEST_TIME 10 6089
TEST_TIME 11 6081
TEST_TIME 12 6412
TEST_TIME 13 6610
TEST_TIME 14 6577
TEST_TIME 15 6604
TEST_TIME 16 6884
TEST_TIME 17 7290
TEST_TIME 18 7614
TEST_TIME 19 7546
TEST_TIME 20 8051
TEST_TIME 21 8736
TEST_TIME 22 8684
TEST_TIME 23 8988
TEST_TIME 24 8860
TEST_TIME 25 9275
TEST_TIME 26 10084
TEST_TIME 27 10834
TEST_TIME 28 10593
TEST_TIME 29 10325
TEST_TIME 30 10165
TEST_TIME 31 10070
TEST_TIME 32 9891
TEST_TIME 33 9793
TEST_TIME 34 9688
TEST_TIME 35 9661
TEST_TIME 36 9487
TEST_TIME 37 9466
TEST_TIME 38 9388
TEST_TIME 39 9288
TEST_TIME 40 9180
TEST_TIME 41 9117
TEST_TIME 42 9006
TEST_TIME 43 9126
TEST_TIME 44 9116
TEST_TIME 45 9042
TEST_TIME 46 9100
TEST_TIME 47 9096
TEST_TIME 48 9055
TEST_TIME 49 9250
TEST_TIME 50 9156
TEST_TIME 51 9144
TEST_TIME 52 9181
TEST_TIME 53 9293
TEST_TIME 54 9278
TEST_TIME 55 9304
TEST_TIME 56 9192
TEST_TIME 57 9212
TEST_TIME 58 9147
TEST_TIME 59 9110
TEST_TIME 60 8991
TEST_TIME 61 9020
TEST_TIME 62 8942
TEST_TIME 63 8995
TEST_TIME 64 8979
TEST_TIME 65 8972
TEST_TIME 66 8960
TEST_TIME 67 9051
TEST_TIME 68 9038
TEST_TIME 69 9099
TEST_TIME 70 9247
TEST_TIME 71 9222
TEST_TIME 72 9199
TEST_TIME 73 9190
TEST_TIME 74 9319
TEST_TIME 75 9283
TEST_TIME 76 9171
TEST_TIME 77 9102
TEST_TIME 78 9061
TEST_TIME 79 9115
TEST_TIME 80 9044
TEST_TIME 81 8995
TEST_TIME 82 8941
TEST_TIME 83 8878
TEST_TIME 84 8901
TEST_TIME 85 8883
TEST_TIME 86 8903
TEST_TIME 87 8906
TEST_TIME 88 8885
TEST_TIME 89 8977
TEST_TIME 90 8979
TEST_TIME 91 8957
TEST_TIME 92 8952
TEST_TIME 93 8956
TEST_TIME 94 9018
TEST_TIME 95 8970
TEST_TIME 96 9008
TEST_TIME 97 8979
TEST_TIME 98 8927
TEST_TIME 99 8906
TEST_TIME 100 8906
TEST_TIME 101 8861
TEST_TIME 102 8863
TEST_TIME 103 8854
TEST_TIME 104 8875
TEST_TIME 105 8865
TEST_TIME 106 8859
TEST_TIME 107 8871
TEST_TIME 108 8879
TEST_TIME 109 8910
TEST_TIME 110 8903
TEST_TIME 111 8891
TEST_TIME 112 8914
TEST_TIME 113 8963
TEST_TIME 114 8987
TEST_TIME 115 8934
TEST_TIME 116 8878
TEST_TIME 117 8860
TEST_TIME 118 8837
TEST_TIME 119 8789
TEST_TIME 120 8723

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2021 10:39:12 to 14-10-2021 10:39:15.

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 1455022 1455022 0 0.00 0 16294 7 0 0
doc_list_biud 1455022 1455022 0 0.00 0 71661 29 0 0
sp_add_invoice_to_stock 5007 4894 113 2.25 28 190075 18666 0 113
sp_cancel_adding_invoice 5340 5340 0 0.00 5 75633 786 0 0
sp_cancel_client_order 1740 1740 0 0.00 4 18437 343 0 0
sp_cancel_customer_reserve 522898 522898 0 0.00 1 37106 63 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 5650 87 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 1910 55 0 0
sp_cancel_supplier_invoice 5859 5852 7 0.11 2 71669 909 0 7
sp_cancel_supplier_order 378 378 0 0.00 4 1062573 110784 0 0
sp_cancel_write_off 10055 10055 0 0.00 1 16703 129 0 0
sp_client_order 19701 19701 0 0.00 4 36746 386 0 0
sp_customer_reserve 440323 440323 0 0.00 0 87453 189 0 0
sp_fill_shopping_cart 30410 30410 0 0.00 1 25430 55 0 0
sp_fill_shopping_cart_clo_res 440320 440320 0 0.00 0 16232 16 0 0
sp_get_clo_for_invoice 4890 4890 0 0.00 0 36680 432 0 0
sp_kill_cost_storno 16464 16464 0 0.00 0 7971 56 0 0
sp_kill_qstorno_ret_qs2qd 1062081 1062081 0 0.00 0 71658 34 0 0
sp_kill_qty_storno 560959 560959 0 0.00 0 71659 74 0 0
sp_lock_selected_doc 20320 20320 0 0.00 0 7499 5 0 0
sp_make_cost_storno 27367 27367 0 0.00 0 15862 63 0 0
sp_multiply_rows_for_pdistr 27367 27367 0 0.00 0 9133 31 0 0
sp_multiply_rows_for_qdistr 19701 19701 0 0.00 0 23149 203 0 0
sp_pay_from_customer 6959 6959 0 0.00 5 15119 115 0 0
sp_pay_to_supplier 5712 5712 0 0.00 5 15743 140 0 0
sp_payment_common 12671 12671 0 0.00 1 7848 22 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 18427 312 0 0
sp_qd_handle_on_invoice_upd_sts 10230 10230 0 0.00 0 46514 348 0 0
sp_qd_handle_on_reserve_upd_sts 9806 9806 0 0.00 0 19556 62 0 0
sp_reserve_write_off 9806 9806 0 0.00 7 20813 202 0 0
sp_supplier_invoice 5373 5373 0 0.00 8 111304 3831 0 0
sp_supplier_order 5345 5345 0 0.00 6 79543 1449 0 0
srv_aggregate_perf_data 96 95 1 1.04 2925 334090 21883 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1339 195332 48603 0 0
srv_find_qd_qs_mism 1001126 1001126 0 0.00 0 16292 6 0 0
srv_make_invnt_saldo 394 394 0 0.00 66 116902 4078 0 0
srv_make_money_saldo 704 704 0 0.00 1 36080 547 0 0
srv_recalc_idx_stat 4 4 0 0.00 178085 423459 299362 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4894 4894 0 0.00 0 12495 173 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 6561 124 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 4259 13 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 950 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 6033 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 356 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 731 14 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 25704 1661 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 7248 17 0 0
t$perf-norm:sp_client_order 19701 19701 0 0.00 0 9284 19 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 1737 6 0 0
t$perf-norm:sp_pay_from_customer 6959 6959 0 0.00 0 7457 11 0 0
t$perf-norm:sp_pay_to_supplier 5712 5712 0 0.00 0 8399 11 0 0
t$perf-norm:sp_reserve_write_off 9806 9806 0 0.00 0 7280 11 0 0
t$perf-norm:sp_supplier_invoice 5373 5373 0 0.00 0 4325 19 0 0
t$perf-norm:sp_supplier_order 5345 5345 0 0.00 0 3160 12 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 263 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 228 6 0 0
t$perf-norm:srv_make_invnt_saldo 394 394 0 0.00 0 3711 15 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 4037 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10004 9778 226 2.25 0 8585 22 0 226
v_all_customers 35880 35880 0 0.00 0 46 1 0 0
v_all_suppliers 29488 29488 0 0.00 0 46 1 0 0
v_all_wares 39395 39395 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1009 7 0 0
v_cancel_client_order 3480 3480 0 0.00 0 4552 7 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 522 3 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2768 7 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 195 3 0 0
v_cancel_supplier_invoice 790 776 14 1.77 0 4743 20 0 14
v_cancel_supplier_order 756 756 0 0.00 0 3418 13 0 0
v_cancel_write_off 1968 1968 0 0.00 0 4971 21 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 573 1 0 0
v_max_id_clo_res 937 937 0 0.00 0 9921 26 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 10824 8 0 0
v_max_non_paid_invoice 1678 1678 0 0.00 0 554 2 0 0
v_max_non_paid_realizn 3822 3822 0 0.00 0 5560 5 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 2358 7 0 0
v_min_id_clo_res 937 937 0 0.00 0 3839 11 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 8323 15 0 0
v_min_non_paid_invoice 5711 5711 0 0.00 0 7418 19 0 0
v_min_non_paid_realizn 6958 6958 0 0.00 0 6829 12 0 0
v_our_firm 9786 9786 0 0.00 0 18 1 0 0
v_reserve_write_off 19612 19612 0 0.00 0 4784 7 0 0
x_lock_depdocs_on_canc_invoice 10804 10804 0 0.00 0 18801 117 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 2965 313 0 0
x_make_qstorno_1000_1200 5345 5345 0 0.00 2 76311 1364 0 0
x_make_qstorno_1200_2000 5373 5373 0 0.00 3 111222 3608 0 0
x_make_qstorno_2100_3300 440323 440323 0 0.00 0 87450 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2021 10:39:16 to 14-10-2021 10:39:20.

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
08:07:12 10 8192000000 8806989168 8821202944 11081376 0.135 4 3 4 0 11316096 147776 86944
08:08:13 29263 8192000000 9731882656 10031665152 802084800 8.917 100 83 166 1 830234352 7446096 20703456
08:09:50 18860 8192000000 9774886448 10083926016 848839248 9.388 100 88 176 0 882883504 13302944 20741312
08:11:13 17856 8192000000 9814056592 10133520384 878367376 9.683 100 86 172 1 922334208 24335296 19631536
08:12:40 12078 8192000000 9885991072 10221187072 928976384 10.185 100 78 156 0 995371840 49380816 17014640
08:13:56 7780 8192000000 9943345408 10288734208 972135920 10.608 100 84 167 1 1051730832 61298592 18296320
08:15:06 4150 8192000000 9974767792 10331041792 1002489328 10.903 100 85 170 2 1091552400 71324640 17738432
08:16:12 2329 8192000000 10016977024 10381324288 1027726624 11.147 100 87 174 0 1136005568 90879120 17399824
08:17:16 2402 8192000000 10017834016 10383261696 1045464240 11.317 100 89 178 1 1132998816 69867584 17666992
08:18:19 1796 8192000000 10046690304 10419752960 1071791440 11.569 100 93 183 1 1161434160 71507920 18134800
08:19:21 17354 8192000000 9979826432 10369462272 1069338832 11.546 100 16 32 0 1094444912 21177536 3928544
08:20:39 3957 8192000000 10015763104 10407215104 1084635072 11.692 100 49 96 0 1122124128 27126880 10362176
08:21:46 7889 8192000000 10012383968 10409721856 1089218176 11.735 100 48 94 3 1118208384 19561760 9428448
08:22:55 61056 8192000000 10006528656 10401353728 1087888000 11.723 99 31 62 3 1108704304 13964336 6851968
08:24:56 5439 8192000000 10029483376 10431938560 1099791040 11.836 100 39 75 0 1138352768 30456176 8105552
08:26:03 4714 8192000000 10018226144 10416984064 1093265376 11.774 99 44 88 0 1117692512 15356592 9070544
08:27:11 9078 8192000000 9996679136 10402766848 1077851632 11.627 99 16 33 0 1109629632 28240992 3537008
08:28:22 37487 8192000000 10014142208 10414202880 1042195168 11.286 96 37 74 0 1079035520 29401712 7438640
08:30:03 3720 8192000000 9968318400 10345308160 1043626112 11.300 100 36 71 2 1089693792 38429072 7638608
08:31:08 4780 8192000000 9927809120 10290606080 990415328 10.786 96 55 112 2 1017022544 13378464 13228752
08:32:15 8604 8192000000 9917383520 10312650752 1011823072 10.993 99 22 45 0 1028374704 11898608 4653024
08:33:24 21100 8192000000 9943748976 10341486592 1022417296 11.095 99 58 115 1 1047368976 12781168 12170512
08:34:46 11352 8192000000 9980115872 10385149952 1056687968 11.425 100 41 82 0 1095901328 30671568 8541792
08:36:00 7329 8192000000 9978088528 10382495744 1069283824 11.545 100 30 59 1 1095360336 19205744 6870768
08:37:07 12065 8192000000 10001916240 10401021952 1085993728 11.705 100 30 58 1 1110537840 17383392 7160720
08:38:23 66332 8192000000 10032590912 10436222976 1106617152 11.900 100 37 74 0 1148654208 33975232 8061824
08:40:33 21851 8192000000 10077352928 10485968896 1123187472 12.057 100 43 87 0 1186896192 55049008 8659712
08:42:01 19107 8192000000 10120011024 10522710016 1137824224 12.195 100 38 76 0 1219236672 73399104 8013344
08:43:27 17197 8192000000 10145898480 10558660608 1134456496 12.163 99 46 92 0 1255144800 110756864 9931440
08:44:53 11166 8192000000 10185861536 10593894400 1148398256 12.294 99 56 112 2 1285117504 124719424 11999824
08:46:11 4617 8192000000 10226626832 10631774208 1154677440 12.353 100 67 132 2 1329954816 161214080 14063296
08:47:19 3384 8192000000 10256139328 10659557376 1159102240 12.395 100 68 134 2 1359877216 186410864 14364112
08:48:28 6142 8192000000 10292408768 10692014080 1158978864 12.394 100 79 158 2 1385115936 209254160 16882912
08:49:42 2035 8192000000 10296034800 10696278016 1166975536 12.469 100 83 166 0 1392698976 207021648 18701792
08:50:48 1955 8192000000 10312764368 10711203840 1170325936 12.500 100 90 179 0 1410095824 220039392 19730496
08:51:53 3977 8192000000 10353355600 10749669376 1168335392 12.481 100 85 168 1 1451279392 264267456 18676544
08:53:03 1337 8192000000 10404616960 10817765376 1167818176 12.476 100 90 173 1 1500140496 312559264 19763056
08:54:08 2768 8192000000 10430111104 10832666624 1156066832 12.366 99 76 150 0 1521971984 347855520 18049632
08:55:14 2757 8192000000 10389012448 10805784576 1153866192 12.346 100 75 150 1 1484284704 312302288 18116224
08:56:34 7456 8192000000 10387112896 10795741184 1136329008 12.181 99 55 110 0 1489274672 338812992 14132672
08:57:46 3160 8192000000 10349954976 10784587776 1125766560 12.081 99 45 90 0 1452681024 315950096 10964368
08:58:51 10866 8192000000 10314028256 10770292736 1147929712 12.290 100 26 52 2 1412328032 257915232 6483088
09:00:05 27894 8192000000 10299661728 10744352768 1137513200 12.192 100 36 72 1 1429993792 283396256 9084336
09:01:37 18797 8192000000 10367944384 10795667456 1126545520 12.089 100 28 55 3 1461696080 328244880 6905680
09:03:03 26962 8192000000 10304300976 10739855360 1098543136 11.824 98 25 50 0 1397188912 291707584 6938192
09:04:36 38389 8192000000 10059740688 10513010688 1117185264 12.000 100 22 44 0 1177557280 55366000 5006016
09:06:15 51489 8192000000 10048533776 10480975872 1109930176 11.932 99 54 106 1 1140720512 18872560 11917776
09:08:11 89395 8192000000 10068350896 10522947584 1108169120 11.915 98 15 30 1 1156187808 45175152 2843536
09:10:46 51149 8192000000 10057660080 10531504128 1111365200 11.945 99 15 30 1 1165037776 50606944 3065632
09:12:40 40588 8192000000 10050913264 10506563584 1118511040 12.013 100 12 25 1 1147956624 27016576 2429008
09:14:29 101503 8192000000 10051016928 10524336128 1111594624 11.948 98 17 34 1 1153788608 37894672 4299312
09:17:17 36889 8192000000 10110278384 10559135744 1141164624 12.226 100 31 59 1 1225548848 77227248 7156976
09:18:54 54429 8192000000 10118011824 10581991424 1126328224 12.087 99 17 35 1 1217743616 87743472 3671920
09:21:00 43141 8192000000 10042639120 10480783360 1123010912 12.055 99 14 28 0 1147148960 21176736 2961312
09:22:44 22520 8192000000 10088522896 10521600000 1130526848 12.126 99 49 98 2 1184374800 44040304 9807648
09:24:16 57429 8192000000 10123092944 10552782848 1138252336 12.199 99 27 54 0 1202113984 58222544 5639104
09:26:13 65621 8192000000 10098507632 10550386688 1141920896 12.234 99 17 34 0 1218081360 72370944 3789520
09:28:20 59338 8192000000 10110796224 10550648832 1138408944 12.201 99 16 32 1 1217642672 75641760 3591968
09:30:19 46921 8192000000 10119123952 10613055488 1159152208 12.395 100 31 62 2 1242398080 75363680 7882192
09:32:08 71252 8192000000 10121433520 10616487936 1123636624 12.061 97 14 28 1 1222221472 95476144 3108704
09:35:02 28303 8192000000 10075114256 10576183296 1124577008 12.070 98 26 52 0 1165837280 36207616 5052656
09:36:44 44152 8192000000 10099964656 10572849152 1148065168 12.291 99 7 14 0 1195910816 46559504 1286144
09:38:28 46072 8192000000 10102623920 10636648448 1151977744 12.328 100 6 12 0 1210872832 57852352 1042736
09:40:14 67451 8192000000 10057464544 10517671936 1090201328 11.745 96 17 35 0 1121137504 27079728 3856448
09:42:29 87285 8192000000 10150478784 10602041344 1138410800 12.201 99 16 32 0 1218708784 76754496 3543488
09:45:01 101963 8192000000 10157848256 10658127872 1120016560 12.027 97 19 38 0 1214050448 90212032 3821856
09:47:44 52687 8192000000 10079194752 10527293440 1101238608 11.849 96 16 32 0 1145567360 41361200 2967552
09:49:45 15927 8192000000 10084205264 10507276288 1106190192 11.896 98 57 112 2 1153814304 36394496 11229616
09:51:01 107391 8192000000 10051956384 10519085056 1078720032 11.635 96 18 36 1 1165234800 83089456 3425312
09:53:49 80740 8192000000 10050376496 10486636544 1120394144 12.031 100 33 65 1 1155044304 28118544 6531616
09:56:10 78165 8192000000 10044367376 10541223936 1117785104 12.006 100 18 36 1 1166588176 45309072 3494000
09:58:28 63070 8192000000 10028885744 10477887488 1061810224 11.474 95 16 32 1 1093931888 28711856 3409808
10:01:17 97829 8192000000 10067461760 10511138816 1086538240 11.710 97 10 20 0 1136481696 47648352 2295104
10:04:32 93237 8192000000 10114232496 10589761536 1118323456 12.011 99 35 70 0 1208192176 82518544 7350176
10:07:08 81763 8192000000 10057011280 10478792704 1102947904 11.866 97 28 56 2 1128368000 20150992 5269104
10:09:32 56518 8192000000 10088503424 10543513600 1117025072 11.999 98 24 48 0 1179422400 57830352 4566976
10:11:29 56808 8192000000 10091597312 10540949504 1103204112 11.868 97 34 68 1 1177244288 67157744 6882432
10:13:40 154989 8192000000 10118090608 10571378688 1141922944 12.234 100 30 60 1 1227452992 79126096 6403952
10:17:19 50498 8192000000 10085762544 10595459072 1145768112 12.270 100 13 26 0 1207008096 58425360 2814624
10:19:10 59843 8192000000 10088914048 10556416000 1120952544 12.036 98 8 16 0 1168152256 45140176 2059536
10:21:10 61969 8192000000 10072008080 10546413568 1088771616 11.731 95 22 44 0 1154611536 61132928 4706992
10:23:23 85578 8192000000 10068047456 10546548736 1084903872 11.694 96 14 28 1 1125898784 38304064 2690848
10:25:49 62278 8192000000 10062570272 10536644608 1103103504 11.867 97 12 24 0 1135584336 30066096 2414736
10:27:57 37971 8192000000 10097772544 10523176960 1085855840 11.703 95 36 70 3 1145439344 52645120 6938384
10:29:53 195331 8192000000 10046875088 10499452928 1085280256 11.698 96 13 26 0 1124784944 36580048 2924640
10:34:16 39683 8192000000 10017128832 10466471936 1112694208 11.958 99 22 42 1 1140305936 23012064 4599664
10:36:01 52491 8192000000 10064194000 10522443776 1094846752 11.789 97 6 12 0 1143619344 47539584 1233008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2021 10:39:22 to 14-10-2021 10:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 113 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 14-10-2021 10:39:38 to 14-10-2021 10:39:40.

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          232873
MON$OLDEST_ACTIVE               234870
MON$OLDEST_SNAPSHOT             234870
MON$NEXT_TRANSACTION            234870
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                       2044512
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:87: 
:88: mscs-test-161.budzdorov.ru    Thu Oct 14 10:37:15 2021
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Thu Oct 14 10:37:31 2021
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
--- end of diff output ---

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

14.10.21 10:39:41. 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-10-14 10:39:43 No severe PSQL-related problems occured
14.10.21 10:39:45. End of report.