Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.05.2022 22: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 16520216 kB
MemAvailable 31376636 kB
Buffers 0 kB
Cached 15162564 kB
SwapCached 2588 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-22 22:38:14
DTS_END                         2022-05-22 22:38:14.4120
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(57:17)
IP                              ::1/41488
TRN_ID                          236348
ATT_ID                          648
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: 8955.95 21807 1074714
customer order: creation 166.92 342 20030
customer order: refuse 13.89 384 1667
order to supplier: creation 43.38 1228 5206
order to supplier: removal 3.48 107784 418
invoice (draft): creation 45.83 3636 5500
invoice (draft): removal 52.38 915 6285
invoice accept: apply 39.88 18833 4786
invoice accept: cancel 47.29 896 5675
customer reserve: creation 3682.69 186 441923
customer reserve: removal 4568.02 64 548163
realization accept: apply 80.29 158 9635
realization accept: cancel 87.28 103 10474
payment from customer: creation 56.68 114 6802
payment from customer: removal 5.74 83 689
payment to supplier: creation 47.18 128 5662
payment to supplier: removal 3.53 72 423
service: total inventory turnovers 4.61 1031 553
service: total monetary turnovers 6.11 22 733
service: aggregate perf. data 0.72 24229 86
service: refresh index statistics 0.03 275936 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-05-2022 22:39:23 to 22-05-2022 22:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7951
TEST_TIME 2 4238
TEST_TIME 3 4190
TEST_TIME 4 4775
TEST_TIME 5 5333
TEST_TIME 6 5198
TEST_TIME 7 4879
TEST_TIME 8 4848
TEST_TIME 9 4948
TEST_TIME 10 4847
TEST_TIME 11 5013
TEST_TIME 12 5235
TEST_TIME 13 5578
TEST_TIME 14 5651
TEST_TIME 15 6182
TEST_TIME 16 6325
TEST_TIME 17 6821
TEST_TIME 18 6844
TEST_TIME 19 7315
TEST_TIME 20 7465
TEST_TIME 21 8053
TEST_TIME 22 8635
TEST_TIME 23 8588
TEST_TIME 24 8876
TEST_TIME 25 9202
TEST_TIME 26 9532
TEST_TIME 27 9816
TEST_TIME 28 10805
TEST_TIME 29 10814
TEST_TIME 30 10654
TEST_TIME 31 10376
TEST_TIME 32 10229
TEST_TIME 33 10132
TEST_TIME 34 10119
TEST_TIME 35 9930
TEST_TIME 36 10001
TEST_TIME 37 9885
TEST_TIME 38 9848
TEST_TIME 39 9853
TEST_TIME 40 9746
TEST_TIME 41 9626
TEST_TIME 42 9552
TEST_TIME 43 9418
TEST_TIME 44 9277
TEST_TIME 45 9170
TEST_TIME 46 9408
TEST_TIME 47 9307
TEST_TIME 48 9288
TEST_TIME 49 9227
TEST_TIME 50 9150
TEST_TIME 51 9205
TEST_TIME 52 9288
TEST_TIME 53 9338
TEST_TIME 54 9252
TEST_TIME 55 9365
TEST_TIME 56 9327
TEST_TIME 57 9373
TEST_TIME 58 9474
TEST_TIME 59 9447
TEST_TIME 60 9496
TEST_TIME 61 9479
TEST_TIME 62 9493
TEST_TIME 63 9400
TEST_TIME 64 9347
TEST_TIME 65 9316
TEST_TIME 66 9251
TEST_TIME 67 9221
TEST_TIME 68 9134
TEST_TIME 69 9145
TEST_TIME 70 9128
TEST_TIME 71 9079
TEST_TIME 72 9094
TEST_TIME 73 9082
TEST_TIME 74 9114
TEST_TIME 75 9111
TEST_TIME 76 9111
TEST_TIME 77 9209
TEST_TIME 78 9237
TEST_TIME 79 9237
TEST_TIME 80 9216
TEST_TIME 81 9251
TEST_TIME 82 9216
TEST_TIME 83 9248
TEST_TIME 84 9281
TEST_TIME 85 9259
TEST_TIME 86 9174
TEST_TIME 87 9140
TEST_TIME 88 9135
TEST_TIME 89 9122
TEST_TIME 90 9106
TEST_TIME 91 9046
TEST_TIME 92 9000
TEST_TIME 93 9017
TEST_TIME 94 8984
TEST_TIME 95 8983
TEST_TIME 96 9008
TEST_TIME 97 9020
TEST_TIME 98 9023
TEST_TIME 99 9001
TEST_TIME 100 9032
TEST_TIME 101 9041
TEST_TIME 102 9042
TEST_TIME 103 9061
TEST_TIME 104 9114
TEST_TIME 105 9088
TEST_TIME 106 9130
TEST_TIME 107 9220
TEST_TIME 108 9179
TEST_TIME 109 9117
TEST_TIME 110 9074
TEST_TIME 111 9032
TEST_TIME 112 9059
TEST_TIME 113 9007
TEST_TIME 114 9017
TEST_TIME 115 8989
TEST_TIME 116 8950
TEST_TIME 117 8950
TEST_TIME 118 8961
TEST_TIME 119 9027
TEST_TIME 120 8958

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-05-2022 22:39:27 to 22-05-2022 22:39:30.

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 1482933 1482933 0 0.00 0 14460 6 0 0
doc_list_biud 1482933 1482933 0 0.00 0 76563 30 0 0
sp_add_invoice_to_stock 4929 4786 143 2.90 37 218282 18834 0 143
sp_cancel_adding_invoice 5675 5675 0 0.00 4 97108 897 0 0
sp_cancel_client_order 1667 1667 0 0.00 4 18214 384 0 0
sp_cancel_customer_reserve 548163 548163 0 0.00 1 59792 65 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 5092 83 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 2 6492 73 0 0
sp_cancel_supplier_invoice 6298 6285 13 0.20 2 76571 916 0 13
sp_cancel_supplier_order 418 418 0 0.00 8 1040632 107785 0 0
sp_cancel_write_off 10474 10474 0 0.00 1 12362 104 0 0
sp_client_order 20030 20030 0 0.00 3 30263 342 0 0
sp_customer_reserve 441923 441923 0 0.00 0 63584 186 0 0
sp_fill_shopping_cart 30732 30732 0 0.00 1 17012 41 0 0
sp_fill_shopping_cart_clo_res 441918 441918 0 0.00 0 31695 14 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 46605 355 0 0
sp_kill_cost_storno 17261 17261 0 0.00 0 8753 54 0 0
sp_kill_qstorno_ret_qs2qd 1113503 1113503 0 0.00 0 76560 35 0 0
sp_kill_qty_storno 587098 587098 0 0.00 0 76562 76 0 0
sp_lock_selected_doc 20082 20082 0 0.00 0 8558 7 0 0
sp_make_cost_storno 26880 26880 0 0.00 0 14444 53 0 0
sp_multiply_rows_for_pdistr 26880 26880 0 0.00 0 8875 28 0 0
sp_multiply_rows_for_qdistr 20030 20030 0 0.00 0 8756 155 0 0
sp_pay_from_customer 6802 6802 0 0.00 4 26641 114 0 0
sp_pay_to_supplier 5662 5662 0 0.00 5 13511 129 0 0
sp_payment_common 12464 12464 0 0.00 1 26631 34 0 0
sp_qd_handle_on_cancel_clo 1667 1667 0 0.00 0 18205 349 0 0
sp_qd_handle_on_invoice_upd_sts 10456 10456 0 0.00 0 54926 371 0 0
sp_qd_handle_on_reserve_upd_sts 9635 9635 0 0.00 0 14382 44 0 0
sp_reserve_write_off 9635 9635 0 0.00 6 16988 158 0 0
sp_supplier_invoice 5500 5500 0 0.00 6 93647 3636 0 0
sp_supplier_order 5206 5206 0 0.00 5 30585 1228 0 0
srv_aggregate_perf_data 86 86 0 0.00 2797 414735 24230 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2209 186063 52586 0 0
srv_find_qd_qs_mism 1028232 1028232 0 0.00 0 13845 5 0 0
srv_make_invnt_saldo 553 553 0 0.00 58 38316 1031 0 0
srv_make_money_saldo 733 733 0 0.00 1 384 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 182353 421689 275936 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4786 4786 0 0.00 0 25988 187 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 7696 98 0 0
t$perf-norm:sp_cancel_client_order 1667 1667 0 0.00 0 5586 13 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 2942 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 5628 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 1639 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 4753 31 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 34933 1671 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1681 9 0 0
t$perf-norm:sp_client_order 20030 20030 0 0.00 0 10177 18 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 7811 19 0 0
t$perf-norm:sp_pay_from_customer 6802 6802 0 0.00 0 6191 10 0 0
t$perf-norm:sp_pay_to_supplier 5662 5662 0 0.00 0 5988 10 0 0
t$perf-norm:sp_reserve_write_off 9635 9635 0 0.00 0 6627 13 0 0
t$perf-norm:sp_supplier_invoice 5500 5500 0 0.00 0 6778 19 0 0
t$perf-norm:sp_supplier_order 5206 5206 0 0.00 0 3498 14 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 680 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 106 2 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 2163 24 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 2663 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9844 9558 286 2.90 0 7061 20 0 286
v_all_customers 36542 36542 0 0.00 0 15 0 0 0
v_all_suppliers 29636 29636 0 0.00 0 108 1 0 0
v_all_wares 40058 40058 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 5666 23 0 0
v_cancel_client_order 3334 3334 0 0.00 0 9734 7 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 2178 8 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 2109 6 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 410 4 0 0
v_cancel_supplier_invoice 780 754 26 3.33 0 2018 16 0 26
v_cancel_supplier_order 836 836 0 0.00 0 3010 7 0 0
v_cancel_write_off 2022 2022 0 0.00 0 2006 15 0 0
v_max_id_clo_ord 5205 5205 0 0.00 0 647 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 1891 12 0 0
v_max_id_ord_sup 5498 5498 0 0.00 0 4777 5 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 1342 4 0 0
v_max_non_paid_realizn 3561 3561 0 0.00 0 7136 6 0 0
v_min_id_clo_ord 5205 5205 0 0.00 0 7524 12 0 0
v_min_id_clo_res 934 934 0 0.00 0 8041 26 0 0
v_min_id_ord_sup 5498 5498 0 0.00 0 4981 8 0 0
v_min_non_paid_invoice 5662 5662 0 0.00 0 9962 23 0 0
v_min_non_paid_realizn 6802 6802 0 0.00 0 7397 23 0 0
v_our_firm 9998 9998 0 0.00 0 108 1 0 0
v_reserve_write_off 19268 19268 0 0.00 0 2097 5 0 0
x_lock_depdocs_on_canc_invoice 11583 11583 0 0.00 0 11976 114 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 35159 484 0 0
x_make_qstorno_1000_1200 5206 5206 0 0.00 1 28065 1145 0 0
x_make_qstorno_1200_2000 5500 5500 0 0.00 3 92984 3491 0 0
x_make_qstorno_2100_3300 441923 441923 0 0.00 0 63551 169 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-05-2022 22:39:31 to 22-05-2022 22:39:36.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:18 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:19 21282 8192000000 9891626176 10199363584 969261792 10.580 100 84 168 1 997659536 5633584 22764160
20:10:47 28432 8192000000 9955621904 10277601280 1031449936 11.182 100 91 182 0 1062603616 7594272 23559408
20:12:20 18520 8192000000 10000954944 10335428608 1084411152 11.689 100 90 180 0 1116666112 10065936 22189024
20:13:49 12188 8192000000 10048598240 10399117312 1130663728 12.128 100 73 146 0 1159314512 11671360 16979424
20:15:04 6816 8192000000 10096746224 10457276416 1182371552 12.612 100 85 170 2 1215894528 13715888 19807088
20:16:15 4930 8192000000 10126586144 10494525440 1217573792 12.939 100 86 169 0 1250029232 14045376 18410064
20:17:23 10149 8192000000 10165686592 10542518272 1250649648 13.244 100 81 162 0 1281957072 13744624 17562800
20:18:35 1920 8192000000 10211192800 10592698368 1292953584 13.631 100 98 196 1 1327511920 12689328 21869008
20:19:39 28809 8192000000 10222403008 10611654656 1307457728 13.763 100 60 121 1 1330181024 9310992 13412304
20:21:09 3562 8192000000 10224159472 10624323584 1317618864 13.855 100 55 112 0 1339616608 10028816 11968928
20:22:13 6189 8192000000 10218391440 10626260992 1327290352 13.943 100 32 62 0 1341518880 7036752 7191776
20:23:23 6694 8192000000 10219166800 10636976128 1329989232 13.967 100 24 48 1 1346216000 10640512 5586256
20:24:32 32150 8192000000 10243048672 10649067520 1336334768 14.024 100 50 100 1 1354274416 5645504 12294144
20:26:04 5886 8192000000 10235810192 10659946496 1343138736 14.086 100 28 56 3 1358244544 9210960 5894848
20:27:12 7372 8192000000 10233378656 10654912512 1333199200 13.996 99 24 49 2 1345979520 7025072 5755248
20:28:20 9901 8192000000 10205613584 10620030976 1283328992 13.543 97 21 42 0 1294073840 5531056 5213792
20:29:32 25477 8192000000 10160259840 10567839744 1199113856 12.768 92 28 57 1 1212519792 6300688 7105248
20:30:58 5028 8192000000 10150390800 10557837312 1264264992 13.369 100 23 46 0 1276377776 7186800 4925984
20:32:03 8202 8192000000 10146056688 10547912704 1235775984 13.107 98 31 60 1 1248423296 5092688 7554624
20:33:15 9154 8192000000 10156234624 10560307200 1232168176 13.074 97 21 42 0 1246285392 8981488 5135728
20:34:29 13306 8192000000 10180794592 10584485888 1276711952 13.483 100 61 122 0 1297633312 6259920 14661440
20:35:53 11321 8192000000 10192618448 10605887488 1297488864 13.672 100 35 70 1 1312292560 5915376 8888320
20:37:11 9403 8192000000 10199443952 10616201216 1307686592 13.765 100 27 51 1 1319510576 5875520 5948464
20:38:21 24890 8192000000 10236173792 10644561920 1331948320 13.985 100 47 94 0 1349512448 6440240 11123888
20:39:48 119307 8192000000 10272546544 10689617920 1360225632 14.239 100 47 94 1 1382466848 11296272 10944944
20:42:56 14851 8192000000 10283505536 10704773120 1363712656 14.271 99 39 77 1 1388618080 15808992 9096432
20:44:26 16598 8192000000 10307388640 10728329216 1389211296 14.499 100 48 96 1 1418229824 17749776 11268752
20:45:59 22034 8192000000 10315591664 10740449280 1393587280 14.538 100 52 104 1 1431798528 25662480 12548768
20:47:40 9086 8192000000 10342716272 10769121280 1410009248 14.684 100 43 84 0 1452490496 31853984 10627264
20:48:54 4868 8192000000 10353858896 10770317312 1412759376 14.708 100 62 123 0 1463300832 34775600 15765856
20:50:06 4123 8192000000 10359305008 10776743936 1410839424 14.691 99 70 139 3 1467206944 39333392 17034128
20:51:16 15005 8192000000 10364948528 10783526912 1409109632 14.676 99 59 117 0 1467114768 42871584 15133552
20:52:49 4710 8192000000 10350440320 10767028224 1397745344 14.575 100 67 134 0 1461485616 46771632 16968640
20:53:58 2632 8192000000 10362131120 10766557184 1388580624 14.493 100 82 164 1 1459927600 50757504 20589472
20:55:09 2202 8192000000 10375869072 10781003776 1406884512 14.656 100 93 188 1 1481229488 50042192 24302784
20:56:17 6303 8192000000 10362952288 10768625664 1381258176 14.428 99 73 145 1 1452200304 52521968 18420160
20:57:29 9373 8192000000 10360772304 10783678464 1394159888 14.543 100 53 107 2 1466143712 56694752 15289072
20:58:43 4579 8192000000 10379807968 10788495360 1398818176 14.584 100 62 125 3 1474442384 59361104 16263104
20:59:55 7990 8192000000 10371517072 10794176512 1407568576 14.662 100 51 102 2 1474267856 53298288 13400992
21:01:23 8854 8192000000 10339956368 10767044608 1383916912 14.452 99 41 82 0 1437547696 42084496 11546288
21:03:17 33668 8192000000 10329896864 10777538560 1370185008 14.329 98 29 58 1 1422534720 43595872 8753840
21:05:04 25332 8192000000 10298413280 10734129152 1328109312 13.950 98 23 46 1 1374904336 39942944 6852080
21:06:31 35161 8192000000 10285641520 10742951936 1365359584 14.285 100 11 22 1 1396883584 28824800 2699200
21:08:08 66907 8192000000 10281044864 10725003264 1346778928 14.118 98 27 54 0 1368836480 15706960 6350592
21:10:31 105041 8192000000 10287175680 10739576832 1360653952 14.243 98 33 64 1 1377818848 9892448 7272448
21:13:23 94604 8192000000 10265224752 10728914944 1330983392 13.976 96 8 14 1 1343393440 10997904 1412144
21:15:58 100004 8192000000 10274301024 10724032512 1336279200 14.024 97 19 39 0 1353836752 12960736 4596816
21:18:40 35126 8192000000 10269555376 10720534528 1363349552 14.267 99 25 50 0 1375932736 7229728 5353456
21:20:21 43723 8192000000 10283642880 10735951872 1382732176 14.441 100 17 34 0 1398065616 11139760 4193680
21:22:11 91064 8192000000 10294332896 10745581568 1390024128 14.506 100 35 70 1 1413505760 14481632 9000000
21:24:42 41812 8192000000 10301289952 10753466368 1395273728 14.553 100 19 38 1 1412648544 13085904 4288912
21:26:30 71475 8192000000 10298672864 10765524992 1399309184 14.589 100 12 24 0 1416416304 14347024 2760096
21:28:43 73241 8192000000 10300113728 10766409728 1373560896 14.359 98 12 24 0 1396769296 20244304 2964096
21:31:16 45352 8192000000 10295290768 10750853120 1371870544 14.344 98 16 32 1 1383013776 7781024 3362208
21:33:02 27053 8192000000 10303836864 10751569920 1380629184 14.422 99 49 98 2 1404596928 13075152 10892592
21:34:30 39300 8192000000 10292966912 10742431744 1361216896 14.248 98 33 66 0 1382194352 13751664 7225792
21:36:09 82139 8192000000 10289875456 10751094784 1386801328 14.477 100 8 16 0 1403015648 14326704 1887616
21:38:57 70713 8192000000 10297221040 10732814336 1322468448 13.899 95 27 52 0 1335241888 7470048 5303392
21:41:09 152584 8192000000 10278377120 10729160704 1314898080 13.830 95 28 56 1 1330672896 9358000 6416816
21:44:46 71085 8192000000 10275475840 10733191168 1368139952 14.310 99 25 50 1 1385607520 11751616 5715952
21:46:57 33727 8192000000 10271587936 10732060672 1366146640 14.293 99 11 22 1 1382482320 13701056 2634624
21:48:47 36186 8192000000 10262746304 10729099264 1358958448 14.228 99 4 8 0 1373013824 13159328 896048
21:50:24 83519 8192000000 10261728080 10711597056 1317446896 13.854 96 13 26 0 1334748368 14510480 2790992
21:52:51 54789 8192000000 10274740816 10715566080 1323024912 13.904 97 17 34 0 1336194064 9404480 3764672
21:54:46 31092 8192000000 10261926768 10705154048 1327628320 13.946 97 21 42 0 1345955216 13925888 4401008
21:56:18 83524 8192000000 10248984992 10693599232 1299774208 13.693 96 8 16 1 1318796000 17192448 1829344
21:58:53 64441 8192000000 10264072464 10708549632 1318993168 13.868 97 32 64 2 1340726048 14790496 6942384
22:01:00 19212 8192000000 10291543376 10724597760 1369460832 14.322 100 44 88 0 1390573280 11709856 9402592
22:02:23 123329 8192000000 10263355440 10719752192 1326693856 13.937 97 16 32 0 1344015472 13608816 3712800
22:05:36 49132 8192000000 10249421440 10703912960 1321939536 13.894 97 14 28 0 1336994272 12137696 2917040
22:07:30 83324 8192000000 10272230272 10736037888 1358402304 14.223 99 12 24 0 1372292608 11281184 2609120
22:10:02 82541 8192000000 10275245792 10734694400 1366033040 14.291 99 15 30 0 1380439008 10868448 3537520
22:12:25 82126 8192000000 10267758208 10730790912 1356864416 14.209 99 9 18 0 1369071392 9614128 2592848
22:14:59 76901 8192000000 10270373472 10735747072 1356925648 14.210 99 11 22 2 1374583328 15399104 2258576
22:17:16 40418 8192000000 10288466240 10738606080 1371045024 14.336 99 39 78 0 1392608704 12767728 8795952
22:18:58 72736 8192000000 10300609152 10748542976 1365165072 14.284 98 28 56 0 1379832480 8621568 6045840
22:21:11 62230 8192000000 10289476752 10742013952 1337735360 14.037 96 26 52 0 1353516544 10179328 5601856
22:23:26 66025 8192000000 10292794816 10741833728 1344415648 14.097 97 27 54 0 1361735840 11112128 6208064
22:25:59 186063 8192000000 10303993184 10756325376 1380365408 14.420 99 14 28 0 1394168528 10864112 2939008
22:30:11 66591 8192000000 10292009456 10760908800 1384870640 14.460 99 9 18 0 1403516512 16437904 2207968
22:33:21 52508 8192000000 10265610896 10720313344 1312029984 13.804 95 14 29 0 1327989312 12882656 3076672
22:35:14 68390 8192000000 10284665024 10742697984 1345900352 14.111 98 23 46 1 1371040400 19622432 5517616
22:37:27 53466 8192000000 8953906192 9203265536 4050272 0.049 1 1 2 0 4155152 62528 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-05-2022 22:39:37 to 22-05-2022 22:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-05-2022 22:39:53 to 22-05-2022 22:39:55.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236689
MON$OLDEST_ACTIVE               236690
MON$OLDEST_SNAPSHOT             236690
MON$NEXT_TRANSACTION            236690
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-03 16:41:55.3470
MON$PAGES                       2019840
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

22.05.22 22:39:56. There are 3 logs in /var/tmp/logs-oltp30 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-22 22:39:58 No severe PSQL-related problems occured
22.05.22 22:40:00. End of report.