Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.05.2022 13: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 16069684 kB
MemAvailable 31492064 kB
Buffers 0 kB
Cached 15613732 kB
SwapCached 2552 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.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-06 13:37:11
DTS_END                         2022-05-06 13:37:11.7350
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/59972
TRN_ID                          213061
ATT_ID                          596
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: 8865.48 30442 1063858
customer order: creation 157.98 500 18957
customer order: refuse 13.63 471 1635
order to supplier: creation 41.94 1434 5033
order to supplier: removal 3.33 202986 400
invoice (draft): creation 42.93 3486 5151
invoice (draft): removal 50.43 1144 6051
invoice accept: apply 39.94 28763 4793
invoice accept: cancel 45.36 1299 5443
customer reserve: creation 3772.82 268 452738
customer reserve: removal 4419.52 119 530343
realization accept: apply 77.52 290 9302
realization accept: cancel 77.97 179 9356
payment from customer: creation 55.79 178 6695
payment from customer: removal 5.73 144 688
payment to supplier: creation 45.83 191 5499
payment to supplier: removal 3.34 134 401
service: total inventory turnovers 3.63 4776 436
service: total monetary turnovers 6.93 402 832
service: aggregate perf. data 0.84 21540 101
service: refresh index statistics 0.03 340531 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-05-2022 13:38:37 to 06-05-2022 13:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7013
TEST_TIME 2 7354
TEST_TIME 3 6849
TEST_TIME 4 6530
TEST_TIME 5 6661
TEST_TIME 6 6203
TEST_TIME 7 6051
TEST_TIME 8 6020
TEST_TIME 9 6165
TEST_TIME 10 6136
TEST_TIME 11 6146
TEST_TIME 12 6091
TEST_TIME 13 6387
TEST_TIME 14 6530
TEST_TIME 15 6464
TEST_TIME 16 6815
TEST_TIME 17 6757
TEST_TIME 18 6945
TEST_TIME 19 6886
TEST_TIME 20 7121
TEST_TIME 21 7350
TEST_TIME 22 7471
TEST_TIME 23 7451
TEST_TIME 24 7612
TEST_TIME 25 7833
TEST_TIME 26 7810
TEST_TIME 27 7958
TEST_TIME 28 8029
TEST_TIME 29 7964
TEST_TIME 30 8127
TEST_TIME 31 8234
TEST_TIME 32 8509
TEST_TIME 33 9106
TEST_TIME 34 9341
TEST_TIME 35 9701
TEST_TIME 36 10215
TEST_TIME 37 10489
TEST_TIME 38 10886
TEST_TIME 39 11021
TEST_TIME 40 10890
TEST_TIME 41 10726
TEST_TIME 42 10544
TEST_TIME 43 10507
TEST_TIME 44 10332
TEST_TIME 45 10401
TEST_TIME 46 10251
TEST_TIME 47 10292
TEST_TIME 48 10133
TEST_TIME 49 10036
TEST_TIME 50 9944
TEST_TIME 51 9870
TEST_TIME 52 9816
TEST_TIME 53 9902
TEST_TIME 54 9822
TEST_TIME 55 9866
TEST_TIME 56 9940
TEST_TIME 57 9944
TEST_TIME 58 9924
TEST_TIME 59 9790
TEST_TIME 60 9717
TEST_TIME 61 9636
TEST_TIME 62 9646
TEST_TIME 63 9638
TEST_TIME 64 9503
TEST_TIME 65 9429
TEST_TIME 66 9383
TEST_TIME 67 9330
TEST_TIME 68 9284
TEST_TIME 69 9323
TEST_TIME 70 9254
TEST_TIME 71 9255
TEST_TIME 72 9275
TEST_TIME 73 9261
TEST_TIME 74 9352
TEST_TIME 75 9360
TEST_TIME 76 9336
TEST_TIME 77 9434
TEST_TIME 78 9396
TEST_TIME 79 9372
TEST_TIME 80 9373
TEST_TIME 81 9358
TEST_TIME 82 9468
TEST_TIME 83 9437
TEST_TIME 84 9423
TEST_TIME 85 9337
TEST_TIME 86 9276
TEST_TIME 87 9267
TEST_TIME 88 9274
TEST_TIME 89 9242
TEST_TIME 90 9182
TEST_TIME 91 9136
TEST_TIME 92 9114
TEST_TIME 93 9111
TEST_TIME 94 9123
TEST_TIME 95 9150
TEST_TIME 96 9151
TEST_TIME 97 9152
TEST_TIME 98 9134
TEST_TIME 99 9147
TEST_TIME 100 9213
TEST_TIME 101 9232
TEST_TIME 102 9222
TEST_TIME 103 9263
TEST_TIME 104 9246
TEST_TIME 105 9216
TEST_TIME 106 9173
TEST_TIME 107 9132
TEST_TIME 108 9103
TEST_TIME 109 9117
TEST_TIME 110 9085
TEST_TIME 111 9041
TEST_TIME 112 9044
TEST_TIME 113 9014
TEST_TIME 114 8968
TEST_TIME 115 8944
TEST_TIME 116 8924
TEST_TIME 117 8992
TEST_TIME 118 8976
TEST_TIME 119 8958
TEST_TIME 120 8894

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-05-2022 13:38:40 to 06-05-2022 13:38:44.

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 1480246 1480246 0 0.00 0 20579 14 0 0
doc_list_biud 1480228 1480228 0 0.00 0 39139 48 0 0
sp_add_invoice_to_stock 4914 4793 121 2.46 38 241435 28764 0 121
sp_cancel_adding_invoice 5443 5443 0 0.00 7 97885 1299 0 0
sp_cancel_client_order 1635 1635 0 0.00 7 9033 471 0 0
sp_cancel_customer_reserve 530343 530343 0 0.00 1 38066 120 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 4 4031 144 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 4 2957 134 0 0
sp_cancel_supplier_invoice 6056 6051 5 0.08 3 39152 1144 0 5
sp_cancel_supplier_order 400 400 0 0.00 17 1817639 202986 0 0
sp_cancel_write_off 9356 9356 0 0.00 2 11293 180 0 0
sp_client_order 18957 18957 0 0.00 6 33390 500 0 0
sp_customer_reserve 452738 452738 0 0.00 1 70487 269 0 0
sp_fill_shopping_cart 29125 29125 0 0.00 1 10671 84 0 0
sp_fill_shopping_cart_clo_res 452701 452701 0 0.00 0 21465 22 0 0
sp_get_clo_for_invoice 4750 4750 0 0.00 0 34422 350 0 0
sp_kill_cost_storno 15888 15888 0 0.00 0 5756 84 0 0
sp_kill_qstorno_ret_qs2qd 1076493 1076493 0 0.00 0 39137 51 0 0
sp_kill_qty_storno 567273 567273 0 0.00 0 39139 117 0 0
sp_lock_selected_doc 19576 19576 0 0.00 0 6660 7 0 0
sp_make_cost_storno 26245 26245 0 0.00 0 13028 80 0 0
sp_multiply_rows_for_pdistr 26238 26238 0 0.00 0 13237 42 0 0
sp_multiply_rows_for_qdistr 18957 18957 0 0.00 0 18754 194 0 0
sp_pay_from_customer 6695 6695 0 0.00 6 17063 179 0 0
sp_pay_to_supplier 5499 5499 0 0.00 7 13298 191 0 0
sp_payment_common 12191 12191 0 0.00 2 6649 44 0 0
sp_qd_handle_on_cancel_clo 1635 1635 0 0.00 1 8949 393 0 0
sp_qd_handle_on_invoice_upd_sts 10189 10189 0 0.00 0 18774 594 0 0
sp_qd_handle_on_reserve_upd_sts 9299 9299 0 0.00 0 18784 68 0 0
sp_reserve_write_off 9302 9302 0 0.00 8 18881 290 0 0
sp_supplier_invoice 5151 5151 0 0.00 9 88198 3486 0 0
sp_supplier_order 5033 5033 0 0.00 6 22796 1434 0 0
srv_aggregate_perf_data 101 101 0 0.00 2565 375039 21540 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 839 172074 35397 0 0
srv_find_qd_qs_mism 1017127 1017127 0 0.00 0 20578 11 0 0
srv_make_invnt_saldo 436 436 0 0.00 54 66130 4777 0 0
srv_make_money_saldo 832 832 0 0.00 1 19555 403 0 0
srv_recalc_idx_stat 4 4 0 0.00 199293 468373 340531 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 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 4799 4799 0 0.00 1 31296 990 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 7182 456 0 0
t$perf-norm:sp_cancel_client_order 1635 1635 0 0.00 0 1992 28 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 622 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 428 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 5756 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 2740 40 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 92450 8412 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 973 18 0 0
t$perf-norm:sp_client_order 18958 18958 0 0.00 0 6018 38 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 1898 17 0 0
t$perf-norm:sp_pay_from_customer 6695 6695 0 0.00 0 6995 18 0 0
t$perf-norm:sp_pay_to_supplier 5499 5499 0 0.00 0 12518 27 0 0
t$perf-norm:sp_reserve_write_off 9302 9302 0 0.00 0 7127 24 0 0
t$perf-norm:sp_supplier_invoice 5151 5151 0 0.00 0 4545 33 0 0
t$perf-norm:sp_supplier_order 5033 5033 0 0.00 0 9290 35 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 489 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 811 16 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 1268 18 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 4108 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 152 44 0 0
v_add_invoice_to_stock 9734 9492 242 2.48 0 4629 27 0 242
v_all_customers 35190 35190 0 0.00 0 88 4 0 0
v_all_suppliers 28574 28574 0 0.00 0 87 4 0 0
v_all_wares 37906 37906 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 274 13 0 0
v_cancel_client_order 3270 3270 0 0.00 0 4959 12 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 2478 16 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 3859 16 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 2912 17 0 0
v_cancel_supplier_invoice 814 804 10 1.22 0 3315 30 0 10
v_cancel_supplier_order 800 800 0 0.00 0 5472 22 0 0
v_cancel_write_off 1844 1844 0 0.00 0 3733 26 0 0
v_max_id_clo_ord 5027 5027 0 0.00 0 514 5 0 0
v_max_id_clo_res 914 914 0 0.00 0 5541 24 0 0
v_max_id_ord_sup 5145 5145 0 0.00 0 4885 8 0 0
v_max_non_paid_invoice 1382 1382 0 0.00 0 187 8 0 0
v_max_non_paid_realizn 3358 3358 0 0.00 0 3524 8 0 0
v_min_id_clo_ord 5027 5027 0 0.00 0 4483 8 0 0
v_min_id_clo_res 914 914 0 0.00 0 1479 13 0 0
v_min_id_ord_sup 5145 5145 0 0.00 0 3241 9 0 0
v_min_non_paid_invoice 5497 5497 0 0.00 0 3159 13 0 0
v_min_non_paid_realizn 6693 6693 0 0.00 0 5373 15 0 0
v_our_firm 9386 9386 0 0.00 0 56 4 0 0
v_reserve_write_off 18598 18598 0 0.00 0 5589 13 0 0
x_lock_depdocs_on_canc_invoice 11092 11092 0 0.00 0 9276 138 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 3417 397 0 0
x_make_qstorno_1000_1200 5033 5033 0 0.00 2 22774 1253 0 0
x_make_qstorno_1200_2000 5151 5151 0 0.00 3 88175 3260 0 0
x_make_qstorno_2100_3300 452734 452734 0 0.00 0 70479 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-05-2022 13:38:45 to 06-05-2022 13:38:49.

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:15 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 26799 8192000000 9717357920 10016985088 796519312 8.861 100 92 185 0 827101280 7685024 22896944
11:09:45 16750 8192000000 9751158432 10059243520 829216048 9.191 100 85 170 0 861900304 12078656 20605600
11:11:09 11461 8192000000 9804021904 10121965568 866919312 9.569 100 84 169 0 907863184 21456416 19487456
11:12:34 9191 8192000000 9847008896 10179055616 911423312 10.011 100 86 172 0 963331968 33161664 18746992
11:13:50 4455 8192000000 9905181264 10246615040 957786064 10.467 100 88 171 0 1021737712 46025664 17925984
11:15:00 1941 8192000000 9955851168 10310877184 986215744 10.745 100 94 183 1 1079655040 74579472 18859824
11:16:05 1019 8192000000 9995160080 10354102272 1009992736 10.975 100 94 186 1 1109567568 81420720 18154112
11:17:08 1174 8192000000 9994985952 10362310656 1029955520 11.168 100 97 186 1 1115960192 67309520 18695152
11:18:11 1261 8192000000 10015483936 10391056384 1053436096 11.394 100 97 189 1 1139977808 66148592 20393120
11:19:14 1596 8192000000 10051217152 10427383808 1063268320 11.488 100 96 183 0 1170264048 89243360 17752368
11:20:17 3996 8192000000 10038665168 10414235648 1067517712 11.528 100 80 157 1 1163316448 80568848 15229888
11:21:23 1055 8192000000 10031054144 10409340928 1075302800 11.603 100 95 178 0 1147482064 54245488 17933776
11:22:25 1400 8192000000 10047076176 10435895296 1081322128 11.660 100 80 156 0 1170068032 72915456 15830448
11:23:27 1071 8192000000 10057532480 10450640896 1089860736 11.741 100 90 170 1 1180325312 73620272 16844304
11:24:29 1328 8192000000 10067022976 10463784960 1094294576 11.783 100 93 174 1 1193608064 80980656 18332832
11:25:33 1625 8192000000 10050661648 10439262208 1088591936 11.729 100 83 161 0 1175195056 69628272 16974848
11:26:36 1415 8192000000 10063672352 10455920640 1096878736 11.808 100 85 168 1 1184523936 70530976 17114224
11:27:38 1076 8192000000 10078670048 10471075840 1102525552 11.862 100 88 171 1 1192666832 72516992 17624288
11:28:40 1406 8192000000 10050939040 10439483392 1093237088 11.773 100 99 187 2 1172992080 59922464 19832528
11:29:42 1078 8192000000 10055978496 10446852096 1096406768 11.804 100 89 170 5 1172959936 59198288 17354880
11:30:44 1603 8192000000 10035448704 10425757696 1080124288 11.649 100 69 132 0 1161116544 67240432 13751824
11:31:47 1147 8192000000 10026265680 10407596032 1070205264 11.554 100 83 155 0 1149812768 62613536 16993968
11:32:49 4333 8192000000 9999333536 10381393920 1040860048 11.273 98 73 135 1 1118378608 63913568 13604992
11:33:54 1313 8192000000 9970293984 10336489472 1022456112 11.096 100 99 173 1 1093045696 53172704 17416880
11:34:56 1023 8192000000 9983059952 10360619008 1021144864 11.083 100 79 155 1 1109366368 73365600 14855904
11:35:58 1172 8192000000 9973556704 10346942464 1023149248 11.102 100 88 158 0 1095518432 55017440 17351744
11:37:01 1441 8192000000 10011045136 10388500480 1046026608 11.323 100 79 150 2 1127554704 65631280 15896816
11:38:10 19733 8192000000 9998699408 10384228352 1050710688 11.367 100 69 131 2 1125855440 62197136 12947616
11:39:36 8128 8192000000 10040977344 10429558784 1068690320 11.540 100 71 139 2 1152482224 69969808 13822096
11:40:50 2515 8192000000 10078410656 10461880320 1082159152 11.668 100 85 163 4 1193327216 94047600 17120464
11:42:10 8201 8192000000 10100560352 10501976064 1101951504 11.856 100 56 111 1 1215840416 101697632 12191280
11:43:28 16723 8192000000 10152353072 10550861824 1108173360 11.915 100 69 135 2 1262466640 139213760 15079520
11:44:51 5137 8192000000 10204913216 10601672704 1129871024 12.120 100 70 138 1 1325318000 179719376 15727600
11:46:00 1497 8192000000 10247163840 10636992512 1143168112 12.245 100 92 173 0 1357976160 195898032 18910016
11:47:06 2256 8192000000 10274258208 10669383680 1154365040 12.350 100 83 162 0 1384808608 212508096 17935472
11:48:13 1524 8192000000 10312058304 10706587648 1162791328 12.429 100 90 177 1 1423753248 241115616 19846304
11:49:19 1029 8192000000 10331965184 10728325120 1162869024 12.430 99 85 167 1 1439483488 256585424 20029040
11:50:25 1760 8192000000 10381112848 10792927232 1176612144 12.559 100 89 172 1 1488758656 293292736 18853776
11:51:32 857 8192000000 10396215664 10801045504 1181769392 12.607 100 96 193 1 1501817008 298193296 21854320
11:52:37 4486 8192000000 10409222016 10825736192 1181347232 12.603 100 96 183 4 1513908496 312127664 20433600
11:53:46 1638 8192000000 10437123648 10837381120 1187728640 12.662 100 95 180 0 1541670880 332554608 21387632
11:54:51 1714 8192000000 10460803728 10882158592 1198039904 12.758 100 99 194 0 1574457600 352783008 23634688
11:55:55 907 8192000000 10505328048 10917937152 1199181280 12.769 100 97 185 0 1612428544 390724128 22523136
11:57:00 1119 8192000000 10536793696 10962006016 1198355152 12.761 100 94 188 2 1642548416 421439728 22753536
11:58:06 1242 8192000000 10534867984 10980405248 1208413408 12.854 100 97 191 0 1644532464 412733680 23385376
11:59:11 1814 8192000000 10574677504 10990440448 1200254704 12.779 100 90 177 0 1681732624 459727584 21750336
12:00:17 839 8192000000 10602790032 11023106048 1212194528 12.889 100 98 192 1 1703951024 468727968 23028528
12:01:20 1144 8192000000 10618776096 11058896896 1216479376 12.929 100 97 190 2 1724113216 483820752 23813088
12:02:24 2916 8192000000 10622130144 11077959680 1202285840 12.798 100 73 141 1 1729644336 509009136 18349360
12:03:33 2223 8192000000 10669379680 11100418048 1212434368 12.892 100 86 172 1 1772507424 539415440 20657616
12:04:41 14591 8192000000 10650027696 11139842048 1204431808 12.817 99 55 110 0 1767858128 548712400 14713920
12:06:15 835 8192000000 10682317952 11160973312 1213181536 12.899 100 74 145 0 1782407488 551218928 18007024
12:07:20 7573 8192000000 10693094800 11124760576 1191958080 12.702 100 61 122 1 1803842384 595398032 16486272
12:08:40 5693 8192000000 10667694880 11113861120 1176228368 12.555 100 61 116 0 1768139472 576356496 15554608
12:10:06 18267 8192000000 10553235024 11101147136 1171925808 12.515 100 39 78 0 1664121488 481590352 10605328
12:12:16 26786 8192000000 10389762160 10913472512 1120044800 12.027 98 31 62 0 1504296320 375651728 8599792
12:13:51 32799 8192000000 10200899584 10737704960 1144047936 12.254 100 24 48 0 1334652480 185068256 5536288
12:15:26 64202 8192000000 10078645440 10578829312 1140289600 12.218 100 6 12 0 1221365936 79732192 1344144
12:17:31 73126 8192000000 10084130352 10519957504 1077842336 11.627 93 23 44 0 1124623856 42345840 4435680
12:20:05 172074 8192000000 10086839472 10579349504 1091607888 11.758 95 29 57 0 1142065968 44726560 5731520
12:24:11 71328 8192000000 10090885312 10524717056 1110240624 11.935 97 32 63 0 1150819072 34373696 6204752
12:26:32 9903 8192000000 10139202640 10571329536 1147513888 12.286 100 58 112 1 1226949088 67744128 11691072
12:27:43 16718 8192000000 10174307552 10607607808 1140754544 12.223 99 52 96 2 1256914976 105654208 10506224
12:29:04 6215 8192000000 10219548224 10636689408 1154756032 12.354 100 85 167 1 1311066832 138567344 17743456
12:30:11 65385 8192000000 10180993968 10684981248 1128339600 12.106 98 25 50 0 1322387600 187806848 6241152
12:32:19 33829 8192000000 10175698192 10678059008 1141138432 12.226 100 14 28 0 1260941504 116839264 2963808
12:34:29 32721 8192000000 10083378176 10566803456 1116682880 11.996 98 28 57 1 1166184336 43358576 6142880
12:36:02 74031 8192000000 10041820864 10473299968 1065970816 11.514 94 24 50 0 1097038832 25410576 5657440
12:38:17 69570 8192000000 10105662896 10602512384 1121682928 12.043 99 17 34 0 1182361296 57002000 3676368
12:41:10 80737 8192000000 10051563536 10510815232 1125431008 12.078 99 19 38 0 1158153392 28756944 3965440
12:43:32 101478 8192000000 10114744192 10607529984 1140418144 12.219 100 15 29 2 1210059568 66350096 3291328
12:46:14 83840 8192000000 10081342416 10553815040 1106508192 11.899 97 9 18 0 1136305792 27947520 1850080
12:48:38 53603 8192000000 10091834800 10553094144 1133287280 12.152 99 18 36 0 1191534832 54566096 3681456
12:50:38 24264 8192000000 10128880672 10578210816 1138018752 12.197 99 49 96 1 1216899936 67843824 11037360
12:52:04 45745 8192000000 10076219056 10521423872 1110833040 11.940 97 30 58 2 1154736016 38042256 5860720
12:53:50 46259 8192000000 10114523376 10598248448 1138357152 12.200 99 37 74 1 1238642912 92853136 7432624
12:55:42 15381 8192000000 10081696160 10523619328 1114827104 11.978 98 38 76 0 1161718704 39422880 7468720
12:57:03 10641 8192000000 10147388384 10572988416 1122448896 12.050 99 57 111 0 1217358400 83934128 10975376
12:58:24 55932 8192000000 10108590048 10595041280 1113270496 11.963 98 23 46 0 1225433584 107466464 4696624
13:00:21 14431 8192000000 10124903808 10547425280 1129486848 12.117 99 52 101 1 1202999968 63607408 9905712
13:01:52 99160 8192000000 10099060736 10540519424 1101946752 11.856 97 50 100 1 1181944576 69409472 10588352
13:04:31 67525 8192000000 10063291584 10542485504 1094727728 11.788 97 17 33 0 1143416288 45073968 3614592
13:06:58 48619 8192000000 10082714096 10560774144 1121546672 12.042 99 22 42 3 1168770560 42444240 4779648
13:09:16 100779 8192000000 10109666032 10583429120 1133368304 12.153 99 11 19 1 1221742944 86319808 2054832
13:11:57 52843 8192000000 10099954416 10543001600 1136501632 12.183 99 38 74 1 1185587936 41829632 7256672
13:13:50 46134 8192000000 10126169392 10571755520 1136425584 12.182 99 24 48 0 1207128640 65661552 5041504
13:15:43 50984 8192000000 10147370640 10583474176 1122937680 12.055 98 40 78 2 1231301488 99894720 8469088
13:17:37 108917 8192000000 10073542544 10529234944 1058320896 11.440 93 29 58 0 1147869552 83684400 5864256
13:20:27 58980 8192000000 10063890352 10507022336 1073620064 11.587 95 14 28 0 1113144096 36950400 2573632
13:22:28 129746 8192000000 10100868400 10578890752 1107985344 11.913 98 43 87 0 1196768576 80079664 8703568
13:25:39 25972 8192000000 10091072720 10536505344 1115129664 11.981 99 43 86 0 1182011984 58036048 8846272
13:27:09 44145 8192000000 10070739152 10565038080 1126709536 12.090 100 7 14 0 1185474832 57308240 1457056
13:28:57 38968 8192000000 10109364144 10582708224 1135286640 12.171 100 14 28 0 1207144784 68937600 2920544
13:30:37 70922 8192000000 10076303040 10526384128 1107810176 11.912 98 17 34 0 1172605248 60184608 4610464
13:33:28 41746 8192000000 10117235456 10567897088 1123995104 12.065 99 29 58 0 1198809552 68075056 6739392
13:35:10 46379 8192000000 10123145792 10569793536 1135682832 12.175 100 25 50 2 1219884640 79439968 4761840
13:37:01 28367 8192000000 9163891456 9487421440 301755312 3.552 27 4 8 0 342656720 40117792 783616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-05-2022 13:38:51 to 06-05-2022 13:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 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 06-05-2022 13:39:07 to 06-05-2022 13:39:09.

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          213715
MON$OLDEST_ACTIVE               213716
MON$OLDEST_SNAPSHOT             213716
MON$NEXT_TRANSACTION            213716
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                       2092928
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
--- end of diff output ---

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

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