Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.06.2022 01: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 16246128 kB
MemAvailable 31313200 kB
Buffers 0 kB
Cached 15433240 kB
SwapCached 2444 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-06-04 01:38:31
DTS_END                         2022-06-04 01:38:16.4280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56696
TRN_ID                          239618
ATT_ID                          274
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: 9136.83 26990 1096420
customer order: creation 164.83 359 19779
customer order: refuse 14.52 359 1742
order to supplier: creation 44.42 1396 5330
order to supplier: removal 3.40 102835 408
invoice (draft): creation 44.96 4141 5395
invoice (draft): removal 55.22 807 6626
invoice accept: apply 40.15 18352 4818
invoice accept: cancel 49.26 797 5911
customer reserve: creation 3594.18 186 431302
customer reserve: removal 4838.32 54 580598
realization accept: apply 81.09 196 9731
realization accept: cancel 82.23 104 9868
payment from customer: creation 56.78 115 6814
payment from customer: removal 5.42 74 650
payment to supplier: creation 47.03 133 5644
payment to supplier: removal 3.30 100 396
service: total inventory turnovers 4.69 1132 563
service: total monetary turnovers 6.28 31 753
service: aggregate perf. data 0.73 21858 88
service: refresh index statistics 0.03 386766 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-06-2022 01:39:03 to 04-06-2022 01:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3020
TEST_TIME 2 3385
TEST_TIME 3 3811
TEST_TIME 4 4584
TEST_TIME 5 6348
TEST_TIME 6 6055
TEST_TIME 7 6294
TEST_TIME 8 6294
TEST_TIME 9 6301
TEST_TIME 10 6376
TEST_TIME 11 6546
TEST_TIME 12 6881
TEST_TIME 13 6722
TEST_TIME 14 6788
TEST_TIME 15 6599
TEST_TIME 16 7208
TEST_TIME 17 7711
TEST_TIME 18 7669
TEST_TIME 19 7777
TEST_TIME 20 7998
TEST_TIME 21 7907
TEST_TIME 22 8051
TEST_TIME 23 8065
TEST_TIME 24 8234
TEST_TIME 25 8787
TEST_TIME 26 8659
TEST_TIME 27 8564
TEST_TIME 28 9213
TEST_TIME 29 9592
TEST_TIME 30 9516
TEST_TIME 31 9889
TEST_TIME 32 10163
TEST_TIME 33 9939
TEST_TIME 34 9884
TEST_TIME 35 9987
TEST_TIME 36 9845
TEST_TIME 37 10015
TEST_TIME 38 9939
TEST_TIME 39 9801
TEST_TIME 40 9681
TEST_TIME 41 9677
TEST_TIME 42 9572
TEST_TIME 43 9505
TEST_TIME 44 9440
TEST_TIME 45 9369
TEST_TIME 46 9232
TEST_TIME 47 9086
TEST_TIME 48 9064
TEST_TIME 49 9016
TEST_TIME 50 9257
TEST_TIME 51 9152
TEST_TIME 52 9035
TEST_TIME 53 9028
TEST_TIME 54 9109
TEST_TIME 55 9041
TEST_TIME 56 8939
TEST_TIME 57 8836
TEST_TIME 58 8845
TEST_TIME 59 8822
TEST_TIME 60 8848
TEST_TIME 61 8791
TEST_TIME 62 8890
TEST_TIME 63 8941
TEST_TIME 64 8907
TEST_TIME 65 8982
TEST_TIME 66 9039
TEST_TIME 67 8980
TEST_TIME 68 9020
TEST_TIME 69 9242
TEST_TIME 70 9203
TEST_TIME 71 9241
TEST_TIME 72 9316
TEST_TIME 73 9243
TEST_TIME 74 9124
TEST_TIME 75 9139
TEST_TIME 76 9081
TEST_TIME 77 9095
TEST_TIME 78 9048
TEST_TIME 79 8991
TEST_TIME 80 9032
TEST_TIME 81 9039
TEST_TIME 82 8956
TEST_TIME 83 8940
TEST_TIME 84 8972
TEST_TIME 85 8973
TEST_TIME 86 8984
TEST_TIME 87 8994
TEST_TIME 88 9023
TEST_TIME 89 9066
TEST_TIME 90 9044
TEST_TIME 91 9047
TEST_TIME 92 9028
TEST_TIME 93 9139
TEST_TIME 94 9199
TEST_TIME 95 9182
TEST_TIME 96 9252
TEST_TIME 97 9279
TEST_TIME 98 9225
TEST_TIME 99 9172
TEST_TIME 100 9146
TEST_TIME 101 9154
TEST_TIME 102 9116
TEST_TIME 103 9108
TEST_TIME 104 9093
TEST_TIME 105 9068
TEST_TIME 106 9070
TEST_TIME 107 9016
TEST_TIME 108 9024
TEST_TIME 109 8980
TEST_TIME 110 9057
TEST_TIME 111 9048
TEST_TIME 112 9055
TEST_TIME 113 9030
TEST_TIME 114 9077
TEST_TIME 115 9088
TEST_TIME 116 9071
TEST_TIME 117 9098
TEST_TIME 118 9143
TEST_TIME 119 9185
TEST_TIME 120 9139

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-06-2022 01:39:06 to 04-06-2022 01:39:10.

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 1498271 1498271 0 0.00 0 23809 6 0 0
doc_list_biud 1498271 1498271 0 0.00 0 48061 28 0 0
sp_add_invoice_to_stock 4930 4818 112 2.27 35 169644 18352 0 112
sp_cancel_adding_invoice 5911 5911 0 0.00 4 42880 798 0 0
sp_cancel_client_order 1742 1742 0 0.00 4 12226 359 0 0
sp_cancel_customer_reserve 580598 580598 0 0.00 1 48064 55 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 2583 74 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 5900 101 0 0
sp_cancel_supplier_invoice 6632 6626 6 0.09 1 29628 808 0 6
sp_cancel_supplier_order 408 408 0 0.00 5 1008049 102835 0 0
sp_cancel_write_off 9868 9868 0 0.00 1 11810 104 0 0
sp_client_order 19779 19779 0 0.00 3 33540 360 0 0
sp_customer_reserve 431302 431302 0 0.00 0 53713 186 0 0
sp_fill_shopping_cart 30490 30490 0 0.00 1 18004 42 0 0
sp_fill_shopping_cart_clo_res 431301 431301 0 0.00 0 21255 13 0 0
sp_get_clo_for_invoice 4815 4815 0 0.00 0 21667 286 0 0
sp_kill_cost_storno 16825 16825 0 0.00 0 21881 60 0 0
sp_kill_qstorno_ret_qs2qd 1178098 1178098 0 0.00 0 48039 30 0 0
sp_kill_qty_storno 619697 619697 0 0.00 0 48061 66 0 0
sp_lock_selected_doc 20192 20192 0 0.00 0 5884 4 0 0
sp_make_cost_storno 27002 27002 0 0.00 0 23807 56 0 0
sp_multiply_rows_for_pdistr 27002 27002 0 0.00 0 18820 32 0 0
sp_multiply_rows_for_qdistr 19779 19779 0 0.00 0 33533 145 0 0
sp_pay_from_customer 6814 6814 0 0.00 4 18848 116 0 0
sp_pay_to_supplier 5644 5644 0 0.00 4 13582 134 0 0
sp_payment_common 12458 12458 0 0.00 1 15034 28 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 12214 328 0 0
sp_qd_handle_on_invoice_upd_sts 10724 10724 0 0.00 0 35728 366 0 0
sp_qd_handle_on_reserve_upd_sts 9731 9731 0 0.00 0 25249 69 0 0
sp_reserve_write_off 9731 9731 0 0.00 6 25279 196 0 0
sp_supplier_invoice 5395 5395 0 0.00 6 90150 4142 0 0
sp_supplier_order 5330 5330 0 0.00 5 51104 1397 0 0
srv_aggregate_perf_data 88 88 0 0.00 2598 414358 21858 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2101 155417 45697 0 0
srv_find_qd_qs_mism 1052238 1052238 0 0.00 0 10146 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 31 30970 1132 0 0
srv_make_money_saldo 753 753 0 0.00 1 1987 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 214269 584068 386767 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 24616 208 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 4723 100 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 2021 13 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1343 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 11577 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 306 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 883 9 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 39334 1704 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 6986 11 0 0
t$perf-norm:sp_client_order 19779 19779 0 0.00 0 21523 22 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 2819 13 0 0
t$perf-norm:sp_pay_from_customer 6814 6814 0 0.00 0 10961 11 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 6557 14 0 0
t$perf-norm:sp_reserve_write_off 9731 9731 0 0.00 0 8361 14 0 0
t$perf-norm:sp_supplier_invoice 5395 5395 0 0.00 0 23432 36 0 0
t$perf-norm:sp_supplier_order 5330 5330 0 0.00 0 10353 28 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 415 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 263 4 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 22892 75 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1678 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9850 9626 224 2.27 0 6795 19 0 224
v_all_customers 35488 35488 0 0.00 0 16 1 0 0
v_all_suppliers 29224 29224 0 0.00 0 122 1 0 0
v_all_wares 39554 39554 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 4793 12 0 0
v_cancel_client_order 3484 3484 0 0.00 0 2576 6 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 2275 7 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 1099 5 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 1765 6 0 0
v_cancel_supplier_invoice 836 824 12 1.43 0 702 15 0 12
v_cancel_supplier_order 816 816 0 0.00 0 1330 5 0 0
v_cancel_write_off 1968 1968 0 0.00 0 7721 19 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 6173 3 0 0
v_max_id_clo_res 997 997 0 0.00 0 1539 16 0 0
v_max_id_ord_sup 5384 5384 0 0.00 0 6422 7 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 547 2 0 0
v_max_non_paid_realizn 3825 3825 0 0.00 0 1269 4 0 0
v_min_id_clo_ord 5328 5328 0 0.00 0 2426 5 0 0
v_min_id_clo_res 997 997 0 0.00 0 2577 8 0 0
v_min_id_ord_sup 5384 5384 0 0.00 0 5931 7 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 7435 20 0 0
v_min_non_paid_realizn 6814 6814 0 0.00 0 15029 19 0 0
v_our_firm 10044 10044 0 0.00 0 14 1 0 0
v_reserve_write_off 19462 19462 0 0.00 0 3523 6 0 0
x_lock_depdocs_on_canc_invoice 12125 12125 0 0.00 0 40030 116 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 36820 453 0 0
x_make_qstorno_1000_1200 5330 5330 0 0.00 2 51096 1303 0 0
x_make_qstorno_1200_2000 5395 5395 0 0.00 3 90139 3990 0 0
x_make_qstorno_2100_3300 431302 431302 0 0.00 0 50615 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-06-2022 01:39:11 to 04-06-2022 01:39:15.

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:08:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:21 20063 8192000000 9896953424 10202583040 974199136 10.628 100 90 180 0 1003700112 5847552 23653424
23:10:54 26238 8192000000 9943845200 10261917696 1025680704 11.127 100 95 190 0 1058869392 8610560 24578128
23:12:30 15813 8192000000 9978386688 10308067328 1055271984 11.411 100 78 157 0 1084245168 9352688 19620496
23:13:50 16420 8192000000 10036931392 10387066880 1119019744 12.018 100 72 145 0 1146716592 10777344 16919504
23:15:11 9797 8192000000 10088844096 10453000192 1180808352 12.598 100 81 162 0 1211158048 11954144 18395552
23:16:23 4046 8192000000 10126332624 10496696320 1213734608 12.904 100 85 170 1 1246838048 14636400 18467040
23:17:29 2544 8192000000 10169444112 10545233920 1253500368 13.270 100 92 183 0 1287482992 14275616 19707008
23:18:33 1838 8192000000 10197687552 10581266432 1285485776 13.563 100 89 178 2 1317900656 12223632 20191248
23:19:35 40716 8192000000 10180091616 10592808960 1299629104 13.692 100 34 68 2 1314032240 7040320 7362816
23:21:19 5208 8192000000 10200389232 10610757632 1306473616 13.754 100 29 58 0 1320386352 7448288 6464448
23:22:28 10171 8192000000 10206816960 10618163200 1313764736 13.820 100 20 38 1 1324055520 6135872 4154912
23:23:39 13168 8192000000 10206733216 10622394368 1318888512 13.867 100 26 52 1 1331240832 6687952 5664368
23:24:54 32382 8192000000 10245138176 10646806528 1323354112 13.907 100 62 126 1 1348306944 10574880 14377952
23:26:26 5000 8192000000 10229223760 10642972672 1323785456 13.911 100 27 55 0 1336228160 6230336 6212368
23:27:35 10668 8192000000 10201580288 10618015744 1290014976 13.604 98 18 36 1 1299991472 5355728 4620768
23:28:47 66458 8192000000 10152280624 10563080192 1220557632 12.967 95 21 42 1 1230746624 5273840 4915152
23:30:54 4627 8192000000 10135752832 10532360192 1234305728 13.094 99 34 68 2 1249058944 7713520 7039696
23:31:59 7670 8192000000 10132244144 10529316864 1214420592 12.910 97 17 34 0 1228612368 10229552 3962224
23:33:08 60928 8192000000 10166728464 10565758976 1260899904 13.338 99 29 59 2 1273152352 5320720 6931728
23:35:10 6769 8192000000 10186709088 10594123776 1291474848 13.618 100 27 54 0 1303769552 5959184 6335520
23:36:22 9972 8192000000 10189337136 10606166016 1304355328 13.735 100 29 58 1 1317550192 6425392 6769472
23:37:33 5716 8192000000 10206149968 10624847872 1318094400 13.859 100 19 38 1 1328925792 5945552 4885840
23:38:40 62487 8192000000 10246701408 10654867456 1337796128 14.038 100 36 72 1 1351844656 5169840 8878688
23:40:47 8857 8192000000 10259731872 10674110464 1353230352 14.177 100 41 81 0 1371080176 9051952 8797872
23:41:59 9380 8192000000 10265446752 10687397888 1365332256 14.285 100 43 86 0 1385196640 10276112 9588272
23:43:20 15296 8192000000 10270190464 10694234112 1365611936 14.288 100 35 70 0 1387117792 12494400 9011456
23:44:39 7587 8192000000 10288351872 10705567744 1362415072 14.259 99 54 107 0 1387856880 13353472 12088336
23:45:52 21810 8192000000 10283550560 10706137088 1371139952 14.337 100 46 91 2 1395255232 13770368 10344912
23:47:22 14039 8192000000 10296442288 10719416320 1382410176 14.438 100 59 118 3 1412803104 15707232 14685696
23:48:43 9512 8192000000 10277462576 10696069120 1326989344 13.940 96 44 86 1 1353388496 15746112 10653040
23:50:03 3372 8192000000 10312855776 10718285824 1377208720 14.392 100 77 152 2 1413906048 19618016 17079312
23:51:10 2269 8192000000 10311357312 10734395392 1394829648 14.549 100 52 104 1 1423460096 16381280 12249168
23:52:18 12811 8192000000 10296085584 10709295104 1357055136 14.211 99 56 111 2 1393063232 22896576 13111520
23:53:36 3654 8192000000 10287293056 10687827968 1333005840 13.994 98 78 157 3 1381124320 28372848 19745632
23:54:45 3423 8192000000 10309780000 10718068736 1365167120 14.284 100 75 150 2 1414253536 30303072 18783344
23:55:52 6569 8192000000 10280284480 10695426048 1340514080 14.062 99 45 90 0 1381195872 29215456 11466336
23:57:03 5370 8192000000 10271358752 10676195328 1310713120 13.793 97 60 116 1 1355939648 30508128 14718400
23:58:12 2100 8192000000 10299797952 10703110144 1355079888 14.193 100 61 122 0 1403193680 32395888 15717904
23:59:28 3581 8192000000 10314092864 10721255424 1363121104 14.265 100 63 126 1 1414421296 35906672 15393520
00:00:37 6872 8192000000 10329002272 10736685056 1370770720 14.334 100 68 133 2 1430623856 43380688 16472448
00:01:46 7611 8192000000 10331309744 10743988224 1368943008 14.318 100 65 131 1 1430512208 44387184 17182016
00:02:58 5278 8192000000 10326250816 10743369728 1371417568 14.340 100 57 114 2 1431079840 44975680 14686592
00:04:06 14266 8192000000 10307820416 10733658112 1369885664 14.326 100 22 44 0 1415371136 39496848 5988624
00:05:26 6208 8192000000 10333676736 10752745472 1364217440 14.275 99 60 120 1 1427150848 47660928 15272480
00:06:39 30219 8192000000 10301318944 10746695680 1368760496 14.316 100 29 58 0 1419107552 42391888 7955168
00:08:31 30192 8192000000 10326747648 10754084864 1387120928 14.480 100 40 80 2 1427641088 30790272 9729888
00:10:03 44028 8192000000 10307196000 10754347008 1391100240 14.516 100 16 32 0 1417842240 22707616 4034384
00:11:48 59905 8192000000 10328524864 10763440128 1397021920 14.568 100 41 82 3 1425713776 17435520 11256336
00:14:31 70073 8192000000 10312382960 10770718720 1399681600 14.592 100 15 30 0 1419397200 16176160 3539440
00:16:46 73420 8192000000 10311491840 10764861440 1394557792 14.547 100 14 28 1 1414181216 16243280 3380144
00:19:17 68897 8192000000 10296698464 10747105280 1387053584 14.480 99 25 50 0 1406893168 14809504 5030080
00:21:28 50216 8192000000 10288003344 10749726720 1390003264 14.506 100 8 16 1 1405460208 13558304 1898640
00:23:19 75135 8192000000 10297551104 10756673536 1369366560 14.321 98 16 32 0 1385521888 12543792 3611536
00:25:48 155410 8192000000 10298904416 10760552448 1378348560 14.402 98 32 64 2 1402551040 16015712 8186768
00:29:36 48773 8192000000 10297892704 10756595712 1400910960 14.603 100 19 38 0 1414038512 9550784 3576768
00:31:30 54547 8192000000 10302989472 10765656064 1398730864 14.584 100 35 70 0 1420613008 13329520 8552624
00:34:14 53566 8192000000 10280107664 10729553920 1345639856 14.108 97 17 34 1 1359929264 9844656 4444752
00:36:50 81237 8192000000 10265130672 10728034304 1318639552 13.864 95 17 34 0 1332928112 10497680 3790880
00:39:21 44665 8192000000 10267455696 10717192192 1330565376 13.972 96 24 48 2 1350068720 13779456 5723888
00:41:09 65934 8192000000 10265440320 10718638080 1319638640 13.873 96 14 28 0 1337063408 14632656 2792112
00:43:15 67717 8192000000 10283942512 10755227648 1378141088 14.400 100 13 26 0 1396950448 15744880 3064480
00:45:24 115320 8192000000 10300402768 10758373376 1321164816 13.887 95 10 20 0 1350493856 27073600 2255440
00:48:25 74973 8192000000 10275069856 10716536832 1290560384 13.609 94 36 71 2 1314440704 15426816 8453504
00:50:41 141488 8192000000 10263564256 10702782464 1323907472 13.912 97 14 28 0 1346558624 19483680 3167472
00:54:03 81387 8192000000 10262915520 10698797056 1284857968 13.557 94 31 62 1 1308987600 17180640 6948992
00:56:28 88095 8192000000 10274113440 10722791424 1356078640 14.202 99 16 32 1 1372586416 12752688 3755088
00:59:01 67405 8192000000 10265311920 10724999168 1340963072 14.066 98 16 32 0 1361641392 16715520 3962800
01:01:10 70610 8192000000 10262715536 10719924224 1319798880 13.875 97 15 30 0 1334550528 11125936 3625712
01:03:22 60989 8192000000 10279968880 10724798464 1330179232 13.969 97 19 38 2 1345037456 9856512 5001712
01:05:25 31642 8192000000 10276890400 10726387712 1367601904 14.306 100 23 46 2 1386373568 13080048 5691616
01:07:05 36976 8192000000 10277734400 10723180544 1370422208 14.331 100 18 36 0 1385090304 10762640 3905456
01:08:46 44855 8192000000 10296612752 10746929152 1386668304 14.476 100 29 58 0 1405865488 13048368 6148816
01:10:39 40730 8192000000 10315373696 10776084480 1397578944 14.573 100 32 63 1 1426773408 22140480 7053984
01:12:20 68381 8192000000 10316745168 10763763712 1389983888 14.506 99 25 50 0 1408072880 12111024 5977968
01:14:33 29231 8192000000 10320396192 10778558464 1398882336 14.585 100 38 76 2 1428328528 20866160 8580032
01:16:10 31974 8192000000 10319708768 10755731456 1379500608 14.412 98 32 62 2 1394244464 8491440 6252416
01:17:45 105522 8192000000 10306755104 10753900544 1386276272 14.473 99 39 78 0 1404585776 9055520 9253984
01:20:35 30999 8192000000 10305773488 10745159680 1342587408 14.081 96 24 48 0 1356309728 8360576 5361744
01:22:12 40927 8192000000 10274980320 10738765824 1375322128 14.375 100 4 8 0 1387785504 11891632 571744
01:23:53 52106 8192000000 10271422176 10726150144 1360261344 14.240 98 25 50 0 1374136816 8213680 5661792
01:25:45 38996 8192000000 10291808944 10743463936 1369557056 14.323 99 14 28 1 1390406768 17445088 3404624
01:27:25 139166 8192000000 10290598784 10769543168 1358211536 14.221 98 10 18 2 1377776400 17632256 1932608
01:30:44 44405 8192000000 10289940464 10740211712 1364119568 14.274 98 25 50 0 1377763888 8164432 5479888
01:32:30 52903 8192000000 10302661584 10748940288 1374583632 14.368 99 30 60 0 1393672976 12683344 6406000
01:34:33 43545 8192000000 10298541216 10741645312 1370223888 14.329 99 31 62 1 1390833600 12382912 8226800
01:36:26 85581 8192000000 10262034864 10718912512 1299857120 13.694 94 11 22 0 1316500256 14333648 2309488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-06-2022 01:39:17 to 04-06-2022 01:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-06-2022 01:39:32 to 04-06-2022 01:39:34.

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          248099
MON$OLDEST_ACTIVE               248100
MON$OLDEST_SNAPSHOT             248100
MON$NEXT_TRANSACTION            248100
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                       2043360
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.33601 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33601 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33601 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33601 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'

04.06.22 01:39:35. 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-06-04 01:39:38 No severe PSQL-related problems occured
04.06.22 01:39:39. End of report.