Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.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 16623584 kB
MemAvailable 31239636 kB
Buffers 0 kB
Cached 15025240 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-13 01:37:57
DTS_END                         2022-01-13 01:37:57.8210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53420
TRN_ID                          227559
ATT_ID                          622
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: 8959.90 25909 1075188
customer order: creation 158.30 406 18996
customer order: refuse 14.26 363 1711
order to supplier: creation 42.11 1254 5053
order to supplier: removal 3.43 111535 411
invoice (draft): creation 41.71 3469 5005
invoice (draft): removal 51.97 819 6236
invoice accept: apply 38.58 19748 4629
invoice accept: cancel 47.51 796 5701
customer reserve: creation 3592.03 195 431044
customer reserve: removal 4680.18 65 561621
realization accept: apply 76.88 190 9226
realization accept: cancel 92.42 117 11090
payment from customer: creation 54.35 124 6522
payment from customer: removal 5.16 80 619
payment to supplier: creation 45.98 137 5517
payment to supplier: removal 3.03 68 363
service: total inventory turnovers 4.89 1018 587
service: total monetary turnovers 6.19 24 743
service: aggregate perf. data 0.92 13572 110
service: refresh index statistics 0.03 364201 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-01-2022 01:39:08 to 13-01-2022 01:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1674
TEST_TIME 2 2216
TEST_TIME 3 2798
TEST_TIME 4 2706
TEST_TIME 5 3028
TEST_TIME 6 3610
TEST_TIME 7 3757
TEST_TIME 8 4418
TEST_TIME 9 4935
TEST_TIME 10 4918
TEST_TIME 11 4801
TEST_TIME 12 4919
TEST_TIME 13 5427
TEST_TIME 14 5490
TEST_TIME 15 5883
TEST_TIME 16 6065
TEST_TIME 17 6348
TEST_TIME 18 6855
TEST_TIME 19 7212
TEST_TIME 20 7776
TEST_TIME 21 7994
TEST_TIME 22 8154
TEST_TIME 23 8730
TEST_TIME 24 9057
TEST_TIME 25 9590
TEST_TIME 26 10142
TEST_TIME 27 10172
TEST_TIME 28 10096
TEST_TIME 29 10065
TEST_TIME 30 10031
TEST_TIME 31 9838
TEST_TIME 32 9803
TEST_TIME 33 9681
TEST_TIME 34 9755
TEST_TIME 35 9683
TEST_TIME 36 9506
TEST_TIME 37 9373
TEST_TIME 38 9329
TEST_TIME 39 9282
TEST_TIME 40 9287
TEST_TIME 41 9304
TEST_TIME 42 9227
TEST_TIME 43 9256
TEST_TIME 44 9180
TEST_TIME 45 9142
TEST_TIME 46 9131
TEST_TIME 47 9057
TEST_TIME 48 9011
TEST_TIME 49 8997
TEST_TIME 50 8974
TEST_TIME 51 8962
TEST_TIME 52 8923
TEST_TIME 53 8978
TEST_TIME 54 9024
TEST_TIME 55 9033
TEST_TIME 56 8960
TEST_TIME 57 9024
TEST_TIME 58 9077
TEST_TIME 59 9169
TEST_TIME 60 9160
TEST_TIME 61 9260
TEST_TIME 62 9185
TEST_TIME 63 9131
TEST_TIME 64 9067
TEST_TIME 65 8989
TEST_TIME 66 8970
TEST_TIME 67 8931
TEST_TIME 68 8975
TEST_TIME 69 8931
TEST_TIME 70 9005
TEST_TIME 71 9032
TEST_TIME 72 8967
TEST_TIME 73 8931
TEST_TIME 74 8954
TEST_TIME 75 9116
TEST_TIME 76 9106
TEST_TIME 77 9161
TEST_TIME 78 9162
TEST_TIME 79 9103
TEST_TIME 80 9184
TEST_TIME 81 9249
TEST_TIME 82 9205
TEST_TIME 83 9102
TEST_TIME 84 9077
TEST_TIME 85 9043
TEST_TIME 86 9032
TEST_TIME 87 8975
TEST_TIME 88 8965
TEST_TIME 89 8913
TEST_TIME 90 8902
TEST_TIME 91 8887
TEST_TIME 92 8871
TEST_TIME 93 8880
TEST_TIME 94 8898
TEST_TIME 95 8873
TEST_TIME 96 8968
TEST_TIME 97 9009
TEST_TIME 98 8978
TEST_TIME 99 8936
TEST_TIME 100 8908
TEST_TIME 101 9017
TEST_TIME 102 9119
TEST_TIME 103 9167
TEST_TIME 104 9139
TEST_TIME 105 9160
TEST_TIME 106 9192
TEST_TIME 107 9215
TEST_TIME 108 9144
TEST_TIME 109 9087
TEST_TIME 110 9046
TEST_TIME 111 9066
TEST_TIME 112 9036
TEST_TIME 113 8995
TEST_TIME 114 8969
TEST_TIME 115 8930
TEST_TIME 116 8905
TEST_TIME 117 8935
TEST_TIME 118 8927
TEST_TIME 119 8920
TEST_TIME 120 8960

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2022 01:39:12 to 13-01-2022 01:39:15.

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 1475952 1475952 0 0.00 0 29464 6 0 0
doc_list_biud 1475952 1475952 0 0.00 0 67657 31 0 0
sp_add_invoice_to_stock 4718 4629 89 1.88 47 200655 19749 0 89
sp_cancel_adding_invoice 5701 5701 0 0.00 6 75103 796 0 0
sp_cancel_client_order 1711 1711 0 0.00 4 18838 363 0 0
sp_cancel_customer_reserve 561621 561621 0 0.00 1 33133 66 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 2 5191 80 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 2 2687 68 0 0
sp_cancel_supplier_invoice 6245 6236 9 0.14 2 67663 820 0 9
sp_cancel_supplier_order 411 411 0 0.00 6 1056817 111535 0 0
sp_cancel_write_off 11090 11090 0 0.00 1 16833 118 0 0
sp_client_order 18996 18996 0 0.00 3 31307 407 0 0
sp_customer_reserve 431044 431044 0 0.00 0 169213 195 0 0
sp_fill_shopping_cart 29039 29039 0 0.00 1 25262 37 0 0
sp_fill_shopping_cart_clo_res 431042 431042 0 0.00 0 12223 15 0 0
sp_get_clo_for_invoice 4627 4627 0 0.00 0 28653 389 0 0
sp_kill_cost_storno 17773 17773 0 0.00 0 8501 62 0 0
sp_kill_qstorno_ret_qs2qd 1140979 1140979 0 0.00 0 67655 35 0 0
sp_kill_qty_storno 600620 600620 0 0.00 0 67657 75 0 0
sp_lock_selected_doc 19327 19327 0 0.00 0 8594 5 0 0
sp_make_cost_storno 25889 25889 0 0.00 0 29456 57 0 0
sp_multiply_rows_for_pdistr 25889 25889 0 0.00 0 7245 28 0 0
sp_multiply_rows_for_qdistr 18996 18996 0 0.00 0 30419 192 0 0
sp_pay_from_customer 6522 6522 0 0.00 4 12635 125 0 0
sp_pay_to_supplier 5517 5517 0 0.00 5 25243 137 0 0
sp_payment_common 12039 12039 0 0.00 1 15565 27 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 18832 328 0 0
sp_qd_handle_on_invoice_upd_sts 10325 10325 0 0.00 0 15103 332 0 0
sp_qd_handle_on_reserve_upd_sts 9226 9226 0 0.00 0 12745 69 0 0
sp_reserve_write_off 9226 9226 0 0.00 6 14261 191 0 0
sp_supplier_invoice 5005 5005 0 0.00 7 76805 3469 0 0
sp_supplier_order 5053 5053 0 0.00 5 48295 1254 0 0
srv_aggregate_perf_data 110 110 0 0.00 2596 130586 13573 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2055 218219 53834 0 0
srv_find_qd_qs_mism 1031782 1031782 0 0.00 0 16340 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 31 52706 1019 0 0
srv_make_money_saldo 743 743 0 0.00 1 2583 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 238067 460762 364202 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4629 4629 0 0.00 0 13837 185 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 4872 124 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 4338 11 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 3757 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 2084 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 2738 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1098 11 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 30825 1729 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 1462 6 0 0
t$perf-norm:sp_client_order 18996 18996 0 0.00 0 15084 20 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 1100 6 0 0
t$perf-norm:sp_pay_from_customer 6522 6522 0 0.00 0 9160 14 0 0
t$perf-norm:sp_pay_to_supplier 5517 5517 0 0.00 0 11784 14 0 0
t$perf-norm:sp_reserve_write_off 9226 9226 0 0.00 0 16084 19 0 0
t$perf-norm:sp_supplier_invoice 5005 5005 0 0.00 0 7499 18 0 0
t$perf-norm:sp_supplier_order 5053 5053 0 0.00 0 13416 18 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 162 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 134 5 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 735 15 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 9080 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9422 9244 178 1.88 0 4028 19 0 178
v_all_customers 34924 34924 0 0.00 0 79 0 0 0
v_all_suppliers 28052 28052 0 0.00 0 13 1 0 0
v_all_wares 37992 37992 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 2232 16 0 0
v_cancel_client_order 3422 3422 0 0.00 0 3554 9 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 2875 6 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 3026 8 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 2460 7 0 0
v_cancel_supplier_invoice 770 752 18 2.33 0 2207 20 0 18
v_cancel_supplier_order 822 822 0 0.00 0 2717 12 0 0
v_cancel_write_off 1946 1946 0 0.00 0 1968 13 0 0
v_max_id_clo_ord 5053 5053 0 0.00 0 171 1 0 0
v_max_id_clo_res 933 933 0 0.00 0 3033 13 0 0
v_max_id_ord_sup 4988 4988 0 0.00 0 4690 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 2071 4 0 0
v_max_non_paid_realizn 3250 3250 0 0.00 0 4528 5 0 0
v_min_id_clo_ord 5053 5053 0 0.00 0 3662 5 0 0
v_min_id_clo_res 933 933 0 0.00 0 2435 13 0 0
v_min_id_ord_sup 4988 4988 0 0.00 0 7293 9 0 0
v_min_non_paid_invoice 5517 5517 0 0.00 0 15559 19 0 0
v_min_non_paid_realizn 6522 6522 0 0.00 0 11633 21 0 0
v_our_firm 9612 9612 0 0.00 0 12 1 0 0
v_reserve_write_off 18452 18452 0 0.00 0 4722 9 0 0
x_lock_depdocs_on_canc_invoice 11561 11561 0 0.00 0 12369 107 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 9033 319 0 0
x_make_qstorno_1000_1200 5053 5053 0 0.00 1 48280 1180 0 0
x_make_qstorno_1200_2000 5005 5005 0 0.00 3 72424 3336 0 0
x_make_qstorno_2100_3300 431044 431044 0 0.00 0 169212 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2022 01:39:16 to 13-01-2022 01:39:20.

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:01 5 8192000000 8804113712 8817123328 9544688 0.116 3 1 2 0 9667568 70368 52512
23:09:02 24508 8192000000 9905089824 10217033728 989709776 10.779 100 92 183 0 1019519776 6088384 23721616
23:10:31 25145 8192000000 9947350960 10269171712 1028486944 11.154 100 92 184 0 1060693616 8673504 23533168
23:12:04 8136 8192000000 9964618656 10294071296 1049272784 11.354 100 92 184 0 1083039776 10040928 23726064
23:13:15 14934 8192000000 10029662656 10373808128 1106984816 11.904 100 86 172 0 1139376384 11205360 21186208
23:14:36 7915 8192000000 10067548032 10424811520 1151099328 12.320 100 81 162 0 1182626272 13075104 18451840
23:15:46 5053 8192000000 10112326048 10477355008 1197426080 12.752 100 84 166 4 1228853488 13276432 18150976
23:16:53 3074 8192000000 10150006896 10523127808 1242355264 13.168 100 86 171 1 1273488080 12702128 18430688
23:17:57 2261 8192000000 10187667680 10566123520 1267308096 13.397 100 92 185 0 1301031744 12660464 21063184
23:19:01 1945 8192000000 10205485232 10591039488 1290385280 13.608 100 89 178 1 1323213744 12806848 20021616
23:20:04 4208 8192000000 10188654976 10593460224 1295845664 13.657 100 48 96 0 1316056960 9961392 10249904
23:21:11 5621 8192000000 10204717184 10612383744 1306158224 13.751 100 33 65 4 1320594864 6927408 7509232
23:22:20 12389 8192000000 10203424576 10621296640 1320299776 13.879 100 13 26 0 1329326304 6190016 2836512
23:23:34 33336 8192000000 10228326000 10643120128 1326169168 13.933 100 47 94 0 1344009264 6985520 10854576
23:25:13 7046 8192000000 10224793600 10641518592 1330805808 13.974 100 34 69 2 1345425584 7158544 7461232
23:26:22 12731 8192000000 10227763424 10645913600 1333701488 14.001 100 22 44 2 1344644880 5925648 5017744
23:27:37 52416 8192000000 10214051776 10623950848 1277336672 13.489 96 29 58 1 1289065040 5251056 6477312
23:29:30 6722 8192000000 10170032864 10571198464 1214750864 12.913 94 39 79 0 1229505840 6129600 8625376
23:30:38 7963 8192000000 10153949552 10561544192 1236447056 13.114 97 26 52 0 1248957456 6004240 6506160
23:31:49 5895 8192000000 10123308272 10517807104 1222130448 12.981 99 30 59 2 1234456224 5329600 6996176
23:33:00 33208 8192000000 10154250592 10564526080 1253558320 13.271 99 21 42 0 1268265360 10028016 4679024
23:34:34 6420 8192000000 10180652848 10580516864 1274665392 13.464 100 37 74 1 1290506640 7252624 8588624
23:35:44 7535 8192000000 10178138240 10590076928 1287918800 13.585 100 17 33 1 1297105696 4738112 4448784
23:36:56 14835 8192000000 10183479728 10597683200 1295360672 13.653 100 17 34 0 1305098688 5719040 4018976
23:38:12 63654 8192000000 10222112848 10631401472 1317202208 13.851 100 37 73 0 1332553040 6481984 8868848
23:40:25 32497 8192000000 10255073888 10674802688 1350365584 14.151 100 48 96 2 1371869040 10151712 11351744
23:42:44 21859 8192000000 10281047456 10700414976 1367501568 14.305 100 42 85 0 1399235296 21286128 10447600
23:44:08 12308 8192000000 10296012016 10716459008 1375195616 14.374 100 46 92 1 1408143408 21120528 11827264
23:45:27 3209 8192000000 10317134000 10732666880 1389802448 14.504 100 59 118 2 1427196976 23517776 13876752
23:46:34 7853 8192000000 10329608560 10751684608 1404543488 14.635 100 55 109 1 1442721296 24941840 13235968
23:47:50 12289 8192000000 10347262544 10769317888 1415955808 14.737 100 62 123 0 1458427616 27692928 14778880
23:49:07 14408 8192000000 10359973872 10782146560 1420341632 14.776 100 52 104 1 1472551808 38181344 14028832
23:50:25 4443 8192000000 10379049440 10798583808 1430898496 14.869 100 70 137 0 1488273200 40273008 17101696
23:51:35 3150 8192000000 10386539088 10802950144 1430331200 14.864 100 76 149 1 1493418608 44108384 18979024
23:52:45 3866 8192000000 10386785056 10810740736 1429297936 14.855 100 75 149 0 1493040480 45101904 18640640
23:53:53 4069 8192000000 10390898896 10804551680 1422417680 14.794 100 94 187 1 1497994784 50818688 24758416
23:55:02 2506 8192000000 10384186208 10798956544 1410829936 14.691 100 80 157 1 1487732560 55887200 21015424
23:56:07 2051 8192000000 10377130224 10789646336 1404129600 14.632 100 82 163 4 1478759344 53167760 21461984
23:57:15 3742 8192000000 10370725616 10788540416 1394131376 14.543 100 71 143 1 1470047744 56676160 19240208
23:58:23 15486 8192000000 10340452160 10779049984 1372225616 14.347 98 36 72 1 1428367840 45566464 10575760
23:59:42 19140 8192000000 10328052400 10762809344 1374120096 14.364 100 40 80 2 1437775648 52251792 11403760
00:01:13 28116 8192000000 10308736160 10757652480 1375282816 14.374 100 22 45 1 1426816784 45349920 6184048
00:02:48 59295 8192000000 10271463488 10717609984 1309821664 13.784 97 13 26 1 1344725392 31726208 3177520
00:05:03 218218 8192000000 10250759904 10714370048 1316426848 13.844 97 38 76 1 1340024528 14950624 8647056
00:09:41 50408 8192000000 10259099728 10705100800 1347896272 14.129 99 27 54 0 1361368080 6971888 6499920
00:11:34 22094 8192000000 10298344608 10723291136 1373168976 14.355 100 57 114 3 1396218592 8782432 14267184
00:12:57 187890 8192000000 10302489584 10736074752 1292607904 13.628 93 25 50 1 1311711872 12818384 6285584
00:17:05 52565 8192000000 10284194000 10737451008 1368498928 14.314 100 24 48 0 1393441920 18800704 6142288
00:19:23 81997 8192000000 10284746752 10738724864 1367803664 14.307 100 31 61 1 1394068048 18423472 7840912
00:21:45 71565 8192000000 10276989520 10732216320 1372484256 14.349 100 19 38 0 1387284752 10387184 4413312
00:23:58 50780 8192000000 10292202848 10746597376 1380231328 14.419 100 13 24 2 1393260160 10685472 2343360
00:25:53 52224 8192000000 10299304032 10749800448 1375542624 14.377 99 29 58 0 1393253472 10898704 6812144
00:27:46 23127 8192000000 10295520528 10745253888 1375467184 14.376 99 30 60 2 1391834608 9427856 6939568
00:29:10 44316 8192000000 10314059504 10760970240 1398045952 14.578 100 36 71 0 1417915936 12111264 7758720
00:30:55 60784 8192000000 10304069488 10758139904 1383170064 14.445 99 28 56 1 1404904896 15406608 6328224
00:32:58 41908 8192000000 10303802096 10754215936 1383017824 14.444 99 20 40 1 1396358368 8749296 4591248
00:34:41 68383 8192000000 10311800880 10770739200 1379868864 14.415 98 13 26 0 1402934272 19699136 3366272
00:37:53 102268 8192000000 10302311712 10766270464 1373893968 14.362 98 21 42 0 1393555440 15122208 4539264
00:41:05 82311 8192000000 10304436096 10753642496 1339772688 14.055 95 20 40 1 1353989424 9772992 4443744
00:43:37 83065 8192000000 10299856768 10767376384 1388849808 14.496 99 17 34 1 1409436256 16657536 3928912
00:46:01 53893 8192000000 10293436688 10752376832 1361566464 14.251 97 20 40 0 1378995360 12796416 4632480
00:48:02 58343 8192000000 10267152608 10725064704 1321518880 13.890 96 11 22 0 1338359904 14090240 2750784
00:50:01 33237 8192000000 10302946544 10745737216 1371799984 14.343 99 29 58 2 1395844448 16359536 7684928
00:51:36 104942 8192000000 10275511328 10735157248 1322033584 13.895 96 10 20 1 1343820720 19539664 2247472
00:54:32 69605 8192000000 10278488944 10725777408 1331325632 13.979 97 16 32 1 1349522576 14664928 3532016
00:56:43 22945 8192000000 10276732080 10713350144 1326617712 13.937 97 47 91 0 1351754048 14430800 10705536
00:58:22 49667 8192000000 10291935568 10725818368 1342825584 14.083 98 22 41 3 1359216672 10915664 5475424
01:00:19 85381 8192000000 10276591904 10729857024 1374472032 14.367 100 27 54 1 1391737120 11098000 6167088
01:03:09 45944 8192000000 10263623952 10723672064 1360239168 14.240 99 15 29 1 1372736368 9003952 3493248
01:04:56 68887 8192000000 10275340256 10729988096 1349953920 14.147 98 13 26 1 1365110080 12163296 2992864
01:07:05 69541 8192000000 10253500224 10706960384 1344270976 14.096 99 9 17 1 1355227744 8771904 2184864
01:09:21 47181 8192000000 10259558848 10713972736 1356964880 14.210 99 24 48 0 1370213248 7935424 5312944
01:11:11 94397 8192000000 10265120080 10701705216 1321931984 13.894 96 16 32 0 1342880976 16901968 4047024
01:14:33 44735 8192000000 10268714384 10722529280 1319893136 13.876 96 23 44 0 1334616976 9802016 4921824
01:16:21 77322 8192000000 10298486112 10744242176 1366426560 14.295 98 49 97 2 1399468000 22081936 10959504
01:18:39 50072 8192000000 10297887328 10765885440 1365710832 14.289 99 10 20 2 1399276704 30971072 2594800
01:20:46 56772 8192000000 10298830208 10744127488 1385511456 14.466 100 27 54 1 1405646304 14030768 6104080
01:22:46 82973 8192000000 10298875664 10755461120 1361249488 14.249 98 21 42 0 1376716736 11387696 4079552
01:25:09 142851 8192000000 10290396336 10738167808 1352100416 14.166 98 27 54 0 1371834784 13435744 6298624
01:28:42 44340 8192000000 10270770560 10736402432 1370002000 14.327 99 8 15 0 1379012800 6757488 2253312
01:30:30 53724 8192000000 10285453840 10746884096 1384979904 14.461 100 6 12 0 1394225376 8088016 1157456
01:32:24 74649 8192000000 10262899936 10718420992 1341173280 14.068 97 11 22 0 1358014016 14269168 2571568
01:35:32 136878 8192000000 10262635136 10726060032 1343437776 14.088 98 12 24 0 1357379488 10885792 3055920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 13-01-2022 01:39:38 to 13-01-2022 01:39:39.

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          227356
MON$OLDEST_ACTIVE               227717
MON$OLDEST_SNAPSHOT             227717
MON$NEXT_TRANSACTION            227717
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                       2003072
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 ---
:869: 
:870: mscs-test-161.budzdorov.ru    Thu Jan 13 01:38:00 2022
:871:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:872: 
--- end of diff output ---

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

13.01.22 01:39:40. 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-13 01:39:43 No severe PSQL-related problems occured
13.01.22 01:39:44. End of report.