Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2022 16: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 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 16599076 kB
MemAvailable 31299668 kB
Buffers 0 kB
Cached 15105648 kB
SwapCached 2324 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.9
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-01-12 16:38:03
DTS_END                         2022-01-12 16:38:03.8930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47300
TRN_ID                          237975
ATT_ID                          625
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: 8820.20 17794 1058424
customer order: creation 175.66 344 21079
customer order: refuse 15.06 348 1807
order to supplier: creation 45.98 1299 5517
order to supplier: removal 3.24 105732 389
invoice (draft): creation 46.34 3620 5561
invoice (draft): removal 47.95 879 5754
invoice accept: apply 42.00 18484 5040
invoice accept: cancel 43.48 806 5217
customer reserve: creation 3866.06 184 463927
customer reserve: removal 4239.33 63 508719
realization accept: apply 85.41 159 10249
realization accept: cancel 79.17 116 9500
payment from customer: creation 59.23 99 7107
payment from customer: removal 5.94 100 713
payment to supplier: creation 48.60 131 5832
payment to supplier: removal 3.63 80 435
service: total inventory turnovers 5.19 832 623
service: total monetary turnovers 7.17 37 860
service: aggregate perf. data 0.76 22327 91
service: refresh index statistics 0.03 200242 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2022 16:39:59 to 12-01-2022 16:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11420
TEST_TIME 2 8303
TEST_TIME 3 7364
TEST_TIME 4 6695
TEST_TIME 5 6323
TEST_TIME 6 6709
TEST_TIME 7 6266
TEST_TIME 8 6386
TEST_TIME 9 6362
TEST_TIME 10 6595
TEST_TIME 11 6615
TEST_TIME 12 6563
TEST_TIME 13 6769
TEST_TIME 14 7103
TEST_TIME 15 7142
TEST_TIME 16 7624
TEST_TIME 17 8033
TEST_TIME 18 8156
TEST_TIME 19 8409
TEST_TIME 20 8569
TEST_TIME 21 8727
TEST_TIME 22 8863
TEST_TIME 23 8952
TEST_TIME 24 9515
TEST_TIME 25 10073
TEST_TIME 26 10798
TEST_TIME 27 11151
TEST_TIME 28 10983
TEST_TIME 29 10786
TEST_TIME 30 10654
TEST_TIME 31 10385
TEST_TIME 32 10329
TEST_TIME 33 10266
TEST_TIME 34 10308
TEST_TIME 35 10209
TEST_TIME 36 9981
TEST_TIME 37 10031
TEST_TIME 38 9947
TEST_TIME 39 9846
TEST_TIME 40 9771
TEST_TIME 41 9646
TEST_TIME 42 9695
TEST_TIME 43 9599
TEST_TIME 44 9545
TEST_TIME 45 9511
TEST_TIME 46 9471
TEST_TIME 47 9330
TEST_TIME 48 9310
TEST_TIME 49 9366
TEST_TIME 50 9339
TEST_TIME 51 9371
TEST_TIME 52 9328
TEST_TIME 53 9292
TEST_TIME 54 9325
TEST_TIME 55 9383
TEST_TIME 56 9452
TEST_TIME 57 9395
TEST_TIME 58 9535
TEST_TIME 59 9584
TEST_TIME 60 9621
TEST_TIME 61 9549
TEST_TIME 62 9468
TEST_TIME 63 9413
TEST_TIME 64 9325
TEST_TIME 65 9292
TEST_TIME 66 9216
TEST_TIME 67 9199
TEST_TIME 68 9134
TEST_TIME 69 9051
TEST_TIME 70 9051
TEST_TIME 71 9020
TEST_TIME 72 8994
TEST_TIME 73 8964
TEST_TIME 74 9007
TEST_TIME 75 8959
TEST_TIME 76 9028
TEST_TIME 77 9140
TEST_TIME 78 9154
TEST_TIME 79 9180
TEST_TIME 80 9203
TEST_TIME 81 9295
TEST_TIME 82 9252
TEST_TIME 83 9309
TEST_TIME 84 9245
TEST_TIME 85 9170
TEST_TIME 86 9105
TEST_TIME 87 9150
TEST_TIME 88 9101
TEST_TIME 89 9045
TEST_TIME 90 9055
TEST_TIME 91 9066
TEST_TIME 92 9058
TEST_TIME 93 9064
TEST_TIME 94 9087
TEST_TIME 95 9132
TEST_TIME 96 9113
TEST_TIME 97 9193
TEST_TIME 98 9228
TEST_TIME 99 9248
TEST_TIME 100 9235
TEST_TIME 101 9214
TEST_TIME 102 9222
TEST_TIME 103 9264
TEST_TIME 104 9225
TEST_TIME 105 9312
TEST_TIME 106 9243
TEST_TIME 107 9170
TEST_TIME 108 9159
TEST_TIME 109 9166
TEST_TIME 110 9109
TEST_TIME 111 9087
TEST_TIME 112 9062
TEST_TIME 113 9065
TEST_TIME 114 9022
TEST_TIME 115 8994
TEST_TIME 116 9007
TEST_TIME 117 8962
TEST_TIME 118 8938
TEST_TIME 119 8898
TEST_TIME 120 8825

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2022 16:40:03 to 12-01-2022 16:40:06.

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 1487220 1487220 0 0.00 0 25558 6 0 0
doc_list_biud 1487217 1487217 0 0.00 0 69930 28 0 0
sp_add_invoice_to_stock 5140 5040 100 1.94 48 186966 18484 0 100
sp_cancel_adding_invoice 5217 5217 0 0.00 5 48686 807 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 11176 349 0 0
sp_cancel_customer_reserve 508719 508719 0 0.00 1 69931 64 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 6477 100 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 3691 81 0 0
sp_cancel_supplier_invoice 5761 5754 7 0.12 1 26487 880 0 7
sp_cancel_supplier_order 389 389 0 0.00 5 1032616 105732 0 0
sp_cancel_write_off 9500 9500 0 0.00 1 14680 116 0 0
sp_client_order 21079 21079 0 0.00 3 17624 345 0 0
sp_customer_reserve 463927 463927 0 0.00 0 42528 185 0 0
sp_fill_shopping_cart 32152 32152 0 0.00 1 19201 43 0 0
sp_fill_shopping_cart_clo_res 463919 463919 0 0.00 0 18361 15 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 27049 301 0 0
sp_kill_cost_storno 15865 15865 0 0.00 0 6472 57 0 0
sp_kill_qstorno_ret_qs2qd 1033081 1033081 0 0.00 0 69926 35 0 0
sp_kill_qty_storno 546659 546659 0 0.00 0 69930 74 0 0
sp_lock_selected_doc 21190 21190 0 0.00 0 8362 6 0 0
sp_make_cost_storno 28214 28214 0 0.00 0 21956 52 0 0
sp_multiply_rows_for_pdistr 28212 28212 0 0.00 0 23298 28 0 0
sp_multiply_rows_for_qdistr 21079 21079 0 0.00 0 16834 174 0 0
sp_pay_from_customer 7107 7107 0 0.00 4 14418 100 0 0
sp_pay_to_supplier 5832 5832 0 0.00 5 23318 132 0 0
sp_payment_common 12939 12939 0 0.00 2 14407 29 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 11155 315 0 0
sp_qd_handle_on_invoice_upd_sts 10241 10241 0 0.00 0 31197 327 0 0
sp_qd_handle_on_reserve_upd_sts 10249 10249 0 0.00 0 12333 49 0 0
sp_reserve_write_off 10249 10249 0 0.00 6 12348 160 0 0
sp_supplier_invoice 5561 5561 0 0.00 6 97358 3620 0 0
sp_supplier_order 5517 5517 0 0.00 5 33616 1299 0 0
srv_aggregate_perf_data 91 91 0 0.00 3091 445315 22327 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2243 141458 45526 0 0
srv_find_qd_qs_mism 1009900 1009900 0 0.00 0 25558 6 0 0
srv_make_invnt_saldo 623 623 0 0.00 38 23768 832 0 0
srv_make_money_saldo 860 860 0 0.00 1 13556 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 87428 374098 200242 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5040 5040 0 0.00 0 9320 166 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 2975 92 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 4545 15 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 19373 44 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1848 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 486 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1043 16 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 14380 1535 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 1807 8 0 0
t$perf-norm:sp_client_order 21079 21079 0 0.00 0 9722 16 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 2129 7 0 0
t$perf-norm:sp_pay_from_customer 7107 7107 0 0.00 0 8283 11 0 0
t$perf-norm:sp_pay_to_supplier 5832 5832 0 0.00 0 5658 9 0 0
t$perf-norm:sp_reserve_write_off 10249 10249 0 0.00 0 7897 10 0 0
t$perf-norm:sp_supplier_invoice 5561 5561 0 0.00 0 4283 18 0 0
t$perf-norm:sp_supplier_order 5517 5517 0 0.00 0 5953 16 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 499 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 53 2 0 0
t$perf-norm:srv_make_invnt_saldo 623 623 0 0.00 0 778 17 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 622 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10248 10048 200 1.95 0 4735 18 0 200
v_all_customers 37896 37896 0 0.00 0 24 1 0 0
v_all_suppliers 30348 30348 0 0.00 0 24 1 0 0
v_all_wares 42156 42156 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2925 9 0 0
v_cancel_client_order 3614 3614 0 0.00 0 3162 7 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 3016 7 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 331 3 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2084 11 0 0
v_cancel_supplier_invoice 846 832 14 1.65 0 270 13 0 14
v_cancel_supplier_order 778 778 0 0.00 0 2642 8 0 0
v_cancel_write_off 2098 2098 0 0.00 0 2542 13 0 0
v_max_id_clo_ord 5516 5516 0 0.00 0 566 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 2208 13 0 0
v_max_id_ord_sup 5558 5558 0 0.00 0 15585 7 0 0
v_max_non_paid_invoice 1732 1732 0 0.00 0 13402 16 0 0
v_max_non_paid_realizn 3937 3937 0 0.00 0 2182 5 0 0
v_min_id_clo_ord 5516 5516 0 0.00 0 5773 7 0 0
v_min_id_clo_res 998 998 0 0.00 0 2314 6 0 0
v_min_id_ord_sup 5558 5558 0 0.00 0 3949 6 0 0
v_min_non_paid_invoice 5832 5832 0 0.00 0 5725 19 0 0
v_min_non_paid_realizn 7107 7107 0 0.00 0 14401 20 0 0
v_our_firm 10600 10600 0 0.00 0 14 1 0 0
v_reserve_write_off 20498 20498 0 0.00 0 4330 7 0 0
x_lock_depdocs_on_canc_invoice 10555 10555 0 0.00 0 14913 117 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 11136 356 0 0
x_make_qstorno_1000_1200 5517 5517 0 0.00 1 33610 1211 0 0
x_make_qstorno_1200_2000 5561 5561 0 0.00 3 94928 3464 0 0
x_make_qstorno_2100_3300 463927 463927 0 0.00 0 42526 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2022 16:40:07 to 12-01-2022 16:40:11.

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
14:08:07 21 8192000000 8809576544 8824393728 13109392 0.159 5 2 4 0 13265072 70720 84960
14:09:08 23564 8192000000 9894257360 10200702976 973684144 10.623 100 91 182 0 1003501808 6163664 23654000
14:10:37 14430 8192000000 9941009744 10259603456 1024002368 11.111 100 94 188 0 1056349248 7975440 24371440
14:11:57 20155 8192000000 9977109040 10308345856 1058417408 11.441 100 81 162 0 1087504384 9113072 19973904
14:13:21 14118 8192000000 10027996224 10376847360 1114656624 11.976 100 78 157 0 1143279888 10155216 18468048
14:14:44 6009 8192000000 10096027808 10453413888 1179758928 12.588 100 85 170 1 1211089264 11919840 19410496
14:15:53 5045 8192000000 10142150576 10512494592 1224398112 13.002 100 84 168 2 1256778416 14261104 18119200
14:17:01 1991 8192000000 10169432848 10545930240 1256711760 13.300 100 90 176 3 1288252672 11905680 19635232
14:18:04 1509 8192000000 10203753168 10583650304 1286798656 13.575 100 87 175 0 1319372816 13082800 19491360
14:19:06 2346 8192000000 10205334032 10594926592 1297927232 13.676 100 81 161 0 1327533936 12074032 17532672
14:20:11 7519 8192000000 10196799824 10609876992 1307766016 13.766 100 29 57 0 1320882160 6698112 6418032
14:21:19 33298 8192000000 10201443120 10623946752 1314864480 13.830 100 20 40 0 1325852752 6066928 4921344
14:23:19 6346 8192000000 10210544128 10636169216 1323829504 13.911 100 22 44 1 1334903744 6366512 4707728
14:24:29 9874 8192000000 10225256656 10648846336 1335218976 14.014 100 26 52 0 1348259552 6472672 6567904
14:25:41 8817 8192000000 10229954576 10650689536 1333373968 13.998 100 28 53 2 1345346800 5613216 6359616
14:26:50 13527 8192000000 10259013040 10666872832 1341157792 14.068 100 72 142 1 1365561008 6401024 18002192
14:28:04 5493 8192000000 10210794128 10625933312 1313658928 13.819 100 33 66 2 1327829904 6150000 8020976
14:29:11 6124 8192000000 10175883552 10586091520 1275742448 13.474 99 20 39 1 1285560976 5303344 4515184
14:30:19 20181 8192000000 10127787936 10532794368 1202432688 12.799 95 16 32 1 1211085216 5204992 3447536
14:31:44 8650 8192000000 10138206864 10533552128 1230113376 13.055 99 32 64 0 1243341904 6046560 7181968
14:32:55 7947 8192000000 10160145232 10567884800 1268552608 13.408 100 28 54 3 1280939920 5901040 6486272
14:34:04 6048 8192000000 10177466144 10585870336 1284639248 13.555 100 21 40 0 1294595776 5255920 4700608
14:35:11 71693 8192000000 10187060720 10604236800 1298734576 13.684 100 23 46 0 1309249344 5103248 5411520
14:37:25 7348 8192000000 10200390288 10615087104 1308296288 13.771 100 23 46 2 1320563056 6908672 5358096
14:38:38 10593 8192000000 10240752640 10648797184 1337807408 14.038 100 53 105 2 1356669632 7877200 10985024
14:39:58 50484 8192000000 10262709456 10685960192 1360219360 14.239 100 40 80 0 1380121120 9236832 10664928
14:41:52 19382 8192000000 10278142480 10692898816 1353795376 14.182 99 51 101 2 1375237648 10443392 10998880
14:43:18 38335 8192000000 10286140128 10712244224 1374608592 14.368 100 38 76 0 1399713536 16177056 8927888
14:45:03 24933 8192000000 10302534160 10724909056 1388572144 14.493 100 41 82 0 1410778832 12370320 9836368
14:46:45 12419 8192000000 10316755632 10736799744 1391244736 14.517 100 64 127 1 1428480064 22477392 14757936
14:48:02 3563 8192000000 10325482272 10744750080 1386983056 14.479 98 55 110 0 1418776640 18765600 13027984
14:49:11 5365 8192000000 10342667088 10759544832 1412928912 14.710 100 69 138 1 1453621872 23623696 17069264
14:50:20 12360 8192000000 10322713648 10741690368 1381919136 14.434 99 48 95 0 1424158048 29869168 12369744
14:51:37 7214 8192000000 10316132816 10732793856 1375938992 14.380 99 50 97 2 1416602592 28213296 12450304
14:52:53 2979 8192000000 10348866864 10766520320 1403666704 14.628 100 60 119 0 1457494416 38297280 15530432
14:54:09 4884 8192000000 10333466240 10741469184 1369155792 14.319 99 75 149 2 1429763600 41051152 19556656
14:55:18 3780 8192000000 10326312176 10734530560 1371980432 14.345 100 72 143 2 1431155648 40306048 18869168
14:56:28 2243 8192000000 10348091504 10751266816 1387459920 14.483 100 91 179 3 1447541984 37294384 22787680
14:57:33 2408 8192000000 10351493760 10752802816 1384679632 14.458 100 88 176 0 1450279824 43229456 22370736
14:58:42 11073 8192000000 10317766480 10736128000 1374168080 14.364 100 43 87 0 1427706752 41917312 11621360
14:59:58 16595 8192000000 10330816112 10753880064 1384085152 14.453 100 39 78 1 1435954640 41587760 10281728
15:01:20 8995 8192000000 10331005968 10757578752 1376949104 14.389 100 32 64 2 1439028608 52718496 9361008
15:02:32 18653 8192000000 10307074672 10734764032 1350146528 14.149 100 28 56 1 1409098896 51077456 7874912
15:03:58 39906 8192000000 10274704944 10722455552 1319748096 13.874 97 18 36 3 1366404112 41823424 4832592
15:05:39 52562 8192000000 10278354976 10719412224 1358754944 14.226 100 25 51 0 1377818992 12977728 6086320
15:07:34 41059 8192000000 10272330304 10720522240 1363844384 14.272 100 17 35 0 1382271376 13957856 4469136
15:09:39 70593 8192000000 10283546048 10732101632 1350480208 14.152 98 16 32 0 1368144096 14043360 3620528
15:11:55 50857 8192000000 10277157312 10720051200 1357618832 14.216 99 14 27 0 1366484576 6403744 2462000
15:13:46 138129 8192000000 10281281408 10749136896 1377307680 14.392 99 17 34 1 1390881872 9734896 3839296
15:17:08 35827 8192000000 10276384592 10748203008 1383062672 14.444 100 6 12 0 1396940720 12611360 1266688
15:18:47 37193 8192000000 10308020112 10752045056 1395347408 14.554 100 26 51 3 1413427760 11975216 6105136
15:20:27 25920 8192000000 10288048800 10752372736 1389896992 14.505 100 12 24 1 1402495616 10035808 2562816
15:21:55 48816 8192000000 10284986784 10740793344 1362863696 14.263 98 15 30 1 1375761968 9562368 3335904
15:23:48 47550 8192000000 10284814560 10747994112 1378241152 14.401 99 12 24 0 1392319104 11059264 3018688
15:25:50 33903 8192000000 10299249056 10746306560 1386984992 14.479 100 40 82 1 1405824048 9376144 9462912
15:27:24 75194 8192000000 10301929520 10779340800 1381967328 14.434 99 12 24 0 1398135568 13440960 2727280
15:29:39 63370 8192000000 10296199936 10755825664 1386786464 14.477 99 21 42 0 1403205888 11286080 5133344
15:31:44 26412 8192000000 10300702560 10750111744 1385333200 14.464 100 36 72 0 1406285664 12531840 8420624
15:33:17 53716 8192000000 10300831328 10753691648 1375646208 14.378 99 31 62 2 1397526384 15004592 6875584
15:35:13 30543 8192000000 10296022096 10743869440 1360921312 14.246 98 26 52 1 1377902064 11071584 5909168
15:36:48 61285 8192000000 10294191984 10765246464 1356806096 14.209 98 12 24 2 1374882720 15670656 2405968
15:38:52 22835 8192000000 10317994512 10748846080 1389396480 14.500 100 47 93 0 1407103904 7209696 10497728
15:40:17 124012 8192000000 10271525104 10724712448 1327308672 13.943 95 31 60 0 1343284848 9358112 6618064
15:43:21 43108 8192000000 10265493728 10727321600 1371433952 14.340 100 13 27 0 1382462768 8253040 2775776
15:45:04 37567 8192000000 10264800944 10737184768 1368901568 14.317 100 6 12 0 1382578816 12719840 957408
15:46:47 84759 8192000000 10254372656 10712715264 1331438128 13.980 97 14 28 3 1340414768 6348576 2628064
15:49:21 67728 8192000000 10262219504 10714836992 1317376240 13.853 96 10 20 0 1328010400 8720272 1913888
15:51:31 64515 8192000000 10275472352 10719113216 1326157216 13.932 97 21 42 0 1351637616 20099936 5380464
15:53:42 59823 8192000000 10270422624 10726645760 1337882096 14.038 98 16 33 0 1365023472 23250656 3890720
15:56:37 18651 8192000000 10284205168 10709946368 1337490848 14.035 98 49 98 1 1358626128 10922288 10212992
15:57:56 14300 8192000000 10265124128 10697641984 1332275312 13.988 98 40 80 2 1351603232 10610304 8717616
15:59:12 85589 8192000000 10279937088 10734342144 1342751520 14.082 98 15 30 2 1356893520 11132288 3009712
16:01:45 78431 8192000000 10277141520 10723536896 1341691200 14.073 97 19 38 0 1356087568 9331264 5065104
16:04:33 35754 8192000000 10297300912 10750320640 1383353712 14.447 100 26 52 0 1407300592 18452416 5494464
16:06:11 60375 8192000000 10274530944 10738372608 1365254240 14.285 99 6 12 1 1382008656 15312256 1442160
16:08:12 89541 8192000000 10286746992 10739671040 1362396064 14.259 99 8 16 1 1374065824 10006480 1663280
16:10:43 40081 8192000000 10278554016 10737053696 1370821728 14.334 99 32 64 1 1387819328 9293072 7704528
16:12:27 86131 8192000000 10274483568 10739965952 1354128624 14.185 98 22 43 0 1374742400 14716560 5897216
16:14:57 36738 8192000000 10286712624 10734219264 1361416448 14.250 99 41 82 2 1385250016 14005280 9828288
16:16:40 141457 8192000000 10284082496 10733223936 1333868928 14.002 97 8 16 0 1352591200 17137472 1584800
16:20:02 63423 8192000000 10299913584 10742013952 1358384528 14.223 98 28 58 0 1374779872 10161280 6234064
16:22:06 79517 8192000000 10293365760 10756087808 1347092720 14.121 97 19 38 1 1364261952 13224096 3945136
16:24:33 97005 8192000000 10265075408 10709737472 1352500480 14.170 99 21 42 0 1371661856 13814480 5346896
16:27:11 69448 8192000000 10237677504 10697678848 1304772784 13.739 96 5 10 0 1317372624 11570016 1029824
16:29:21 47800 8192000000 10251151296 10703466496 1349598144 14.144 100 22 46 1 1365048128 9545104 5904880
16:31:19 67865 8192000000 10246047936 10685616128 1313232304 13.815 97 15 30 1 1325928080 9342544 3353232
16:33:27 81275 8192000000 10248154688 10712059904 1331765936 13.983 98 13 26 0 1344480320 9786800 2927584
16:35:58 42162 8192000000 10263417792 10709012480 1329874496 13.966 97 20 40 0 1348204976 13725104 4605376
16:37:48 30133 8192000000 9410827584 9719779328 552692416 6.320 41 15 28 1 575100800 19028384 3380000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2022 16:40:13 to 12-01-2022 16:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2022 16:40:29 to 12-01-2022 16:40:31.

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          237974
MON$OLDEST_ACTIVE               243070
MON$OLDEST_SNAPSHOT             243070
MON$NEXT_TRANSACTION            243070
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                       2018816
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:846: 
:847: mscs-test-161.budzdorov.ru    Wed Jan 12 16:38:23 2022
:848:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:849: 
:850: 
:851: mscs-test-161.budzdorov.ru    Wed Jan 12 16:38:30 2022
:852:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:853: 
--- end of diff output ---

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

12.01.22 16:40:32. 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-01-12 16:40:34 No severe PSQL-related problems occured
12.01.22 16:40:35. End of report.