Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.05.2022 04:37
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 16581148 kB
MemAvailable 31401352 kB
Buffers 20 kB
Cached 15039064 kB
SwapCached 2608 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-11 04:37:17
DTS_END                         2022-05-11 04:37:17.1990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46158
TRN_ID                          212966
ATT_ID                          604
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: 8725.88 26180 1047106
customer order: creation 158.92 492 19070
customer order: refuse 13.51 363 1621
order to supplier: creation 44.32 1386 5318
order to supplier: removal 3.18 169555 382
invoice (draft): creation 41.35 3257 4962
invoice (draft): removal 49.29 935 5915
invoice accept: apply 39.09 26114 4691
invoice accept: cancel 45.08 1055 5409
customer reserve: creation 3622.69 249 434723
customer reserve: removal 4441.25 97 532950
realization accept: apply 76.23 266 9148
realization accept: cancel 71.20 165 8544
payment from customer: creation 54.99 178 6599
payment from customer: removal 5.28 102 634
payment to supplier: creation 44.66 191 5359
payment to supplier: removal 3.27 137 392
service: total inventory turnovers 3.53 3804 423
service: total monetary turnovers 7.13 373 855
service: aggregate perf. data 0.88 15976 106
service: refresh index statistics 0.04 298912 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-05-2022 04:38:16 to 11-05-2022 04:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5720
TEST_TIME 2 4289
TEST_TIME 3 5177
TEST_TIME 4 5054
TEST_TIME 5 5332
TEST_TIME 6 4957
TEST_TIME 7 5218
TEST_TIME 8 5151
TEST_TIME 9 5299
TEST_TIME 10 5719
TEST_TIME 11 5987
TEST_TIME 12 6147
TEST_TIME 13 6365
TEST_TIME 14 6605
TEST_TIME 15 6952
TEST_TIME 16 7139
TEST_TIME 17 7282
TEST_TIME 18 7500
TEST_TIME 19 7944
TEST_TIME 20 8074
TEST_TIME 21 8145
TEST_TIME 22 8513
TEST_TIME 23 8625
TEST_TIME 24 9045
TEST_TIME 25 9092
TEST_TIME 26 9806
TEST_TIME 27 10256
TEST_TIME 28 10866
TEST_TIME 29 10973
TEST_TIME 30 10723
TEST_TIME 31 10748
TEST_TIME 32 10579
TEST_TIME 33 10422
TEST_TIME 34 10300
TEST_TIME 35 10215
TEST_TIME 36 10057
TEST_TIME 37 10069
TEST_TIME 38 10078
TEST_TIME 39 9997
TEST_TIME 40 9897
TEST_TIME 41 9816
TEST_TIME 42 9654
TEST_TIME 43 9551
TEST_TIME 44 9468
TEST_TIME 45 9431
TEST_TIME 46 9369
TEST_TIME 47 9345
TEST_TIME 48 9376
TEST_TIME 49 9383
TEST_TIME 50 9305
TEST_TIME 51 9393
TEST_TIME 52 9316
TEST_TIME 53 9285
TEST_TIME 54 9298
TEST_TIME 55 9340
TEST_TIME 56 9299
TEST_TIME 57 9383
TEST_TIME 58 9328
TEST_TIME 59 9187
TEST_TIME 60 9043
TEST_TIME 61 8958
TEST_TIME 62 8974
TEST_TIME 63 8963
TEST_TIME 64 8915
TEST_TIME 65 8828
TEST_TIME 66 8762
TEST_TIME 67 8756
TEST_TIME 68 8720
TEST_TIME 69 8752
TEST_TIME 70 8740
TEST_TIME 71 8795
TEST_TIME 72 8853
TEST_TIME 73 8822
TEST_TIME 74 8933
TEST_TIME 75 8957
TEST_TIME 76 8933
TEST_TIME 77 9111
TEST_TIME 78 9115
TEST_TIME 79 9032
TEST_TIME 80 9058
TEST_TIME 81 9010
TEST_TIME 82 8945
TEST_TIME 83 8929
TEST_TIME 84 8892
TEST_TIME 85 8831
TEST_TIME 86 8803
TEST_TIME 87 8768
TEST_TIME 88 8724
TEST_TIME 89 8825
TEST_TIME 90 8804
TEST_TIME 91 8786
TEST_TIME 92 8781
TEST_TIME 93 8743
TEST_TIME 94 8753
TEST_TIME 95 8832
TEST_TIME 96 8853
TEST_TIME 97 8834
TEST_TIME 98 8935
TEST_TIME 99 8918
TEST_TIME 100 8882
TEST_TIME 101 8919
TEST_TIME 102 9017
TEST_TIME 103 9089
TEST_TIME 104 9008
TEST_TIME 105 9002
TEST_TIME 106 8942
TEST_TIME 107 8872
TEST_TIME 108 8829
TEST_TIME 109 8839
TEST_TIME 110 8789
TEST_TIME 111 8778
TEST_TIME 112 8744
TEST_TIME 113 8744
TEST_TIME 114 8735
TEST_TIME 115 8718
TEST_TIME 116 8817
TEST_TIME 117 8800
TEST_TIME 118 8778
TEST_TIME 119 8768
TEST_TIME 120 8748

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-05-2022 04:38:20 to 11-05-2022 04:38:23.

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 1449006 1449006 0 0.00 0 19349 12 0 0
doc_list_biud 1448994 1448994 0 0.00 0 45193 41 0 0
sp_add_invoice_to_stock 4809 4691 118 2.45 29 231835 26115 0 118
sp_cancel_adding_invoice 5409 5409 0 0.00 7 56012 1055 0 0
sp_cancel_client_order 1621 1621 0 0.00 7 9253 363 0 0
sp_cancel_customer_reserve 532950 532950 0 0.00 1 45245 97 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 4 2986 103 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 6758 138 0 0
sp_cancel_supplier_invoice 5923 5915 8 0.13 2 30476 936 0 8
sp_cancel_supplier_order 382 382 0 0.00 8 1369527 169555 0 0
sp_cancel_write_off 8544 8544 0 0.00 2 12025 166 0 0
sp_client_order 19070 19070 0 0.00 6 44929 492 0 0
sp_customer_reserve 434723 434723 0 0.00 1 62257 250 0 0
sp_fill_shopping_cart 29331 29331 0 0.00 1 22102 80 0 0
sp_fill_shopping_cart_clo_res 434697 434697 0 0.00 0 25815 22 0 0
sp_get_clo_for_invoice 4659 4659 0 0.00 0 32760 346 0 0
sp_kill_cost_storno 14979 14979 0 0.00 0 7485 66 0 0
sp_kill_qstorno_ret_qs2qd 1080741 1080741 0 0.00 0 45069 43 0 0
sp_kill_qty_storno 568626 568626 0 0.00 0 45183 97 0 0
sp_lock_selected_doc 19171 19171 0 0.00 0 8108 7 0 0
sp_make_cost_storno 25765 25765 0 0.00 0 19342 72 0 0
sp_multiply_rows_for_pdistr 25761 25761 0 0.00 0 15217 35 0 0
sp_multiply_rows_for_qdistr 19069 19069 0 0.00 0 18099 210 0 0
sp_pay_from_customer 6599 6599 0 0.00 5 15228 179 0 0
sp_pay_to_supplier 5359 5359 0 0.00 6 15198 192 0 0
sp_payment_common 11955 11955 0 0.00 2 7524 40 0 0
sp_qd_handle_on_cancel_clo 1621 1621 0 0.00 0 9244 298 0 0
sp_qd_handle_on_invoice_upd_sts 10068 10068 0 0.00 0 13523 456 0 0
sp_qd_handle_on_reserve_upd_sts 9146 9146 0 0.00 0 15462 64 0 0
sp_reserve_write_off 9148 9148 0 0.00 7 19410 267 0 0
sp_supplier_invoice 4962 4962 0 0.00 9 93626 3258 0 0
sp_supplier_order 5318 5318 0 0.00 7 44434 1386 0 0
srv_aggregate_perf_data 106 106 0 0.00 2245 181062 15977 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 929 182004 42113 0 0
srv_find_qd_qs_mism 1002339 1002339 0 0.00 0 16133 10 0 0
srv_make_invnt_saldo 423 423 0 0.00 68 75589 3805 0 0
srv_make_money_saldo 855 855 0 0.00 1 11220 373 0 0
srv_recalc_idx_stat 5 5 0 0.00 99046 595228 298913 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4694 4694 0 0.00 1 9018 816 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 7700 385 0 0
t$perf-norm:sp_cancel_client_order 1621 1621 0 0.00 0 4590 24 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 579 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 4851 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 230 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 1002 32 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 90402 6228 0 0
t$perf-norm:sp_cancel_write_off 895 895 0 0.00 0 3885 20 0 0
t$perf-norm:sp_client_order 19071 19071 0 0.00 0 8186 33 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 2737 21 0 0
t$perf-norm:sp_pay_from_customer 6599 6599 0 0.00 0 9448 21 0 0
t$perf-norm:sp_pay_to_supplier 5359 5359 0 0.00 0 4831 18 0 0
t$perf-norm:sp_reserve_write_off 9149 9149 0 0.00 0 5581 21 0 0
t$perf-norm:sp_supplier_invoice 4962 4962 0 0.00 0 3678 27 0 0
t$perf-norm:sp_supplier_order 5318 5318 0 0.00 0 5554 30 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 314 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2173 63 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 413 9 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 958 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 141 54 0 0
v_add_invoice_to_stock 9550 9314 236 2.47 0 6437 28 0 236
v_all_customers 34982 34982 0 0.00 0 58 3 0 0
v_all_suppliers 28402 28402 0 0.00 0 63 4 0 0
v_all_wares 38136 38136 0 0.00 0 60 3 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 5018 23 0 0
v_cancel_client_order 3242 3242 0 0.00 0 4278 11 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 2870 14 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 5637 13 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1637 11 0 0
v_cancel_supplier_invoice 820 804 16 1.95 0 5913 29 0 16
v_cancel_supplier_order 764 764 0 0.00 0 4553 20 0 0
v_cancel_write_off 1790 1790 0 0.00 0 11943 32 0 0
v_max_id_clo_ord 5312 5312 0 0.00 0 216 4 0 0
v_max_id_clo_res 924 924 0 0.00 0 3334 20 0 0
v_max_id_ord_sup 4951 4951 0 0.00 0 9590 8 0 0
v_max_non_paid_invoice 1420 1420 0 0.00 0 52 6 0 0
v_max_non_paid_realizn 3371 3371 0 0.00 0 4118 8 0 0
v_min_id_clo_ord 5312 5312 0 0.00 0 1345 8 0 0
v_min_id_clo_res 923 923 0 0.00 0 8491 27 0 0
v_min_id_ord_sup 4951 4951 0 0.00 0 8419 10 0 0
v_min_non_paid_invoice 5358 5358 0 0.00 0 6186 11 0 0
v_min_non_paid_realizn 6595 6595 0 0.00 0 5119 14 0 0
v_our_firm 9598 9598 0 0.00 0 64 4 0 0
v_reserve_write_off 18292 18292 0 0.00 0 5639 12 0 0
x_lock_depdocs_on_canc_invoice 10922 10922 0 0.00 0 21987 133 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 22027 532 0 0
x_make_qstorno_1000_1200 5318 5318 0 0.00 3 44417 1231 0 0
x_make_qstorno_1200_2000 4962 4962 0 0.00 5 93412 3031 0 0
x_make_qstorno_2100_3300 434722 434722 0 0.00 0 59039 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-05-2022 04:38:24 to 11-05-2022 04:38:28.

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
02:07:21 7 8192000000 8798447664 8809598976 4478816 0.054 2 1 2 0 4578208 63616 35776
02:08:21 31845 8192000000 9708012544 10007605248 787789168 8.772 100 91 182 1 818347152 7950896 22607088
02:09:57 25284 8192000000 9763756928 10073800704 832855152 9.228 100 88 176 0 867468880 13279696 21334032
02:11:25 24109 8192000000 9825023904 10145198080 875619584 9.656 100 87 174 0 920888224 24690160 20578480
02:12:58 10192 8192000000 9877416096 10212474880 921806560 10.114 100 80 159 1 989131008 50409856 16914592
02:14:11 7304 8192000000 9934464032 10279440384 966315488 10.551 100 90 174 1 1054851696 70492096 18044112
02:15:20 2562 8192000000 9967700848 10324635648 999002208 10.869 100 82 160 0 1078826144 63816784 16007152
02:16:25 1339 8192000000 10006542400 10373087232 1035584976 11.222 100 95 185 0 1127270128 72340736 19344416
02:17:28 1144 8192000000 10031498864 10402344960 1053640064 11.396 100 94 186 0 1147333920 74388816 19305040
02:18:31 1187 8192000000 10051583520 10431762432 1064307712 11.498 100 100 193 1 1169905968 86151072 19447184
02:19:33 1206 8192000000 10028829920 10406961152 1072482112 11.576 100 97 186 0 1151780400 60542720 18755568
02:20:35 799 8192000000 10044300992 10428231680 1073955264 11.590 100 92 177 2 1165452560 73496256 18001040
02:21:37 1211 8192000000 10066248336 10452713472 1082205792 11.668 100 95 176 4 1188109616 88582240 17321584
02:22:39 1260 8192000000 10057852000 10442563584 1084735248 11.693 100 99 187 2 1177698880 73711088 19252544
02:23:41 941 8192000000 10050732384 10439311360 1089940256 11.742 100 97 179 1 1169791440 60643840 19207344
02:24:43 1245 8192000000 10073679568 10462363648 1098135600 11.820 100 99 190 1 1195397600 78780272 18481728
02:25:45 1267 8192000000 10068121040 10457714688 1098614368 11.824 100 91 175 2 1185470240 69657728 17198144
02:26:48 1113 8192000000 10070199120 10458152960 1096532112 11.805 100 97 190 0 1195874144 80552160 18789872
02:27:49 1262 8192000000 10085515552 10476478464 1098636704 11.825 100 97 194 0 1207624960 88876144 20112112
02:28:51 1230 8192000000 10075103312 10462973952 1094148144 11.782 100 88 169 3 1191101520 80066752 16886624
02:29:54 1724 8192000000 10025588736 10417537024 1083535344 11.681 100 75 144 1 1145915008 46768576 15611088
02:30:56 681 8192000000 10022043600 10405511168 1063055824 11.486 100 87 164 0 1144086624 63140112 17890688
02:31:58 2616 8192000000 9993635584 10377830400 1039790576 11.263 98 73 133 2 1114243264 60233632 14219056
02:33:01 1638 8192000000 9998570144 10385440768 1051736752 11.377 100 68 132 2 1120202480 54959424 13506304
02:34:04 1298 8192000000 9959996320 10331213824 1003188816 10.909 99 74 139 0 1084363168 67757520 13416832
02:35:06 1828 8192000000 9979890176 10360430592 1038008000 11.246 100 78 144 0 1101173568 49327344 13838224
02:36:08 1388 8192000000 9969286944 10336641024 1035317168 11.220 100 95 182 0 1088412192 34237408 18857616
02:37:10 3997 8192000000 9994852832 10376196096 1045062880 11.313 100 59 110 2 1118055504 62313776 10678848
02:38:20 18034 8192000000 9997410288 10376019968 1070026400 11.552 100 70 127 3 1105839264 24510512 11302352
02:39:51 6656 8192000000 10069079856 10461786112 1091157504 11.754 100 66 127 1 1183779552 79954848 12667200
02:41:03 9245 8192000000 10089111440 10485891072 1102743376 11.864 100 70 126 1 1208242720 92519472 12979872
02:42:24 2608 8192000000 10155265520 10553978880 1122382512 12.049 100 75 135 0 1274461600 138544560 13534528
02:43:33 16334 8192000000 10177609344 10576556032 1117537904 12.004 99 56 112 0 1287531264 157534064 12459296
02:44:59 5069 8192000000 10216004224 10608021504 1136955840 12.187 100 86 156 1 1331021584 175553776 18511968
02:46:09 3767 8192000000 10261142656 10658549760 1142521152 12.239 100 75 145 1 1372148480 213509792 16117536
02:47:16 1869 8192000000 10302514304 10713739264 1156267184 12.368 100 88 173 0 1411554256 234186832 21100240
02:48:22 2127 8192000000 10321128176 10726490112 1167291696 12.472 100 89 171 0 1427195024 241058000 18845328
02:49:29 1240 8192000000 10359966496 10758516736 1183428128 12.622 100 91 175 0 1468973568 266746960 18798480
02:50:33 923 8192000000 10393435472 10799181824 1187755264 12.662 100 94 184 1 1498569328 288836208 21977856
02:51:37 1079 8192000000 10426865760 10848940032 1192425904 12.706 100 99 192 0 1538827664 323210800 23190960
02:52:42 1377 8192000000 10425197360 10844090368 1187363264 12.659 99 94 185 2 1533918816 325379568 21175984
02:53:46 994 8192000000 10442484000 10881998848 1189486960 12.679 100 95 186 0 1549324496 337148656 22688880
02:54:51 1621 8192000000 10471849648 10897784832 1188978064 12.674 100 93 182 0 1571323584 359742368 22603152
02:55:56 920 8192000000 10456703808 10925084672 1195370864 12.733 100 98 191 1 1566196816 347304464 23521488
02:56:58 1002 8192000000 10488940352 10912747520 1199222336 12.769 100 100 189 3 1592760160 370733200 22804624
02:58:02 1877 8192000000 10490154752 10928685056 1189880880 12.682 99 77 152 1 1597405424 389622496 17902048
02:59:09 9039 8192000000 10509734272 10980724736 1180037312 12.591 98 56 112 0 1613335584 418525632 14772640
03:00:35 7532 8192000000 10505016112 10961420288 1184893536 12.636 100 49 98 0 1623699072 426154368 12651168
03:01:44 16496 8192000000 10438350800 10958393344 1151706288 12.326 99 32 64 0 1537502816 377458080 8338448
03:03:02 16499 8192000000 10341989680 10825453568 1151413696 12.323 100 35 68 0 1477013184 316653952 8945536
03:04:40 56019 8192000000 10119075168 10660012032 1146270880 12.274 100 16 32 0 1343868448 193591088 4006480
03:06:51 70938 8192000000 10077161360 10555719680 1120556880 12.032 98 19 37 1 1202138688 77273888 4307920
03:09:06 34852 8192000000 10073848224 10527653888 1112671888 11.958 99 28 56 0 1174692928 56737792 5283248
03:10:46 77423 8192000000 10085142336 10560770048 1121577968 12.042 100 14 27 0 1178050544 53323936 3148640
03:13:04 81639 8192000000 10109649296 10542813184 1103325184 11.869 98 33 65 0 1196742384 86403520 7013680
03:15:27 181988 8192000000 10067702704 10511069184 1078787392 11.636 96 22 44 0 1144805808 61002160 5016256
03:19:29 26992 8192000000 10045842240 10473328640 1105764544 11.892 99 59 111 5 1144871952 26518288 12589120
03:20:57 42066 8192000000 10078785088 10531606528 1113779312 11.968 99 37 71 1 1177317536 55788096 7750128
03:22:40 58541 8192000000 10091385472 10551418880 1121138784 12.038 100 19 38 0 1202348032 76964768 4244480
03:24:39 46040 8192000000 10056308496 10501947392 1105233792 11.887 99 32 64 0 1139476848 27295168 6947888
03:26:34 88772 8192000000 10080358336 10521116672 1099356144 11.832 97 15 30 1 1157263248 54543728 3363376
03:29:06 115415 8192000000 10086199856 10532372480 1057650592 11.434 94 24 48 0 1112012032 49222560 5138880
03:32:07 57964 8192000000 10055850864 10475606016 1104017600 11.876 98 44 83 1 1147507280 35479856 8009824
03:34:07 102535 8192000000 10053089440 10522193920 1118271744 12.011 99 27 54 0 1197070896 73418368 5380784
03:36:50 122818 8192000000 10101413568 10530258944 1114459136 11.975 99 53 98 3 1156768224 32925088 9384000
03:40:00 24429 8192000000 10074445760 10549153792 1118791232 12.016 100 25 50 1 1177576368 54049392 4735744
03:41:26 44538 8192000000 10051808800 10499559424 1129428176 12.116 100 13 26 0 1179131936 47325248 2378512
03:43:11 82124 8192000000 10079560960 10560012288 1112119440 11.952 98 22 42 0 1174242848 57847968 4275440
03:45:33 29682 8192000000 10157604176 10587455488 1149403648 12.304 100 23 46 1 1240024592 85517920 5103024
03:47:03 19379 8192000000 10175831344 10607497216 1159495872 12.399 100 36 72 4 1264865280 97019664 8349744
03:48:23 30159 8192000000 10190650720 10651013120 1159154352 12.395 100 37 74 1 1319740192 151402976 9182864
03:49:55 21279 8192000000 10168725968 10619723776 1141799136 12.232 99 33 64 4 1265164960 116146448 7219376
03:51:20 74055 8192000000 10170924352 10620059648 1129023760 12.112 97 31 62 1 1273743344 137680032 7039552
03:53:34 56959 8192000000 10104312560 10597273600 1124604064 12.070 98 18 36 1 1210863568 82168544 4090960
03:55:33 61441 8192000000 10104467984 10545479680 1073357472 11.584 94 26 52 0 1132138064 53705600 5074992
03:57:42 104799 8192000000 10073877776 10513031168 1117107664 12.000 99 35 67 0 1174080576 49978912 6994000
04:00:30 52256 8192000000 10065702528 10533511168 1137846848 12.195 100 21 42 1 1179340592 36511568 4982176
04:02:24 60605 8192000000 10084620928 10548039680 1127850192 12.101 98 16 33 0 1176300352 44272288 4177872
04:04:29 72912 8192000000 10148063120 10628812800 1139629024 12.212 99 28 57 2 1242587136 96704528 6253584
04:07:16 11103 8192000000 10099485568 10533584896 1152139056 12.330 99 48 91 2 1194499792 31980368 10380368
04:08:34 42036 8192000000 10130377312 10575298560 1121438656 12.041 97 38 76 1 1205975152 75974272 8562224
04:10:33 28722 8192000000 10174296384 10601394176 1108400016 11.917 96 40 75 1 1229981328 113697600 7883712
04:12:05 67652 8192000000 10151734896 10574843904 1112797440 11.959 97 26 51 1 1211707088 93985072 4924576
04:14:14 27801 8192000000 10178802080 10604597248 1152011904 12.328 100 35 68 1 1259098528 100157680 6928944
04:15:43 51928 8192000000 10139433088 10572677120 1126658544 12.090 98 26 52 1 1211721552 79276592 5786416
04:17:51 11453 8192000000 10214007680 10636115968 1152195568 12.330 100 64 121 0 1298427744 132749984 13482192
04:19:03 52246 8192000000 10167112384 10705035264 1136443312 12.182 99 21 42 0 1307589952 166011584 5135056
04:21:01 96990 8192000000 10060777296 10511790080 1094623440 11.787 97 32 64 1 1147193968 45990608 6579920
04:24:09 55949 8192000000 10069937888 10544709632 1128564992 12.108 100 4 8 0 1176117248 46788272 763984
04:26:06 78307 8192000000 10102353888 10588844032 1117939200 12.008 98 14 28 0 1176615056 55188160 3487696
04:28:26 56099 8192000000 10121783104 10560655360 1123443248 12.060 98 30 60 1 1191583840 61777200 6363392
04:30:22 55585 8192000000 10156967648 10596466688 1119322864 12.021 97 22 44 2 1221013872 96643440 5047568
04:32:45 84092 8192000000 10072772960 10540388352 1095342176 11.793 96 22 44 0 1176457056 76525360 4589520
04:35:11 35244 8192000000 10136911520 10572955648 1141330592 12.228 100 39 76 4 1220396000 71178528 7886880
04:36:59 41156 8192000000 9214000000 9526255616 345488592 4.046 31 4 7 0 365057088 18788144 780352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-05-2022 04:38:30 to 11-05-2022 04:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-05-2022 04:38:47 to 11-05-2022 04:38:49.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          213238
MON$OLDEST_ACTIVE               213239
MON$OLDEST_SNAPSHOT             213239
MON$NEXT_TRANSACTION            213239
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-30 07:30:08.6030 GMT
MON$PAGES                       2020032
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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-oltp40 according to config parameter 'remove_isql_logs'

11.05.22 04:38:50. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-11 04:38:52 No severe PSQL-related problems occured
11.05.22 04:38:53. End of report.