Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.11.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 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 16600920 kB
MemAvailable 31590284 kB
Buffers 0 kB
Cached 15069184 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-19 10:37:09
DTS_END                         2021-11-19 10:37:09.0490
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/35298
TRN_ID                          232096
ATT_ID                          688
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: 9036.12 27574 1084334
customer order: creation 165.13 421 19816
customer order: refuse 14.27 382 1712
order to supplier: creation 45.21 1531 5425
order to supplier: removal 3.39 117979 407
invoice (draft): creation 45.08 3844 5409
invoice (draft): removal 52.13 982 6256
invoice accept: apply 40.90 19677 4908
invoice accept: cancel 46.88 908 5626
customer reserve: creation 3769.66 194 452359
customer reserve: removal 4573.91 67 548869
realization accept: apply 82.04 208 9845
realization accept: cancel 73.81 128 8857
payment from customer: creation 57.15 139 6858
payment from customer: removal 5.32 91 638
payment to supplier: creation 47.00 147 5640
payment to supplier: removal 3.08 125 370
service: total inventory turnovers 3.73 3884 447
service: total monetary turnovers 6.52 587 782
service: aggregate perf. data 0.88 18648 106
service: refresh index statistics 0.03 381543 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-11-2021 10:38:33 to 19-11-2021 10:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4211
TEST_TIME 2 6320
TEST_TIME 3 6230
TEST_TIME 4 6609
TEST_TIME 5 6102
TEST_TIME 6 6059
TEST_TIME 7 6010
TEST_TIME 8 5807
TEST_TIME 9 5748
TEST_TIME 10 6278
TEST_TIME 11 6232
TEST_TIME 12 6351
TEST_TIME 13 6501
TEST_TIME 14 6484
TEST_TIME 15 6759
TEST_TIME 16 7622
TEST_TIME 17 7674
TEST_TIME 18 7581
TEST_TIME 19 7451
TEST_TIME 20 7907
TEST_TIME 21 8472
TEST_TIME 22 8520
TEST_TIME 23 8692
TEST_TIME 24 8948
TEST_TIME 25 9594
TEST_TIME 26 10161
TEST_TIME 27 10157
TEST_TIME 28 9878
TEST_TIME 29 9982
TEST_TIME 30 10261
TEST_TIME 31 10370
TEST_TIME 32 10294
TEST_TIME 33 10109
TEST_TIME 34 9937
TEST_TIME 35 9989
TEST_TIME 36 9840
TEST_TIME 37 9970
TEST_TIME 38 9985
TEST_TIME 39 9921
TEST_TIME 40 9912
TEST_TIME 41 9948
TEST_TIME 42 10070
TEST_TIME 43 9958
TEST_TIME 44 9751
TEST_TIME 45 9669
TEST_TIME 46 9537
TEST_TIME 47 9604
TEST_TIME 48 9515
TEST_TIME 49 9532
TEST_TIME 50 9564
TEST_TIME 51 9501
TEST_TIME 52 9449
TEST_TIME 53 9364
TEST_TIME 54 9366
TEST_TIME 55 9296
TEST_TIME 56 9261
TEST_TIME 57 9218
TEST_TIME 58 9235
TEST_TIME 59 9223
TEST_TIME 60 9253
TEST_TIME 61 9375
TEST_TIME 62 9355
TEST_TIME 63 9430
TEST_TIME 64 9459
TEST_TIME 65 9421
TEST_TIME 66 9449
TEST_TIME 67 9477
TEST_TIME 68 9548
TEST_TIME 69 9638
TEST_TIME 70 9786
TEST_TIME 71 9746
TEST_TIME 72 9670
TEST_TIME 73 9575
TEST_TIME 74 9530
TEST_TIME 75 9508
TEST_TIME 76 9468
TEST_TIME 77 9418
TEST_TIME 78 9352
TEST_TIME 79 9307
TEST_TIME 80 9281
TEST_TIME 81 9224
TEST_TIME 82 9191
TEST_TIME 83 9109
TEST_TIME 84 9180
TEST_TIME 85 9172
TEST_TIME 86 9129
TEST_TIME 87 9125
TEST_TIME 88 9190
TEST_TIME 89 9145
TEST_TIME 90 9107
TEST_TIME 91 9136
TEST_TIME 92 9256
TEST_TIME 93 9255
TEST_TIME 94 9309
TEST_TIME 95 9295
TEST_TIME 96 9296
TEST_TIME 97 9332
TEST_TIME 98 9267
TEST_TIME 99 9195
TEST_TIME 100 9185
TEST_TIME 101 9148
TEST_TIME 102 9106
TEST_TIME 103 9060
TEST_TIME 104 9071
TEST_TIME 105 9065
TEST_TIME 106 9038
TEST_TIME 107 9068
TEST_TIME 108 9092
TEST_TIME 109 9068
TEST_TIME 110 9118
TEST_TIME 111 9127
TEST_TIME 112 9109
TEST_TIME 113 9118
TEST_TIME 114 9135
TEST_TIME 115 9195
TEST_TIME 116 9216
TEST_TIME 117 9201
TEST_TIME 118 9137
TEST_TIME 119 9114
TEST_TIME 120 9039

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-11-2021 10:38:37 to 19-11-2021 10:38:40.

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 1502832 1502832 0 0.00 0 33022 7 0 0
doc_list_biud 1502832 1502832 0 0.00 0 64222 32 0 0
sp_add_invoice_to_stock 5032 4908 124 2.46 40 170204 19678 0 124
sp_cancel_adding_invoice 5626 5626 0 0.00 4 86183 909 0 0
sp_cancel_client_order 1712 1712 0 0.00 4 11604 382 0 0
sp_cancel_customer_reserve 548869 548869 0 0.00 1 34150 68 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 7139 92 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 7294 126 0 0
sp_cancel_supplier_invoice 6261 6256 5 0.07 2 64227 983 0 5
sp_cancel_supplier_order 407 407 0 0.00 6 1025390 117979 0 0
sp_cancel_write_off 8857 8857 0 0.00 1 7418 129 0 0
sp_client_order 19816 19816 0 0.00 4 38322 422 0 0
sp_customer_reserve 452359 452359 0 0.00 0 42267 194 0 0
sp_fill_shopping_cart 30637 30637 0 0.00 1 14628 44 0 0
sp_fill_shopping_cart_clo_res 452359 452359 0 0.00 0 12131 15 0 0
sp_get_clo_for_invoice 4907 4907 0 0.00 0 16640 374 0 0
sp_kill_cost_storno 15491 15491 0 0.00 0 33013 71 0 0
sp_kill_qstorno_ret_qs2qd 1113258 1113258 0 0.00 0 64220 37 0 0
sp_kill_qty_storno 586479 586479 0 0.00 0 64221 80 0 0
sp_lock_selected_doc 20324 20324 0 0.00 0 7405 5 0 0
sp_make_cost_storno 27250 27250 0 0.00 0 20617 64 0 0
sp_multiply_rows_for_pdistr 27250 27250 0 0.00 0 10294 35 0 0
sp_multiply_rows_for_qdistr 19816 19816 0 0.00 0 38311 204 0 0
sp_pay_from_customer 6858 6858 0 0.00 4 20623 139 0 0
sp_pay_to_supplier 5640 5640 0 0.00 5 17605 148 0 0
sp_payment_common 12498 12498 0 0.00 1 8509 28 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 11596 340 0 0
sp_qd_handle_on_invoice_upd_sts 10533 10533 0 0.00 0 22578 405 0 0
sp_qd_handle_on_reserve_upd_sts 9845 9845 0 0.00 0 20694 66 0 0
sp_reserve_write_off 9845 9845 0 0.00 7 21549 209 0 0
sp_supplier_invoice 5409 5409 0 0.00 7 79375 3845 0 0
sp_supplier_order 5425 5425 0 0.00 6 47852 1532 0 0
srv_aggregate_perf_data 106 106 0 0.00 2837 410063 18649 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1527 170750 42562 0 0
srv_find_qd_qs_mism 1037909 1037909 0 0.00 0 11020 6 0 0
srv_make_invnt_saldo 447 447 0 0.00 40 95391 3884 0 0
srv_make_money_saldo 782 782 0 0.00 1 18572 587 0 0
srv_recalc_idx_stat 4 4 0 0.00 236358 581393 381543 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4909 4909 0 0.00 0 7268 171 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 4512 91 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 2789 13 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1347 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 3431 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 775 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1070 17 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 25135 1769 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 3535 15 0 0
t$perf-norm:sp_client_order 19816 19816 0 0.00 0 10959 20 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 3055 13 0 0
t$perf-norm:sp_pay_from_customer 6858 6858 0 0.00 0 4249 9 0 0
t$perf-norm:sp_pay_to_supplier 5640 5640 0 0.00 0 7662 9 0 0
t$perf-norm:sp_reserve_write_off 9845 9845 0 0.00 0 7637 10 0 0
t$perf-norm:sp_supplier_invoice 5409 5409 0 0.00 0 5886 19 0 0
t$perf-norm:sp_supplier_order 5425 5425 0 0.00 0 3559 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1638 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 151 3 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 1463 14 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 3902 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 10062 9814 248 2.46 0 5793 22 0 248
v_all_customers 35850 35850 0 0.00 0 84 1 0 0
v_all_suppliers 29660 29660 0 0.00 0 25 1 0 0
v_all_wares 39632 39632 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1863 14 0 0
v_cancel_client_order 3424 3424 0 0.00 0 7064 11 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 2724 10 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 3260 14 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 108 3 0 0
v_cancel_supplier_invoice 774 764 10 1.29 0 1882 18 0 10
v_cancel_supplier_order 814 814 0 0.00 0 949 7 0 0
v_cancel_write_off 2034 2034 0 0.00 0 3842 18 0 0
v_max_id_clo_ord 5425 5425 0 0.00 0 991 2 0 0
v_max_id_clo_res 994 994 0 0.00 0 2697 14 0 0
v_max_id_ord_sup 5396 5396 0 0.00 0 5920 4 0 0
v_max_non_paid_invoice 1631 1631 0 0.00 0 440 2 0 0
v_max_non_paid_realizn 3772 3772 0 0.00 0 2083 3 0 0
v_min_id_clo_ord 5425 5425 0 0.00 0 4253 8 0 0
v_min_id_clo_res 994 994 0 0.00 0 8655 31 0 0
v_min_id_ord_sup 5396 5396 0 0.00 0 6401 7 0 0
v_min_non_paid_invoice 5640 5640 0 0.00 0 6816 22 0 0
v_min_non_paid_realizn 6858 6858 0 0.00 0 8504 21 0 0
v_our_firm 9954 9954 0 0.00 0 19 1 0 0
v_reserve_write_off 19690 19690 0 0.00 0 9207 9 0 0
x_lock_depdocs_on_canc_invoice 11500 11500 0 0.00 0 16144 109 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 4238 354 0 0
x_make_qstorno_1000_1200 5425 5425 0 0.00 2 47846 1438 0 0
x_make_qstorno_1200_2000 5409 5409 0 0.00 4 79242 3688 0 0
x_make_qstorno_2100_3300 452359 452359 0 0.00 0 41080 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-11-2021 10:38:41 to 19-11-2021 10:38:45.

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 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 29048 8192000000 9708453776 10006372352 786604112 8.760 100 85 169 1 814564608 7626656 20333840
08:09:47 19705 8192000000 9764891840 10071490560 835153584 9.251 100 88 176 0 868098704 12241888 20703232
08:11:16 16631 8192000000 9800391056 10117533696 868021120 9.580 100 85 168 3 905079872 17547808 19510944
08:12:35 24696 8192000000 9870310080 10203410432 912011120 10.017 100 84 167 0 975154208 45075184 18067904
08:14:04 11932 8192000000 9912332816 10259922944 954662544 10.437 100 70 140 0 1025378064 56089344 14626176
08:15:18 4494 8192000000 9962775616 10315857920 994708944 10.827 100 80 158 1 1074581584 63875392 15997248
08:16:26 3792 8192000000 9993419616 10356273152 1024547744 11.116 100 81 162 0 1108413968 67760400 16105824
08:17:31 1999 8192000000 10030751952 10397421568 1049433376 11.355 100 91 182 1 1152508560 83570128 19505056
08:18:35 2303 8192000000 10027953824 10405531648 1066960384 11.523 100 87 173 0 1142477120 58956240 16560496
08:19:38 4270 8192000000 10010880176 10404372480 1075115312 11.601 100 33 66 1 1131362512 49722208 6524992
08:20:43 6933 8192000000 9996357328 10391379968 1087970416 11.723 100 38 70 3 1112397536 15836608 8590512
08:21:51 73285 8192000000 10010455664 10421473280 1095736816 11.797 100 46 92 0 1127321504 20786800 10797888
08:24:05 5515 8192000000 10030754384 10425581568 1099326000 11.831 100 42 84 0 1137493312 28209056 9958256
08:25:11 8449 8192000000 10023991744 10433191936 1100951296 11.847 100 32 62 1 1137754096 30541328 6261472
08:26:24 2234 8192000000 10011265648 10409775104 1099235264 11.830 100 40 78 1 1122316592 14512656 8568672
08:27:27 19760 8192000000 9997947712 10415570944 1055985088 11.418 96 22 44 1 1087264384 26755600 4523696
08:28:47 6160 8192000000 9974068768 10362421248 1041139824 11.276 97 40 81 2 1070571696 20668240 8763632
08:29:54 8719 8192000000 9949194384 10347540480 1003139296 10.909 96 33 63 0 1045519120 35083936 7295888
08:31:05 12442 8192000000 9912827792 10288324608 934020992 10.234 90 24 46 1 952321888 13196272 5104624
08:32:20 25799 8192000000 9952121008 10348027904 991076128 10.792 96 16 31 0 1030692352 36344256 3271968
08:33:46 8177 8192000000 9956387920 10342248448 1040878320 11.273 100 42 84 0 1076085232 26292224 8914688
08:34:55 10053 8192000000 9972564400 10367119360 1055586064 11.414 100 23 46 1 1079767824 19067072 5114688
08:36:07 100135 8192000000 9980162160 10377916416 1065850112 11.512 100 28 56 2 1099372656 26874544 6648000
08:38:59 4527 8192000000 10038651760 10430246912 1086989808 11.714 100 63 127 0 1150088560 50207008 12891744
08:40:08 5828 8192000000 10061186832 10455654400 1095622864 11.796 100 59 118 0 1166546432 59272784 11650784
08:41:17 30648 8192000000 10081260608 10484121600 1111761280 11.949 100 45 88 1 1194062672 72956800 9344592
08:42:55 20237 8192000000 10158315184 10559463424 1128102368 12.103 100 48 96 1 1246010720 107069616 10838736
08:44:24 20228 8192000000 10167880672 10588766208 1141348672 12.228 100 43 86 0 1275690512 124759728 9582112
08:45:50 11732 8192000000 10210728624 10622447616 1151425440 12.323 100 47 93 1 1311514112 149469872 10618800
08:47:16 6395 8192000000 10227347952 10640572416 1169040208 12.488 100 71 140 0 1329333312 144841600 15451504
08:48:33 4440 8192000000 10269657120 10674151424 1170264192 12.499 99 63 125 2 1358919456 175293568 13361696
08:49:43 6762 8192000000 10310627520 10718560256 1193117200 12.712 100 76 152 0 1405644032 195355296 17171536
08:50:54 3037 8192000000 10326844656 10733785088 1180218944 12.592 99 82 160 0 1420634400 221674928 18740528
08:52:01 2188 8192000000 10363009504 10758037504 1169431712 12.492 100 93 186 0 1458719840 267994384 21293744
08:53:09 3068 8192000000 10359037264 10783080448 1169662016 12.494 100 94 187 0 1456468624 265200784 21605824
08:54:22 1525 8192000000 10362123984 10763526144 1170108384 12.498 100 81 161 0 1456475408 267423616 18943408
08:55:34 3706 8192000000 10385323600 10790866944 1168317824 12.481 100 75 150 0 1478770128 292774336 17677968
08:56:48 3260 8192000000 10400795008 10798678016 1144632128 12.259 99 87 174 0 1499200480 333785136 20783216
08:57:55 3134 8192000000 10421973680 10825920512 1158827760 12.392 100 76 152 1 1516236656 339094176 18314720
08:59:06 8590 8192000000 10371819040 10784387072 1157984432 12.384 100 66 131 0 1459074912 285306832 15783648
09:00:17 17733 8192000000 10361697312 10786222080 1141983040 12.234 100 35 70 0 1475718704 324075760 9659904
09:01:38 11498 8192000000 10357537184 10798608384 1143112656 12.245 100 30 60 2 1448766064 297716496 7936912
09:03:05 24386 8192000000 10260700400 10743902208 1123665104 12.062 99 18 36 0 1370069776 241910784 4493888
09:04:52 35153 8192000000 10171041584 10589687808 1136276608 12.180 100 47 92 2 1254910336 107720704 10913024
09:06:28 25022 8192000000 10154708768 10642128896 1124169344 12.066 100 12 24 0 1275904736 148707904 3027488
09:07:56 72014 8192000000 10052835200 10547744768 1110965168 11.942 99 25 50 0 1162194144 45803152 5425824
09:10:24 49442 8192000000 10062516272 10492497920 1096051872 11.800 97 29 57 0 1125224816 23284448 5888496
09:12:20 50274 8192000000 10068754416 10514251776 1112998144 11.961 99 13 26 0 1170782944 54486560 3298240
09:14:13 108319 8192000000 10064405408 10534125568 1107321008 11.907 98 14 27 2 1168483504 58397744 2764752
09:17:03 14859 8192000000 10077326160 10496401408 1125937824 12.083 99 50 97 3 1151076016 15763376 9374816
09:18:22 55664 8192000000 10119581840 10586996736 1112374240 11.955 97 16 32 0 1186063232 70404000 3284992
09:20:19 117991 8192000000 10097839200 10544107520 1142875088 12.243 100 21 41 1 1187478416 40985648 3617680
09:23:24 41488 8192000000 10128716448 10570203136 1145320640 12.266 100 24 48 1 1221246016 70486336 5439040
09:25:24 69620 8192000000 10101029136 10591264768 1153466816 12.342 100 31 62 1 1214438624 54023872 6947936
09:27:39 30978 8192000000 10094461840 10582269952 1155987840 12.366 100 10 20 1 1192907184 34622720 2296624
09:29:24 71612 8192000000 10129594016 10586492928 1165365952 12.453 100 11 22 0 1232559488 65093696 2099840
09:31:42 58023 8192000000 10142446976 10618761216 1161579376 12.418 100 20 40 1 1286161872 120191168 4391328
09:34:16 27155 8192000000 10132746832 10576674816 1141445728 12.229 99 21 41 1 1229444560 83565712 4433120
09:35:46 76305 8192000000 10177848928 10672328704 1148202960 12.293 99 23 46 0 1257839360 104047456 5588944
09:38:04 31456 8192000000 10113482368 10601426944 1134970816 12.168 98 24 48 0 1217513632 76728496 5814320
09:39:37 50636 8192000000 10115173168 10614054912 1155039376 12.357 100 22 44 0 1254799680 94878288 4882016
09:41:29 57757 8192000000 10121236432 10581987328 1140232960 12.218 98 32 64 0 1206292784 59226320 6833504
09:43:30 21883 8192000000 10144126864 10578857984 1127198320 12.095 97 38 76 2 1199800048 64567280 8034448
09:44:58 59595 8192000000 10152262608 10635837440 1110119568 11.934 97 17 34 1 1217871888 104105952 3646368
09:47:00 170749 8192000000 10088004896 10611048448 1096123152 11.801 96 11 22 0 1160589184 62673920 1792112
09:50:55 35926 8192000000 10071034592 10514599936 1075976272 11.609 95 37 72 0 1122036128 38149248 7910608
09:52:42 50030 8192000000 10031898096 10501873664 1107058720 11.905 99 6 12 0 1143212048 34989168 1164160
09:54:33 43849 8192000000 10042518816 10498990080 1101846496 11.855 99 19 39 1 1133912016 28199440 3866080
09:56:21 45524 8192000000 10074592096 10527092736 1121505360 12.041 100 31 62 1 1174897728 46439008 6953360
09:58:24 45952 8192000000 10071432000 10507411456 1105994736 11.894 98 14 27 1 1166342272 57178720 3168816
10:00:14 63790 8192000000 10091372000 10544349184 1107237488 11.906 99 14 28 0 1190360368 79826496 3296384
10:02:21 48194 8192000000 10038685376 10483642368 1088869584 11.732 97 11 22 1 1123603024 32571312 2162128
10:04:09 33942 8192000000 10092500608 10536263680 1107329200 11.907 99 41 82 0 1195175904 79376704 8470000
10:06:04 51707 8192000000 10088623696 10521939968 1114179776 11.972 99 22 44 0 1172479808 53511904 4788128
10:07:56 80497 8192000000 10073281552 10599391232 1113527712 11.966 99 21 42 0 1228150320 109502896 5119712
10:10:18 55039 8192000000 10066640624 10545348608 1105870448 11.893 98 18 36 0 1163131776 53978768 3282560
10:12:23 22971 8192000000 10074976864 10512297984 1109930128 11.932 98 46 90 1 1156097328 36832832 9334368
10:13:49 64521 8192000000 10088466384 10611085312 1135661184 12.175 100 13 26 0 1253537728 114850432 3026112
10:16:19 40701 8192000000 10110403520 10538184704 1147982128 12.291 100 48 95 0 1205739040 47350960 10405952
10:18:00 66905 8192000000 10088266256 10557804544 1142403360 12.238 100 10 20 0 1198557904 54473824 1680720
10:20:07 52526 8192000000 10083622720 10537693184 1126194032 12.085 98 11 22 1 1182994624 54307760 2492832
10:22:00 82297 8192000000 10087680384 10532405248 1135129744 12.170 99 18 36 1 1173501904 34925168 3446992
10:24:23 46985 8192000000 10086821056 10554212352 1123104880 12.056 98 19 38 0 1176887008 49608096 4174032
10:26:10 35898 8192000000 10078067520 10511970304 1081333872 11.660 94 29 58 3 1125569120 38323472 5911776
10:27:54 78164 8192000000 10099225728 10554466304 1120827520 12.035 98 16 32 0 1166304944 42244928 3232496
10:30:12 50192 8192000000 10138328080 10588745728 1137421040 12.191 99 35 70 0 1232287760 87391056 7475664
10:32:14 56671 8192000000 10091294544 10559696896 1151132784 12.320 100 15 30 0 1208981040 54984784 2863472
10:34:22 82718 8192000000 10054475136 10504040448 1124971552 12.074 99 17 34 0 1164309760 35567040 3771168
10:36:53 27452 8192000000 9289341120 9608364032 431927200 5.008 39 3 6 0 450689920 18260000 502720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-11-2021 10:38:47 to 19-11-2021 10:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-11-2021 10:39:03 to 19-11-2021 10:39:05.

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          232231
MON$OLDEST_ACTIVE               232534
MON$OLDEST_SNAPSHOT             232534
MON$NEXT_TRANSACTION            232534
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                       2059360
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Fri Nov 19 10:37:23 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

19.11.21 10:39:05. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-19 10:39:08 No severe PSQL-related problems occured
19.11.21 10:39:09. End of report.