Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2021 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16277480 kB
MemAvailable 30868660 kB
Buffers 0 kB
Cached 15448708 kB
SwapCached 4792 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.8
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 2021-10-29 01:37:53
DTS_END                         2021-10-29 01:37:53.2180
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/39204
TRN_ID                          229076
ATT_ID                          653
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: 9132.56 24002 1095907
customer order: creation 160.53 352 19264
customer order: refuse 13.31 408 1597
order to supplier: creation 42.73 1237 5128
order to supplier: removal 3.48 107675 417
invoice (draft): creation 42.70 3258 5124
invoice (draft): removal 54.70 775 6564
invoice accept: apply 38.83 18063 4659
invoice accept: cancel 49.29 801 5915
customer reserve: creation 3580.54 178 429665
customer reserve: removal 4854.12 60 582494
realization accept: apply 77.85 187 9342
realization accept: cancel 93.14 104 11177
payment from customer: creation 54.83 129 6580
payment from customer: removal 5.50 93 660
payment to supplier: creation 45.28 147 5434
payment to supplier: removal 3.68 119 442
service: total inventory turnovers 4.76 988 571
service: total monetary turnovers 6.40 39 768
service: aggregate perf. data 0.85 17189 102
service: refresh index statistics 0.03 328246 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2021 01:39:45 to 29-10-2021 01:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3461
TEST_TIME 2 4788
TEST_TIME 3 4533
TEST_TIME 4 4582
TEST_TIME 5 4553
TEST_TIME 6 5054
TEST_TIME 7 4919
TEST_TIME 8 4753
TEST_TIME 9 4777
TEST_TIME 10 5060
TEST_TIME 11 5193
TEST_TIME 12 5216
TEST_TIME 13 5478
TEST_TIME 14 5712
TEST_TIME 15 6171
TEST_TIME 16 6276
TEST_TIME 17 6771
TEST_TIME 18 7024
TEST_TIME 19 7105
TEST_TIME 20 7923
TEST_TIME 21 8534
TEST_TIME 22 8582
TEST_TIME 23 8673
TEST_TIME 24 8912
TEST_TIME 25 9135
TEST_TIME 26 10101
TEST_TIME 27 10612
TEST_TIME 28 10469
TEST_TIME 29 10513
TEST_TIME 30 10427
TEST_TIME 31 10174
TEST_TIME 32 10038
TEST_TIME 33 9866
TEST_TIME 34 9890
TEST_TIME 35 9796
TEST_TIME 36 9813
TEST_TIME 37 9559
TEST_TIME 38 9861
TEST_TIME 39 9672
TEST_TIME 40 9716
TEST_TIME 41 9578
TEST_TIME 42 9594
TEST_TIME 43 9547
TEST_TIME 44 9395
TEST_TIME 45 9345
TEST_TIME 46 9387
TEST_TIME 47 9314
TEST_TIME 48 9300
TEST_TIME 49 9267
TEST_TIME 50 9239
TEST_TIME 51 9213
TEST_TIME 52 9131
TEST_TIME 53 8970
TEST_TIME 54 8958
TEST_TIME 55 8953
TEST_TIME 56 8983
TEST_TIME 57 8927
TEST_TIME 58 8984
TEST_TIME 59 9128
TEST_TIME 60 9092
TEST_TIME 61 9166
TEST_TIME 62 9262
TEST_TIME 63 9356
TEST_TIME 64 9413
TEST_TIME 65 9456
TEST_TIME 66 9480
TEST_TIME 67 9427
TEST_TIME 68 9479
TEST_TIME 69 9471
TEST_TIME 70 9393
TEST_TIME 71 9318
TEST_TIME 72 9247
TEST_TIME 73 9226
TEST_TIME 74 9193
TEST_TIME 75 9113
TEST_TIME 76 9134
TEST_TIME 77 9046
TEST_TIME 78 9120
TEST_TIME 79 9202
TEST_TIME 80 9228
TEST_TIME 81 9298
TEST_TIME 82 9295
TEST_TIME 83 9257
TEST_TIME 84 9280
TEST_TIME 85 9322
TEST_TIME 86 9392
TEST_TIME 87 9340
TEST_TIME 88 9384
TEST_TIME 89 9417
TEST_TIME 90 9410
TEST_TIME 91 9377
TEST_TIME 92 9297
TEST_TIME 93 9274
TEST_TIME 94 9232
TEST_TIME 95 9202
TEST_TIME 96 9198
TEST_TIME 97 9146
TEST_TIME 98 9175
TEST_TIME 99 9136
TEST_TIME 100 9107
TEST_TIME 101 9080
TEST_TIME 102 9105
TEST_TIME 103 9113
TEST_TIME 104 9078
TEST_TIME 105 9129
TEST_TIME 106 9192
TEST_TIME 107 9231
TEST_TIME 108 9204
TEST_TIME 109 9217
TEST_TIME 110 9225
TEST_TIME 111 9259
TEST_TIME 112 9301
TEST_TIME 113 9315
TEST_TIME 114 9267
TEST_TIME 115 9206
TEST_TIME 116 9194
TEST_TIME 117 9175
TEST_TIME 118 9177
TEST_TIME 119 9174
TEST_TIME 120 9140

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-10-2021 01:39:49 to 29-10-2021 01:39:52.

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 1494229 1494229 0 0.00 0 24156 6 0 0
doc_list_biud 1494226 1494226 0 0.00 0 26109 29 0 0
sp_add_invoice_to_stock 4783 4659 124 2.59 57 204398 18063 0 124
sp_cancel_adding_invoice 5915 5915 0 0.00 3 61089 802 0 0
sp_cancel_client_order 1597 1597 0 0.00 4 15426 408 0 0
sp_cancel_customer_reserve 582494 582494 0 0.00 1 15771 60 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 2 12652 94 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 9312 119 0 0
sp_cancel_supplier_invoice 6574 6564 10 0.15 2 26117 775 0 10
sp_cancel_supplier_order 417 417 0 0.00 8 1166781 107675 0 0
sp_cancel_write_off 11177 11177 0 0.00 1 8091 105 0 0
sp_client_order 19264 19264 0 0.00 3 21010 352 0 0
sp_customer_reserve 429665 429665 0 0.00 0 69947 179 0 0
sp_fill_shopping_cart 29508 29508 0 0.00 1 23360 38 0 0
sp_fill_shopping_cart_clo_res 429654 429654 0 0.00 0 13294 15 0 0
sp_get_clo_for_invoice 4647 4647 0 0.00 0 26449 356 0 0
sp_kill_cost_storno 18194 18194 0 0.00 0 6711 58 0 0
sp_kill_qstorno_ret_qs2qd 1183146 1183146 0 0.00 0 26106 32 0 0
sp_kill_qty_storno 622153 622153 0 0.00 0 26108 69 0 0
sp_lock_selected_doc 19480 19480 0 0.00 0 12643 7 0 0
sp_make_cost_storno 26003 26003 0 0.00 0 24148 54 0 0
sp_multiply_rows_for_pdistr 26003 26003 0 0.00 0 7613 31 0 0
sp_multiply_rows_for_qdistr 19264 19264 0 0.00 0 13411 155 0 0
sp_pay_from_customer 6580 6580 0 0.00 4 12725 129 0 0
sp_pay_to_supplier 5434 5434 0 0.00 4 11566 148 0 0
sp_payment_common 12013 12013 0 0.00 1 11450 32 0 0
sp_qd_handle_on_cancel_clo 1597 1597 0 0.00 0 15417 366 0 0
sp_qd_handle_on_invoice_upd_sts 10562 10562 0 0.00 0 22971 325 0 0
sp_qd_handle_on_reserve_upd_sts 9342 9342 0 0.00 0 13496 57 0 0
sp_reserve_write_off 9342 9342 0 0.00 6 18133 188 0 0
sp_supplier_invoice 5124 5124 0 0.00 6 95635 3258 0 0
sp_supplier_order 5128 5128 0 0.00 5 57217 1238 0 0
srv_aggregate_perf_data 102 102 0 0.00 2849 299967 17189 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1345 120740 45278 0 0
srv_find_qd_qs_mism 1051639 1051639 0 0.00 0 8792 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 39 29262 989 0 0
srv_make_money_saldo 768 768 0 0.00 1 13882 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 56256 614303 328247 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4659 4659 0 0.00 0 8748 162 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 7995 111 0 0
t$perf-norm:sp_cancel_client_order 1597 1597 0 0.00 0 1455 9 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 853 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 1784 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 449 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 2453 15 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 26924 1489 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 10896 20 0 0
t$perf-norm:sp_client_order 19264 19264 0 0.00 0 31508 21 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 2233 6 0 0
t$perf-norm:sp_pay_from_customer 6580 6580 0 0.00 0 8020 6 0 0
t$perf-norm:sp_pay_to_supplier 5434 5434 0 0.00 0 6485 10 0 0
t$perf-norm:sp_reserve_write_off 9342 9342 0 0.00 0 15469 14 0 0
t$perf-norm:sp_supplier_invoice 5124 5124 0 0.00 0 4443 13 0 0
t$perf-norm:sp_supplier_order 5128 5128 0 0.00 0 34133 20 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 136 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 558 16 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 592 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 102 48 0 0
v_add_invoice_to_stock 9538 9290 248 2.60 0 12463 28 0 248
v_all_customers 35204 35204 0 0.00 0 21 1 0 0
v_all_suppliers 28432 28432 0 0.00 0 79 1 0 0
v_all_wares 38526 38526 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 3614 10 0 0
v_cancel_client_order 3194 3194 0 0.00 0 11407 11 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 9249 14 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 5262 8 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 9274 19 0 0
v_cancel_supplier_invoice 832 812 20 2.40 0 2045 18 0 20
v_cancel_supplier_order 834 834 0 0.00 0 3199 9 0 0
v_cancel_write_off 1830 1830 0 0.00 0 1992 15 0 0
v_max_id_clo_ord 5126 5126 0 0.00 0 1763 2 0 0
v_max_id_clo_res 932 932 0 0.00 0 4102 13 0 0
v_max_id_ord_sup 5118 5118 0 0.00 0 4693 4 0 0
v_max_non_paid_invoice 1461 1461 0 0.00 0 5713 6 0 0
v_max_non_paid_realizn 3335 3335 0 0.00 0 1920 4 0 0
v_min_id_clo_ord 5126 5126 0 0.00 0 6072 13 0 0
v_min_id_clo_res 932 932 0 0.00 0 4023 15 0 0
v_min_id_ord_sup 5118 5118 0 0.00 0 1723 2 0 0
v_min_non_paid_invoice 5433 5433 0 0.00 0 11445 27 0 0
v_min_non_paid_realizn 6580 6580 0 0.00 0 7782 22 0 0
v_our_firm 9812 9812 0 0.00 0 31 1 0 0
v_reserve_write_off 18680 18680 0 0.00 0 11073 10 0 0
x_lock_depdocs_on_canc_invoice 12073 12073 0 0.00 0 33838 139 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 16049 364 0 0
x_make_qstorno_1000_1200 5128 5128 0 0.00 1 56376 1152 0 0
x_make_qstorno_1200_2000 5124 5124 0 0.00 2 93484 3131 0 0
x_make_qstorno_2100_3300 429665 429665 0 0.00 0 69946 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2021 01:39:53 to 29-10-2021 01:39:57.

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
23:07:57 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:58 28595 8192000000 9897722720 10207956992 966982992 10.557 100 85 170 1 995597136 5993280 22620864
23:10:32 31056 8192000000 9963832720 10288373760 1041345760 11.278 100 94 188 0 1074250528 8740000 24164768
23:12:09 16618 8192000000 9988213584 10327113728 1074792672 11.598 100 79 158 0 1103677824 9582224 19302928
23:13:28 11792 8192000000 10042861360 10397032448 1134889568 12.167 100 75 150 0 1162951264 10437680 17624016
23:14:44 8630 8192000000 10087109952 10450108416 1173730224 12.532 100 81 162 0 1205235680 12899808 18605648
23:15:56 6358 8192000000 10128117648 10499817472 1214777936 12.913 100 84 168 0 1248120848 14592208 18750704
23:17:07 2731 8192000000 10168096704 10546429952 1253030304 13.266 100 88 174 0 1285344944 13076256 19238384
23:18:12 3171 8192000000 10195167328 10578677760 1281579136 13.527 100 89 177 1 1315275552 14034208 19662208
23:19:18 1702 8192000000 10215983920 10599108608 1293027264 13.632 100 89 178 0 1326364752 14159008 19178480
23:20:22 2229 8192000000 10201922320 10600366080 1303412464 13.726 100 62 126 1 1329081136 11436672 14232000
23:21:26 14399 8192000000 10199308880 10612588544 1314429312 13.826 100 36 71 4 1329523776 6499200 8595264
23:22:41 10700 8192000000 10218540896 10630463488 1317514720 13.854 100 42 85 1 1334871648 7547888 9809040
23:23:54 8447 8192000000 10220083184 10637844480 1325906288 13.930 100 25 50 0 1337975408 6324256 5744864
23:25:03 6395 8192000000 10219006976 10646822912 1334681792 14.009 100 19 38 0 1345345408 6529328 4134288
23:26:12 53849 8192000000 10238282352 10659770368 1332959888 13.994 99 23 45 1 1343413712 4707968 5745856
23:28:06 4654 8192000000 10229462304 10643533824 1329593344 13.963 100 32 65 2 1344226976 7106896 7526736
23:29:15 6849 8192000000 10165673216 10579460096 1248204448 13.222 95 23 46 0 1258910992 5184064 5522480
23:30:22 12313 8192000000 10162564416 10567880704 1250177104 13.240 98 17 34 1 1260457760 6087616 4193040
23:31:38 43539 8192000000 10146652240 10554150912 1219108368 12.953 96 29 58 1 1230719360 5851168 5759824
23:33:21 7165 8192000000 10128833408 10530541568 1232597952 13.078 98 21 42 1 1242973888 5818176 4557760
23:34:29 9622 8192000000 10167213312 10575134720 1267608016 13.400 99 23 46 0 1278692704 5514784 5569904
23:35:40 62109 8192000000 10190601264 10599137280 1294526992 13.645 100 19 38 1 1304005200 5720192 3758016
23:37:43 5776 8192000000 10218124688 10631352320 1324681088 13.919 100 31 62 0 1337947760 6872000 6394672
23:38:54 7624 8192000000 10250646832 10658111488 1340006816 14.057 100 48 96 1 1361118592 10126768 10985008
23:40:05 14597 8192000000 10266573296 10681106432 1356422400 14.205 100 51 100 0 1379412688 11595056 11395232
23:41:26 67287 8192000000 10293979568 10716794880 1377429760 14.394 100 50 98 0 1405486144 17039584 11016800
23:43:39 31271 8192000000 10280448864 10706075648 1369159104 14.320 99 28 56 1 1395392224 19057472 7175648
23:45:15 8574 8192000000 10304285856 10728251392 1389646016 14.503 100 54 108 2 1421675984 19607696 12422272
23:46:42 9261 8192000000 10332991088 10759245824 1409211520 14.677 100 45 90 0 1445893072 25280704 11400848
23:47:57 4234 8192000000 10346742608 10767441920 1414083360 14.720 100 61 122 1 1457031392 27911136 15036896
23:49:07 7813 8192000000 10365804832 10785546240 1424661824 14.814 100 61 122 1 1475256944 35214704 15380416
23:50:18 1344 8192000000 10398644096 10816651264 1440618720 14.955 100 74 148 2 1503939376 45362992 17957664
23:51:25 5094 8192000000 10410180432 10827460608 1445479040 14.998 100 78 156 0 1514282864 48847344 19956480
23:52:34 2311 8192000000 10413077344 10828824576 1439070560 14.941 99 91 179 2 1508712224 46318112 23323552
23:53:41 3936 8192000000 10419165296 10838822912 1454203344 15.075 100 78 157 2 1526046080 51282464 20560272
23:54:54 5865 8192000000 10388542960 10808057856 1423662528 14.805 100 68 136 1 1494610256 53350480 17597248
23:56:06 2009 8192000000 10376684176 10778664960 1383534688 14.448 98 92 182 0 1465336544 59595936 22205920
23:57:11 12551 8192000000 10380763472 10809712640 1411113952 14.694 100 47 94 1 1492039920 67915584 13010384
23:58:43 10499 8192000000 10365963712 10802282496 1395750352 14.557 99 37 74 0 1465613120 59240096 10622672
23:59:58 21024 8192000000 10329107776 10761904128 1365001072 14.282 98 27 51 3 1423800400 51694752 7104576
00:01:21 21316 8192000000 10318480256 10757476352 1341987056 14.075 98 25 50 3 1406585568 57261232 7337280
00:02:47 52955 8192000000 10290493024 10726985728 1302051568 13.714 96 21 42 0 1368933552 60679840 6202144
00:04:54 28965 8192000000 10256385840 10691702784 1339625120 14.054 100 27 53 2 1362576656 16774000 6177536
00:06:29 34301 8192000000 10244654832 10701656064 1331289648 13.979 99 18 36 0 1352072752 16443504 4339600
00:08:15 120740 8192000000 10253895424 10698940416 1276893936 13.485 94 19 37 1 1286987248 6304672 3788640
00:11:23 28397 8192000000 10294601920 10736742400 1371385152 14.339 100 26 52 1 1400693568 23350320 5958096
00:13:09 57730 8192000000 10283921888 10742009856 1367229216 14.302 100 16 32 1 1393067136 21783760 4054160
00:15:07 74847 8192000000 10272702832 10730786816 1361129792 14.247 99 24 47 1 1377557264 10867984 5559488
00:17:52 23228 8192000000 10281897552 10740023296 1363715088 14.271 99 11 22 0 1383288112 16867936 2705088
00:19:27 44980 8192000000 10286317216 10750410752 1381653952 14.431 100 15 30 1 1400551648 15163008 3734688
00:21:20 51748 8192000000 10302497776 10760732672 1400615680 14.600 100 23 46 1 1418601232 12810240 5175312
00:23:14 78514 8192000000 10290452928 10763710464 1391485440 14.519 100 8 16 1 1405725072 12415776 1823856
00:26:08 39564 8192000000 10287772560 10739060736 1375305696 14.375 99 41 82 1 1393090560 8704240 9080624
00:27:58 31812 8192000000 10291552480 10749513728 1389987984 14.506 100 28 56 0 1404572144 7772704 6811456
00:29:32 59569 8192000000 10292526816 10762252288 1392516384 14.528 100 5 10 0 1407076112 13556480 1003248
00:31:32 64289 8192000000 10274413280 10740555776 1356038032 14.202 98 15 30 1 1373279504 13637152 3604320
00:33:43 53705 8192000000 10289169984 10749239296 1372546016 14.350 99 12 24 0 1387449552 12212432 2691104
00:35:37 81832 8192000000 10301641808 10759659520 1371201936 14.338 98 14 28 0 1390738208 16266848 3269424
00:38:05 61805 8192000000 10303588880 10769588224 1382804128 14.442 99 15 30 1 1400831616 14990416 3037072
00:40:08 46436 8192000000 10311661184 10763988992 1399141952 14.587 100 13 26 0 1414775856 12799200 2834704
00:41:55 44490 8192000000 10304851920 10752544768 1349691888 14.145 96 14 28 0 1360971344 8341456 2938000
00:43:45 50606 8192000000 10305106576 10738335744 1340615120 14.063 96 34 66 2 1356801408 9205712 6980576
00:45:38 59828 8192000000 10298731088 10753650688 1386629888 14.476 100 26 52 0 1407669712 15256880 5782944
00:47:40 98422 8192000000 10273538784 10708168704 1281959120 13.531 92 29 57 1 1295807280 7516912 6331248
00:50:23 25313 8192000000 10285338560 10726576128 1344142992 14.095 98 41 82 0 1368374848 14966336 9265520
00:51:58 64867 8192000000 10285683936 10734718976 1356731600 14.208 98 21 42 2 1379157696 17415552 5010544
00:54:11 70409 8192000000 10288198176 10744479744 1364832320 14.281 99 20 40 1 1391021184 20663984 5524880
00:56:52 61005 8192000000 10262915552 10715410432 1303055024 13.723 95 7 14 1 1315365808 10613216 1697568
00:59:03 45341 8192000000 10246725344 10688143360 1300878016 13.703 96 13 27 0 1314564560 10479696 3206848
01:00:59 29606 8192000000 10292762256 10727325696 1353465920 14.179 99 42 84 1 1373371472 10064496 9841056
01:02:35 109664 8192000000 10277090976 10724900864 1344059568 14.094 98 9 18 0 1361214176 15134496 2020112
01:06:52 119443 8192000000 10287216928 10734866432 1348016992 14.130 98 14 28 1 1361946288 11343456 2585840
01:09:52 68115 8192000000 10278650992 10729713664 1331463040 13.980 96 29 58 2 1345844208 7671424 6709744
01:12:01 12229 8192000000 10282877472 10743828480 1380815184 14.424 100 13 22 2 1397268160 14004320 2448656
01:13:24 46333 8192000000 10290309456 10759692288 1389468512 14.501 100 6 12 0 1405866880 14834384 1563984
01:15:11 56500 8192000000 10274461568 10738372608 1332150224 13.987 96 10 20 0 1353620064 18942976 2526864
01:18:51 66319 8192000000 10294952848 10760482816 1361350880 14.249 98 11 22 0 1376201616 12247616 2603120
01:21:10 36909 8192000000 10311639600 10764795904 1393105280 14.534 99 42 83 4 1417325504 13938096 10282128
01:23:06 45507 8192000000 10312962752 10778877952 1399886336 14.594 100 8 16 1 1419515040 17581536 2047168
01:24:52 113267 8192000000 10311166128 10769948672 1349123360 14.140 96 11 22 0 1362466464 10836800 2506304
01:27:46 34103 8192000000 10304233776 10763382784 1376654080 14.387 99 29 58 0 1398258832 14982896 6621856
01:29:21 97326 8192000000 10302192336 10768019456 1378108800 14.400 99 6 12 0 1392352256 13080848 1162608
01:32:02 71561 8192000000 10303072288 10747404288 1357653040 14.216 97 39 76 0 1373050416 6627312 8770064
01:34:15 38600 8192000000 10301521664 10769543168 1395942864 14.559 100 28 56 0 1422981744 21062432 5976448
01:35:55 69361 8192000000 10297776976 10774646784 1381450736 14.430 99 7 14 1 1404453232 21550048 1452448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2021 01:39:59 to 29-10-2021 01:40:14.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2021 01:40:15 to 29-10-2021 01:40:17.

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          226438
MON$OLDEST_ACTIVE               229249
MON$OLDEST_SNAPSHOT             229249
MON$NEXT_TRANSACTION            229249
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                       2010432
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:413: 
:414: mscs-test-161.budzdorov.ru    Fri Oct 29 01:38:08 2021
:415:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:416: 
:417: 
:418: mscs-test-161.budzdorov.ru    Fri Oct 29 01:38:09 2021
:419:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:420: 
--- end of diff output ---

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

29.10.21 01:40:18. 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
2021-10-29 01:40:20 No severe PSQL-related problems occured
29.10.21 01:40:21. End of report.