Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2021 13: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 15902300 kB
MemAvailable 30697052 kB
Buffers 0 kB
Cached 15839616 kB
SwapCached 3776 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-16 13:37:09
DTS_END                         2021-10-16 13:37:09.1320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46364
TRN_ID                          230500
ATT_ID                          597
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: 8808.00 24635 1056960
customer order: creation 167.13 412 20056
customer order: refuse 14.03 396 1683
order to supplier: creation 44.02 1584 5282
order to supplier: removal 3.35 114378 402
invoice (draft): creation 45.15 4127 5418
invoice (draft): removal 50.36 906 6043
invoice accept: apply 39.78 21366 4773
invoice accept: cancel 45.31 966 5437
customer reserve: creation 3664.72 211 439766
customer reserve: removal 4447.48 68 533697
realization accept: apply 82.13 221 9856
realization accept: cancel 81.17 137 9740
payment from customer: creation 57.47 137 6896
payment from customer: removal 5.41 123 649
payment to supplier: creation 47.07 151 5648
payment to supplier: removal 3.16 115 379
service: total inventory turnovers 3.50 4235 420
service: total monetary turnovers 5.98 614 717
service: aggregate perf. data 0.78 22893 94
service: refresh index statistics 0.03 319660 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2021 13:39:58 to 16-10-2021 13:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4203
TEST_TIME 2 4939
TEST_TIME 3 5723
TEST_TIME 4 6327
TEST_TIME 5 6055
TEST_TIME 6 5630
TEST_TIME 7 6083
TEST_TIME 8 6408
TEST_TIME 9 6537
TEST_TIME 10 7053
TEST_TIME 11 7123
TEST_TIME 12 6966
TEST_TIME 13 6763
TEST_TIME 14 7083
TEST_TIME 15 7307
TEST_TIME 16 7348
TEST_TIME 17 7737
TEST_TIME 18 8035
TEST_TIME 19 7984
TEST_TIME 20 7843
TEST_TIME 21 8339
TEST_TIME 22 8540
TEST_TIME 23 8957
TEST_TIME 24 8923
TEST_TIME 25 8757
TEST_TIME 26 9202
TEST_TIME 27 9334
TEST_TIME 28 10562
TEST_TIME 29 10505
TEST_TIME 30 10540
TEST_TIME 31 10566
TEST_TIME 32 10276
TEST_TIME 33 9978
TEST_TIME 34 9761
TEST_TIME 35 9732
TEST_TIME 36 9792
TEST_TIME 37 9829
TEST_TIME 38 9730
TEST_TIME 39 9648
TEST_TIME 40 9540
TEST_TIME 41 9437
TEST_TIME 42 9341
TEST_TIME 43 9163
TEST_TIME 44 8981
TEST_TIME 45 9300
TEST_TIME 46 9587
TEST_TIME 47 9495
TEST_TIME 48 9536
TEST_TIME 49 9523
TEST_TIME 50 9571
TEST_TIME 51 9574
TEST_TIME 52 9568
TEST_TIME 53 9476
TEST_TIME 54 9487
TEST_TIME 55 9411
TEST_TIME 56 9533
TEST_TIME 57 9482
TEST_TIME 58 9406
TEST_TIME 59 9290
TEST_TIME 60 9230
TEST_TIME 61 9208
TEST_TIME 62 9181
TEST_TIME 63 9136
TEST_TIME 64 9044
TEST_TIME 65 9061
TEST_TIME 66 9024
TEST_TIME 67 9021
TEST_TIME 68 8957
TEST_TIME 69 9045
TEST_TIME 70 9103
TEST_TIME 71 9053
TEST_TIME 72 9116
TEST_TIME 73 9074
TEST_TIME 74 9092
TEST_TIME 75 9042
TEST_TIME 76 9057
TEST_TIME 77 9129
TEST_TIME 78 9114
TEST_TIME 79 9093
TEST_TIME 80 8994
TEST_TIME 81 8978
TEST_TIME 82 8954
TEST_TIME 83 8914
TEST_TIME 84 8861
TEST_TIME 85 8799
TEST_TIME 86 8785
TEST_TIME 87 8783
TEST_TIME 88 8814
TEST_TIME 89 8853
TEST_TIME 90 8862
TEST_TIME 91 8868
TEST_TIME 92 8840
TEST_TIME 93 8853
TEST_TIME 94 8876
TEST_TIME 95 8901
TEST_TIME 96 8963
TEST_TIME 97 8954
TEST_TIME 98 8905
TEST_TIME 99 9038
TEST_TIME 100 9022
TEST_TIME 101 8934
TEST_TIME 102 8881
TEST_TIME 103 8848
TEST_TIME 104 8825
TEST_TIME 105 8775
TEST_TIME 106 8749
TEST_TIME 107 8708
TEST_TIME 108 8737
TEST_TIME 109 8729
TEST_TIME 110 8755
TEST_TIME 111 8739
TEST_TIME 112 8715
TEST_TIME 113 8749
TEST_TIME 114 8744
TEST_TIME 115 8714
TEST_TIME 116 8779
TEST_TIME 117 8866
TEST_TIME 118 8841
TEST_TIME 119 8880
TEST_TIME 120 8811

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2021 13:40:01 to 16-10-2021 13:40:05.

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 1464072 1464072 0 0.00 0 33027 8 0 0
doc_list_biud 1464072 1464072 0 0.00 0 37799 31 0 0
sp_add_invoice_to_stock 4860 4773 87 1.79 64 261168 21366 0 87
sp_cancel_adding_invoice 5437 5437 0 0.00 5 64648 967 0 0
sp_cancel_client_order 1683 1683 0 0.00 5 9962 397 0 0
sp_cancel_customer_reserve 533697 533697 0 0.00 1 37801 69 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 18774 123 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 2455 116 0 0
sp_cancel_supplier_invoice 6055 6043 12 0.19 2 28302 906 0 12
sp_cancel_supplier_order 402 402 0 0.00 6 1069724 114378 0 0
sp_cancel_write_off 9740 9740 0 0.00 1 9767 138 0 0
sp_client_order 20056 20056 0 0.00 4 25890 413 0 0
sp_customer_reserve 439766 439766 0 0.00 0 88207 212 0 0
sp_fill_shopping_cart 30745 30745 0 0.00 1 26631 48 0 0
sp_fill_shopping_cart_clo_res 439760 439760 0 0.00 0 18389 16 0 0
sp_get_clo_for_invoice 4766 4766 0 0.00 0 32457 438 0 0
sp_kill_cost_storno 16205 16205 0 0.00 0 11914 69 0 0
sp_kill_qstorno_ret_qs2qd 1083579 1083579 0 0.00 0 33883 37 0 0
sp_kill_qty_storno 571624 571624 0 0.00 0 37799 80 0 0
sp_lock_selected_doc 20174 20174 0 0.00 0 6182 4 0 0
sp_make_cost_storno 27166 27166 0 0.00 0 30217 65 0 0
sp_multiply_rows_for_pdistr 27166 27166 0 0.00 0 11289 43 0 0
sp_multiply_rows_for_qdistr 20056 20056 0 0.00 0 25514 195 0 0
sp_pay_from_customer 6896 6896 0 0.00 4 13093 138 0 0
sp_pay_to_supplier 5648 5648 0 0.00 5 13413 152 0 0
sp_payment_common 12544 12544 0 0.00 1 12330 24 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 9955 362 0 0
sp_qd_handle_on_invoice_upd_sts 10203 10203 0 0.00 0 22390 379 0 0
sp_qd_handle_on_reserve_upd_sts 9856 9856 0 0.00 0 10524 59 0 0
sp_reserve_write_off 9856 9856 0 0.00 7 23641 221 0 0
sp_supplier_invoice 5418 5418 0 0.00 6 85586 4127 0 0
sp_supplier_order 5282 5282 0 0.00 5 34527 1584 0 0
srv_aggregate_perf_data 94 94 0 0.00 2738 634103 22893 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1442 110581 42160 0 0
srv_find_qd_qs_mism 1011068 1011068 0 0.00 0 26787 7 0 0
srv_make_invnt_saldo 420 420 0 0.00 55 100381 4235 0 0
srv_make_money_saldo 717 717 0 0.00 1 9996 615 0 0
srv_recalc_idx_stat 4 4 0 0.00 195573 436943 319661 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 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 4773 4773 0 0.00 0 9730 168 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 5714 135 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 3179 10 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 7987 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 859 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 7869 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 903 23 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 24254 1586 0 0
t$perf-norm:sp_cancel_write_off 897 897 0 0.00 0 3828 13 0 0
t$perf-norm:sp_client_order 20056 20056 0 0.00 0 17420 22 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 2158 5 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 9040 11 0 0
t$perf-norm:sp_pay_to_supplier 5648 5648 0 0.00 0 11211 11 0 0
t$perf-norm:sp_reserve_write_off 9856 9856 0 0.00 0 16975 11 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 4833 20 0 0
t$perf-norm:sp_supplier_order 5282 5282 0 0.00 0 3721 14 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 269 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 360 7 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 1216 8 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 4016 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9706 9532 174 1.79 0 6979 21 0 174
v_all_customers 36500 36500 0 0.00 0 17 1 0 0
v_all_suppliers 29528 29528 0 0.00 0 19 1 0 0
v_all_wares 40112 40112 0 0.00 0 124 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1862 16 0 0
v_cancel_client_order 3366 3366 0 0.00 0 3215 9 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 6627 11 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 2969 9 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 2130 12 0 0
v_cancel_supplier_invoice 846 822 24 2.83 0 482 13 0 24
v_cancel_supplier_order 804 804 0 0.00 0 3064 10 0 0
v_cancel_write_off 1794 1794 0 0.00 0 3494 18 0 0
v_max_id_clo_ord 5279 5279 0 0.00 0 1224 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 14264 38 0 0
v_max_id_ord_sup 5410 5410 0 0.00 0 5954 5 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 11837 10 0 0
v_max_non_paid_realizn 3603 3603 0 0.00 0 1709 3 0 0
v_min_id_clo_ord 5279 5279 0 0.00 0 1645 4 0 0
v_min_id_clo_res 985 985 0 0.00 0 1715 4 0 0
v_min_id_ord_sup 5410 5410 0 0.00 0 4795 6 0 0
v_min_non_paid_invoice 5648 5648 0 0.00 0 5767 14 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 9393 14 0 0
v_our_firm 10198 10198 0 0.00 0 117 1 0 0
v_reserve_write_off 19712 19712 0 0.00 0 12116 11 0 0
x_lock_depdocs_on_canc_invoice 11069 11069 0 0.00 0 29160 122 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 14460 370 0 0
x_make_qstorno_1000_1200 5282 5282 0 0.00 2 34513 1501 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 2 85577 3938 0 0
x_make_qstorno_2100_3300 439766 439766 0 0.00 0 88204 193 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2021 13:40:06 to 16-10-2021 13:40:10.

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
11:07:12 1042 8192000000 8960448848 9025155072 3900128 0.047 2 1 2 0 3997744 61840 35776
11:08:15 30519 8192000000 9720373552 10017587200 801347568 8.910 100 85 170 0 829748112 7739168 20661376
11:09:53 16070 8192000000 9764579680 10069897216 834793696 9.247 100 89 178 0 868568704 12356832 21418176
11:11:14 8150 8192000000 9790971312 10106712064 863980848 9.540 100 84 168 0 900363216 16728192 19654176
11:12:29 14906 8192000000 9859887936 10191097856 906929424 9.967 100 74 148 0 956331664 33017600 16384640
11:13:49 9537 8192000000 9908396464 10252730368 953885136 10.429 100 80 160 1 1023301744 52503872 16912736
11:15:05 7800 8192000000 9937012928 10289324032 986712944 10.750 100 77 153 0 1059351296 57119936 15518416
11:16:18 5034 8192000000 9986486864 10348765184 1020934704 11.081 100 83 166 0 1107902224 70279184 16688336
11:17:25 1634 8192000000 10013831408 10378661888 1050188608 11.362 100 89 176 1 1131673280 63401712 18082960
11:18:28 1763 8192000000 10046350160 10415747072 1067030288 11.524 100 91 180 0 1162490688 76958496 18501904
11:19:31 2153 8192000000 10057471968 10429759488 1067101008 11.524 100 88 177 1 1168583168 83083936 18398224
11:20:34 4836 8192000000 10004463216 10390102016 1074588672 11.596 100 62 124 2 1116992656 29366048 13037936
11:21:43 8745 8192000000 9995691456 10402267136 1080033104 11.648 100 22 44 1 1111833056 27638128 4161824
11:22:52 63389 8192000000 9994746288 10408443904 1087190720 11.716 100 30 57 1 1112293600 19043312 6059568
11:24:56 6826 8192000000 10009415280 10425753600 1097085600 11.810 100 35 71 0 1135746528 31826624 6834304
11:26:05 8434 8192000000 10006041312 10420580352 1101882480 11.855 100 27 54 0 1125526640 17159376 6484784
11:27:17 5650 8192000000 10003385136 10400198656 1070233312 11.554 98 41 79 1 1103712576 24655024 8824240
11:28:24 39754 8192000000 10002174384 10407510016 1073713344 11.588 99 25 50 0 1101352864 23122208 4517312
11:30:05 3902 8192000000 9940884032 10318163968 998225072 10.861 96 54 109 3 1020394656 8858560 13311024
11:31:09 4203 8192000000 9923292224 10302148608 1008273808 10.959 99 35 70 2 1034416528 18248896 7893824
11:32:17 10732 8192000000 9932960288 10312237056 974083056 10.627 95 41 78 1 1000733184 17947728 8702400
11:33:33 28157 8192000000 9934804848 10329968640 1011577984 10.991 98 21 42 1 1034077520 17987072 4512464
11:35:01 9472 8192000000 9973761712 10368102400 1040520832 11.270 100 47 93 1 1068201760 17563056 10117872
11:36:13 5035 8192000000 10002805984 10385539072 1061810704 11.474 100 63 125 0 1092642176 17292368 13539104
11:37:20 24998 8192000000 10004186784 10394103808 1089045632 11.734 100 41 78 1 1105433008 8177760 8209616
11:38:51 35173 8192000000 10040140768 10434945024 1102035056 11.857 100 48 98 1 1153739168 42151088 9553024
11:40:29 8162 8192000000 10086768304 10488115200 1122688656 12.052 100 69 137 3 1199693760 62043952 14961152
11:41:57 10697 8192000000 10110637824 10515902464 1137774576 12.195 100 50 100 1 1210005808 61017392 11213840
11:43:11 19231 8192000000 10156200368 10562945024 1146616864 12.278 100 54 107 0 1254843104 96324384 11901856
11:44:34 12799 8192000000 10164327472 10576347136 1152642160 12.334 100 51 102 2 1260951056 97550944 10757952
11:45:52 26583 8192000000 10173136912 10600390656 1167720000 12.476 100 56 107 2 1275424208 95451296 12252912
11:47:30 7584 8192000000 10229949072 10641977344 1176757264 12.560 100 66 130 1 1329670048 137914640 14998144
11:48:40 6691 8192000000 10242714304 10657079296 1180604016 12.596 100 59 118 2 1345110608 152150064 12356528
11:49:52 3854 8192000000 10268834608 10679095296 1180906480 12.599 100 71 138 1 1369355008 173328992 15119536
11:51:00 5527 8192000000 10319282752 10726227968 1167764272 12.476 99 73 145 1 1419583744 235597120 16222352
11:52:09 4373 8192000000 10295610992 10699837440 1165791840 12.457 99 70 136 2 1401316384 219549104 15975440
11:53:20 1800 8192000000 10284189120 10691629056 1124436224 12.069 97 79 156 3 1360218576 217721472 18060880
11:54:26 2751 8192000000 10344715856 10742468608 1158197168 12.386 100 82 164 0 1442530288 265601584 18731536
11:55:33 1441 8192000000 10377368720 10777690112 1155582000 12.362 100 87 176 2 1467984400 292505536 19896864
11:56:41 7979 8192000000 10350159216 10759806976 1160114176 12.404 100 62 125 0 1448481184 273747408 14619600
11:57:59 1455 8192000000 10394441728 10804826112 1134264064 12.162 100 77 151 2 1492671504 340337152 18070288
11:59:06 2301 8192000000 10371928112 10787401728 1151388128 12.323 100 92 183 1 1455748656 283138832 21221696
12:00:15 17893 8192000000 10292909056 10769838080 1150462960 12.314 100 33 66 1 1405210704 246295232 8452512
12:01:40 16595 8192000000 10307910992 10748256256 1140126704 12.217 100 25 50 0 1412217440 265551104 6539632
12:03:15 23874 8192000000 10291230640 10771062784 1122958128 12.055 99 17 34 1 1404611936 276999040 4654768
12:04:43 23217 8192000000 10264820896 10733101056 1107710336 11.911 99 22 44 1 1384569184 270915968 5942880
12:06:07 25402 8192000000 10063976736 10491387904 1107470992 11.908 99 46 92 1 1149860448 32231344 10158112
12:07:38 59321 8192000000 10083708864 10545463296 1108471616 11.918 98 15 31 1 1178335568 67434176 2429776
12:09:43 58741 8192000000 10106144336 10522075136 1132704944 12.147 99 48 90 2 1174405808 32346656 9354208
12:12:20 55996 8192000000 10129807808 10576097280 1140482160 12.220 100 30 60 1 1244889136 98077152 6329824
12:14:18 69630 8192000000 10104053728 10539999232 1136009488 12.178 99 18 36 1 1195047072 54977248 4060336
12:16:30 55597 8192000000 10128782560 10584236032 1131813632 12.138 99 18 36 0 1236047264 99937840 4295792
12:18:51 21844 8192000000 10196918016 10646208512 1161656624 12.419 100 23 46 0 1296376656 129294080 5425952
12:20:14 104793 8192000000 10280020880 10720690176 1131370096 12.134 98 21 42 0 1329504896 193204480 4930320
12:22:59 45619 8192000000 10066417072 10487660544 1121001424 12.036 98 28 56 1 1226101040 98480592 6619024
12:24:49 67907 8192000000 10088949264 10557779968 1151616864 12.325 100 18 36 0 1196314080 40989456 3707760
12:26:57 61212 8192000000 10106405216 10581667840 1160338848 12.406 100 23 46 3 1238817776 73674368 4804560
12:29:00 29699 8192000000 10110921904 10574848000 1147940224 12.290 99 25 50 0 1196670304 43258784 5471296
12:30:48 24964 8192000000 10114050160 10542133248 1141678880 12.231 99 40 80 1 1211080832 61440912 7961040
12:32:15 56842 8192000000 10144293696 10591318016 1116925648 11.998 97 29 58 0 1196303472 73345248 6032576
12:34:14 50073 8192000000 10075870000 10520547328 1116365104 11.993 98 34 68 1 1160407680 37205824 6836752
12:37:10 84084 8192000000 10095135120 10572296192 1108428720 11.918 97 32 64 1 1184069968 69112432 6528816
12:39:36 24817 8192000000 10102869712 10555260928 1152127104 12.329 100 38 76 0 1203860400 44211872 7521424
12:41:26 50831 8192000000 10077952160 10540552192 1101905456 11.856 96 14 27 0 1160065872 55334400 2826016
12:43:54 61464 8192000000 10071481568 10539544576 1134572448 12.164 100 22 44 1 1206625632 66888352 5164832
12:45:58 59818 8192000000 10111321152 10587250688 1136785152 12.185 99 14 28 0 1218627808 78697072 3145584
12:48:02 28591 8192000000 10048609632 10481692672 1107470592 11.908 97 20 40 0 1127304576 15639968 4194016
12:49:31 87345 8192000000 10057348896 10498109440 1071226384 11.564 96 15 30 0 1110759152 36648800 2883968
12:51:59 73910 8192000000 10070093296 10497613824 1036783104 11.234 94 40 80 0 1103599136 58355120 8460912
12:54:14 66326 8192000000 10044697040 10476199936 1075757536 11.607 98 24 48 0 1141194112 60588336 4848240
12:56:22 78560 8192000000 10029724832 10452709376 1075511328 11.605 98 16 32 0 1121963456 43064160 3387968
12:58:41 55283 8192000000 10052341440 10509520896 1110402736 11.936 100 20 40 0 1169171184 54688976 4079472
13:00:38 45060 8192000000 10079162992 10529554432 1126268000 12.086 100 15 30 1 1187350304 57529216 3553088
13:02:34 55388 8192000000 10111422240 10583293952 1116512144 11.994 99 8 16 0 1196853072 78551200 1789728
13:04:51 62101 8192000000 10088127408 10581966848 1104258928 11.878 98 14 30 0 1188767040 81403568 3104544
13:06:54 36909 8192000000 10078015904 10503536640 1117842320 12.007 99 33 66 0 1176044832 50943552 7258960
13:08:32 43332 8192000000 10078282784 10503745536 1126526784 12.089 99 27 53 1 1171585200 39759248 5299168
13:10:16 40934 8192000000 10130979984 10583728128 1136156096 12.179 100 34 68 0 1224331520 81331296 6844128
13:12:07 97205 8192000000 10083030384 10598084608 1127626176 12.099 99 12 24 0 1213136448 82791952 2718320
13:14:45 67583 8192000000 10163658912 10601926656 1146528992 12.277 100 24 46 2 1217582480 66726208 4327280
13:17:03 110581 8192000000 10082600752 10533335040 1121064896 12.037 98 23 46 1 1196555856 70128896 5362064
13:20:35 58947 8192000000 10080160848 10546663424 1139049056 12.207 99 12 24 0 1194083120 52493696 2540368
13:22:35 50059 8192000000 10115695968 10567172096 1147811504 12.289 100 14 28 0 1209478576 58854448 2812624
13:24:28 52835 8192000000 10140866736 10618851328 1140640944 12.222 99 20 40 1 1248767424 103398272 4728208
13:26:21 40883 8192000000 10080526512 10520805376 1118652480 12.014 98 20 40 0 1169877392 46663680 4561232
13:28:05 54801 8192000000 10171436176 10634186752 1141554672 12.230 99 27 52 2 1191361168 43366736 6439760
13:30:01 67377 8192000000 10115312992 10546634752 1096933600 11.809 95 17 34 0 1187689456 86620960 4134896
13:32:08 72080 8192000000 10186345408 10680033280 1144126320 12.254 99 24 48 1 1270723568 121390736 5206512
13:34:51 72811 8192000000 10107827760 10534948864 1084909904 11.694 94 27 54 0 1129818256 39102560 5805792
13:37:05 29996 8192000000 9591520640 10011000832 688863520 7.756 60 1 2 0 727345456 38446528 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2021 13:40:12 to 16-10-2021 13:40:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 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 16-10-2021 13:40:28 to 16-10-2021 13:40:30.

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          230924
MON$OLDEST_ACTIVE               230925
MON$OLDEST_SNAPSHOT             230925
MON$NEXT_TRANSACTION            230925
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                       2042144
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             787
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 ---
:204: 
:205: mscs-test-161.budzdorov.ru    Sat Oct 16 13:37:38 2021
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:207: 
:208: 
:209: mscs-test-161.budzdorov.ru    Sat Oct 16 13:37:41 2021
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:211: 
:212: 
:213: mscs-test-161.budzdorov.ru    Sat Oct 16 13:37:41 2021
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:215: 
--- end of diff output ---

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

16.10.21 13:40:31. 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-16 13:40:33 No severe PSQL-related problems occured
16.10.21 13:40:35. End of report.