Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.04.2021 04:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16605588 kB
MemAvailable 31368840 kB
Buffers 20 kB
Cached 14958820 kB
SwapCached 60 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.0
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 2021-04-06 04:26:58
DTS_END                         2021-04-06 04:26:58.1920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54306
TRN_ID                          233582
ATT_ID                          626
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: 8859.14 31518 1063097
customer order: creation 165.53 429 19863
customer order: refuse 14.61 373 1753
order to supplier: creation 46.08 1422 5530
order to supplier: removal 3.15 138727 378
invoice (draft): creation 45.19 3853 5423
invoice (draft): removal 48.47 1156 5816
invoice accept: apply 41.08 19317 4929
invoice accept: cancel 44.09 1017 5291
customer reserve: creation 3808.58 189 457029
customer reserve: removal 4341.95 79 521034
realization accept: apply 82.03 192 9843
realization accept: cancel 92.79 133 11135
payment from customer: creation 58.18 116 6981
payment from customer: removal 5.35 79 642
payment to supplier: creation 47.78 128 5733
payment to supplier: removal 3.49 76 419
service: total inventory turnovers 3.93 3753 471
service: total monetary turnovers 5.97 613 716
service: aggregate perf. data 0.89 18579 107
service: refresh index statistics 0.03 440122 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-04-2021 04:27:41 to 06-04-2021 04:27:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1218
TEST_TIME 2 2150
TEST_TIME 3 3820
TEST_TIME 4 4386
TEST_TIME 5 4558
TEST_TIME 6 4570
TEST_TIME 7 4615
TEST_TIME 8 4547
TEST_TIME 9 4646
TEST_TIME 10 4754
TEST_TIME 11 4961
TEST_TIME 12 5457
TEST_TIME 13 5747
TEST_TIME 14 5769
TEST_TIME 15 5945
TEST_TIME 16 6100
TEST_TIME 17 6139
TEST_TIME 18 6357
TEST_TIME 19 6393
TEST_TIME 20 6886
TEST_TIME 21 7136
TEST_TIME 22 7167
TEST_TIME 23 7324
TEST_TIME 24 7840
TEST_TIME 25 8140
TEST_TIME 26 8487
TEST_TIME 27 8621
TEST_TIME 28 9503
TEST_TIME 29 10565
TEST_TIME 30 10710
TEST_TIME 31 10619
TEST_TIME 32 10436
TEST_TIME 33 10344
TEST_TIME 34 10344
TEST_TIME 35 10286
TEST_TIME 36 10176
TEST_TIME 37 10205
TEST_TIME 38 10314
TEST_TIME 39 10280
TEST_TIME 40 10168
TEST_TIME 41 10359
TEST_TIME 42 10303
TEST_TIME 43 10095
TEST_TIME 44 9903
TEST_TIME 45 9759
TEST_TIME 46 9730
TEST_TIME 47 9657
TEST_TIME 48 9609
TEST_TIME 49 9687
TEST_TIME 50 9623
TEST_TIME 51 9515
TEST_TIME 52 9401
TEST_TIME 53 9403
TEST_TIME 54 9297
TEST_TIME 55 9404
TEST_TIME 56 9305
TEST_TIME 57 9326
TEST_TIME 58 9358
TEST_TIME 59 9285
TEST_TIME 60 9358
TEST_TIME 61 9277
TEST_TIME 62 9325
TEST_TIME 63 9301
TEST_TIME 64 9313
TEST_TIME 65 9354
TEST_TIME 66 9396
TEST_TIME 67 9440
TEST_TIME 68 9382
TEST_TIME 69 9345
TEST_TIME 70 9271
TEST_TIME 71 9195
TEST_TIME 72 9169
TEST_TIME 73 9076
TEST_TIME 74 9053
TEST_TIME 75 8973
TEST_TIME 76 9007
TEST_TIME 77 9092
TEST_TIME 78 9085
TEST_TIME 79 9122
TEST_TIME 80 9099
TEST_TIME 81 9117
TEST_TIME 82 9155
TEST_TIME 83 9158
TEST_TIME 84 9169
TEST_TIME 85 9181
TEST_TIME 86 9241
TEST_TIME 87 9231
TEST_TIME 88 9150
TEST_TIME 89 9053
TEST_TIME 90 9092
TEST_TIME 91 9061
TEST_TIME 92 9000
TEST_TIME 93 8990
TEST_TIME 94 8958
TEST_TIME 95 8975
TEST_TIME 96 8950
TEST_TIME 97 8947
TEST_TIME 98 8953
TEST_TIME 99 8924
TEST_TIME 100 8878
TEST_TIME 101 8911
TEST_TIME 102 8933
TEST_TIME 103 9085
TEST_TIME 104 9103
TEST_TIME 105 9086
TEST_TIME 106 9028
TEST_TIME 107 8970
TEST_TIME 108 8957
TEST_TIME 109 8935
TEST_TIME 110 8884
TEST_TIME 111 8857
TEST_TIME 112 8834
TEST_TIME 113 8819
TEST_TIME 114 8811
TEST_TIME 115 8856
TEST_TIME 116 8873
TEST_TIME 117 8887
TEST_TIME 118 8869
TEST_TIME 119 8899
TEST_TIME 120 8863

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-04-2021 04:27:45 to 06-04-2021 04:27:48.

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 1484590 1484590 0 0.00 0 18060 7 0 0
doc_list_biud 1484586 1484586 0 0.00 0 20357 35 0 0
sp_add_invoice_to_stock 5019 4929 90 1.79 39 211339 19317 0 90
sp_cancel_adding_invoice 5291 5291 0 0.00 4 77086 1018 0 0
sp_cancel_client_order 1753 1753 0 0.00 5 11232 374 0 0
sp_cancel_customer_reserve 521034 521034 0 0.00 1 18608 79 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 8519 79 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 2259 77 0 0
sp_cancel_supplier_invoice 5830 5816 14 0.24 2 20360 1157 0 14
sp_cancel_supplier_order 378 378 0 0.00 9 1436610 138728 0 0
sp_cancel_write_off 11135 11135 0 0.00 1 14716 133 0 0
sp_client_order 19863 19863 0 0.00 4 48020 430 0 0
sp_customer_reserve 457029 457029 0 0.00 0 81002 190 0 0
sp_fill_shopping_cart 30807 30807 0 0.00 1 16281 43 0 0
sp_fill_shopping_cart_clo_res 457020 457020 0 0.00 0 18576 19 0 0
sp_get_clo_for_invoice 4920 4920 0 0.00 0 40415 332 0 0
sp_kill_cost_storno 17487 17487 0 0.00 0 13917 65 0 0
sp_kill_qstorno_ret_qs2qd 1059397 1059397 0 0.00 0 20356 43 0 0
sp_kill_qty_storno 560166 560166 0 0.00 0 20356 92 0 0
sp_lock_selected_doc 20476 20476 0 0.00 0 10817 7 0 0
sp_make_cost_storno 27477 27477 0 0.00 0 18052 53 0 0
sp_multiply_rows_for_pdistr 27474 27474 0 0.00 0 9165 34 0 0
sp_multiply_rows_for_qdistr 19863 19863 0 0.00 0 24237 230 0 0
sp_pay_from_customer 6981 6981 0 0.00 4 14049 117 0 0
sp_pay_to_supplier 5733 5733 0 0.00 5 13520 128 0 0
sp_payment_common 12714 12714 0 0.00 1 11216 19 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 9121 329 0 0
sp_qd_handle_on_invoice_upd_sts 10209 10209 0 0.00 0 17085 411 0 0
sp_qd_handle_on_reserve_upd_sts 9841 9841 0 0.00 0 15439 64 0 0
sp_reserve_write_off 9843 9843 0 0.00 6 20609 193 0 0
sp_supplier_invoice 5423 5423 0 0.00 6 112411 3854 0 0
sp_supplier_order 5530 5530 0 0.00 5 27357 1422 0 0
srv_aggregate_perf_data 107 107 0 0.00 2610 395297 18579 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1694 125299 41977 0 0
srv_find_qd_qs_mism 1015494 1015494 0 0.00 0 17469 7 0 0
srv_make_invnt_saldo 471 471 0 0.00 52 109434 3753 0 0
srv_make_money_saldo 716 716 0 0.00 1 15547 614 0 0
srv_recalc_idx_stat 4 4 0 0.00 330006 554225 440123 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4929 4929 0 0.00 0 31862 198 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 4076 91 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 32833 49 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 390 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 15111 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 7516 43 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 664 14 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 32598 1964 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 1362 6 0 0
t$perf-norm:sp_client_order 19863 19863 0 0.00 0 30170 21 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 8798 18 0 0
t$perf-norm:sp_pay_from_customer 6981 6981 0 0.00 0 22869 12 0 0
t$perf-norm:sp_pay_to_supplier 5733 5733 0 0.00 0 31803 15 0 0
t$perf-norm:sp_reserve_write_off 9843 9843 0 0.00 0 14633 13 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 16429 26 0 0
t$perf-norm:sp_supplier_order 5530 5530 0 0.00 0 2572 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 855 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 791 13 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 2915 23 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 1441 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 6 0 0
v_add_invoice_to_stock 10014 9834 180 1.79 0 7716 19 0 180
v_all_customers 36502 36502 0 0.00 0 99 1 0 0
v_all_suppliers 30232 30232 0 0.00 0 25 1 0 0
v_all_wares 39726 39726 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 3510 17 0 0
v_cancel_client_order 3506 3506 0 0.00 0 5505 11 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 2311 9 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 5140 10 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 2240 11 0 0
v_cancel_supplier_invoice 870 842 28 3.21 0 4674 23 0 28
v_cancel_supplier_order 756 756 0 0.00 0 3939 13 0 0
v_cancel_write_off 1992 1992 0 0.00 0 3901 20 0 0
v_max_id_clo_ord 5528 5528 0 0.00 0 1335 2 0 0
v_max_id_clo_res 978 978 0 0.00 0 3679 17 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 5456 8 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 2604 4 0 0
v_max_non_paid_realizn 3573 3573 0 0.00 0 971 3 0 0
v_min_id_clo_ord 5528 5528 0 0.00 0 5129 8 0 0
v_min_id_clo_res 978 978 0 0.00 0 8937 47 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 5310 5 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 11212 12 0 0
v_min_non_paid_realizn 6980 6980 0 0.00 0 8841 14 0 0
v_our_firm 10040 10040 0 0.00 0 23 1 0 0
v_reserve_write_off 19682 19682 0 0.00 0 5774 8 0 0
x_lock_depdocs_on_canc_invoice 10686 10686 0 0.00 0 10953 129 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 21411 460 0 0
x_make_qstorno_1000_1200 5530 5530 0 0.00 2 27345 1333 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 3 112376 3699 0 0
x_make_qstorno_2100_3300 457028 457028 0 0.00 0 81001 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-04-2021 04:27:49 to 06-04-2021 04:27:53.

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
01:57:01 5 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:02 32761 8192000000 9727950800 10025615360 803006240 8.927 100 87 174 0 832061488 8071056 20984192
01:59:42 24641 8192000000 9766194320 10074877952 842416016 9.324 100 83 166 0 875254960 13184464 19654480
02:01:10 20607 8192000000 9812934752 10133352448 877182432 9.672 100 79 158 1 921046992 25947568 17916992
02:02:33 10551 8192000000 9862721248 10196115456 920260192 10.099 100 75 150 2 971026992 34573408 16193392
02:03:47 7489 8192000000 9928455360 10267951104 962858864 10.517 100 88 176 2 1035285328 54157888 18268576
02:04:57 5659 8192000000 9970748752 10320003072 989871536 10.780 100 93 187 1 1086318528 77902576 18544416
02:06:05 2737 8192000000 9992207456 10351935488 1021392560 11.085 100 92 182 0 1107299024 66836000 19070464
02:07:10 2320 8192000000 10015194480 10382381056 1051036112 11.371 100 94 188 0 1133942512 63789824 19116576
02:08:14 7372 8192000000 10036556896 10409652224 1061892816 11.475 100 79 156 2 1149603360 71110288 16600256
02:09:22 7994 8192000000 9987874496 10377531392 1069735888 11.550 100 29 57 3 1113558144 37734176 6088080
02:10:31 11997 8192000000 9979481040 10380980224 1074616704 11.596 100 32 64 1 1109487040 27726576 7143760
02:11:46 25917 8192000000 9997054848 10388197376 1081235952 11.659 100 58 112 3 1106037520 11834544 12967024
02:13:12 5592 8192000000 10020609760 10418110464 1091082656 11.753 100 45 85 4 1122907152 22923264 8901232
02:14:20 7327 8192000000 9998307904 10401062912 1096034272 11.800 100 37 72 1 1120544160 15889040 8620848
02:15:27 7507 8192000000 10001674832 10401685504 1097653232 11.815 100 23 47 0 1124064096 21180112 5230752
02:16:38 21701 8192000000 10014218400 10432208896 1096694096 11.806 99 31 62 0 1137071696 33928400 6449200
02:18:00 3256 8192000000 9980834592 10375999488 1067222368 11.526 98 47 94 1 1088871120 11417312 10231440
02:19:05 9688 8192000000 9947119760 10336993280 1033023776 11.198 98 26 52 2 1056650352 17848368 5778208
02:20:20 8324 8192000000 9939092448 10331365376 1014399520 11.018 99 39 78 0 1054714672 31453920 8861232
02:21:29 22542 8192000000 9924435344 10317770752 990322624 10.785 97 32 64 0 1023744624 26968928 6453072
02:22:56 6669 8192000000 9955351776 10335555584 1021229408 11.084 99 49 96 1 1064454272 32560560 10664304
02:24:03 5008 8192000000 9973837792 10351300608 1041294816 11.277 100 60 117 3 1076650048 21547376 13807856
02:25:11 17588 8192000000 9982069120 10381602816 1058973744 11.447 100 38 75 3 1085726624 18935648 7817232
02:26:32 24150 8192000000 9975614752 10386071552 1060740128 11.464 100 14 28 0 1089043664 24923040 3380496
02:28:10 5964 8192000000 10010231152 10404302848 1077316208 11.622 100 50 96 1 1116795728 28500512 10979008
02:29:19 7993 8192000000 10078112464 10467151872 1092414512 11.766 100 63 122 1 1180312352 75903536 11994304
02:30:32 32097 8192000000 10067827152 10467315712 1106611488 11.900 100 44 88 0 1174807760 59303696 8892576
02:32:18 28145 8192000000 10136221968 10551099392 1128481120 12.107 100 32 64 0 1255748544 120151472 7115952
02:33:56 14569 8192000000 10170341200 10579906560 1143774944 12.251 100 48 93 1 1271589824 117457184 10357696
02:35:14 8765 8192000000 10223833360 10636783616 1151645824 12.325 100 61 121 0 1336565680 171317136 13602720
02:36:28 8491 8192000000 10272926048 10685550592 1169415888 12.491 100 65 127 3 1373969120 190014336 14538896
02:37:40 5869 8192000000 10291082448 10696118272 1171841488 12.514 100 71 142 2 1388954256 201392304 15720464
02:38:51 6802 8192000000 10309823984 10747408384 1180953728 12.599 100 55 109 0 1414354816 221050608 12350480
02:40:06 6525 8192000000 10343444160 10751242240 1190354240 12.687 100 71 142 0 1445356048 239359216 15642592
02:41:18 2356 8192000000 10365306096 10774392832 1180494880 12.595 99 79 156 1 1450867248 253546800 16825568
02:42:25 5840 8192000000 10398470416 10799423488 1178888304 12.580 99 85 166 1 1493350048 296061296 18400448
02:43:34 4161 8192000000 10441777760 10845638656 1191744384 12.700 100 82 163 1 1541715664 331572512 18398768
02:44:44 1692 8192000000 10469004416 10871209984 1167933504 12.478 99 84 165 0 1561431312 374494240 19003568
02:45:52 4028 8192000000 10507813792 10944282624 1178277904 12.574 100 75 150 1 1601240624 404675200 18287520
02:47:03 3262 8192000000 10486100944 10909773824 1158093664 12.385 98 72 144 1 1584980528 409157296 17729568
02:48:12 4059 8192000000 10493316784 10902614016 1162525392 12.427 100 67 133 0 1585033632 406262864 16245376
02:49:20 8183 8192000000 10518108512 10958528512 1169466880 12.492 100 42 84 0 1614034240 433458320 11109040
02:50:33 13680 8192000000 10509784320 10955726848 1160643360 12.409 100 38 77 1 1622132768 451218352 10271056
02:51:58 7777 8192000000 10503944496 10939752448 1153187680 12.339 100 38 76 0 1604884128 441114880 10581568
02:53:10 27303 8192000000 10483139408 10937245696 1133480608 12.154 100 24 48 1 1588747248 448611296 6655344
02:54:47 8224 8192000000 10303564736 10807255040 1133201488 12.152 100 34 68 0 1456214256 314066768 8946000
02:56:03 44788 8192000000 10085371536 10536824832 1105232560 11.887 98 16 32 1 1156619728 47459440 3927728
02:57:48 47216 8192000000 10065620496 10491609088 1128730464 12.109 99 28 54 3 1154601664 20610912 5260288
02:59:57 70809 8192000000 10059716624 10537308160 1075814272 11.608 95 15 30 0 1127372352 48710272 2847808
03:03:07 30583 8192000000 10100390240 10544984064 1105277552 11.888 98 8 17 1 1180409504 73531840 1600112
03:04:50 57551 8192000000 10066116752 10513395712 1124637712 12.071 100 20 40 1 1148118320 19656976 3823632
03:06:49 46324 8192000000 10106025936 10597462016 1096884032 11.808 96 12 24 0 1193208704 93790928 2533744
03:08:37 125297 8192000000 10076054416 10504994816 1118015072 12.008 99 39 78 0 1150749632 24758512 7976048
03:12:00 39641 8192000000 10084594592 10530164736 1127354272 12.096 99 19 38 0 1182229664 51166800 3708592
03:13:41 49749 8192000000 10117538960 10565365760 1132952480 12.149 99 29 57 0 1215207264 75992848 6261936
03:15:33 68164 8192000000 10115807744 10605645824 1120973360 12.036 97 14 28 0 1163391232 39584560 2833312
03:17:41 39324 8192000000 10155698464 10609512448 1157845600 12.383 100 17 34 0 1255222768 93755552 3621616
03:19:31 27103 8192000000 10136252592 10589839360 1147679232 12.288 99 14 28 0 1225023376 73846752 3497392
03:20:58 57046 8192000000 10106818352 10640834560 1153701856 12.344 100 14 28 1 1268093504 110900496 3491152
03:22:57 55648 8192000000 10094652048 10603491328 1144509568 12.258 99 7 14 0 1223025920 77030848 1485504
03:24:53 32530 8192000000 10086351776 10525646848 1123892192 12.064 97 23 46 0 1154650608 26536496 4221920
03:26:27 48026 8192000000 10079945344 10541961216 1116569040 11.995 97 22 44 0 1160641456 39347728 4724688
03:28:15 44058 8192000000 10108183584 10592387072 1123356928 12.059 98 20 40 2 1173079888 45517856 4205104
03:30:16 61549 8192000000 10115678496 10556321792 1072100688 11.572 94 17 34 0 1133787536 57876320 3810528
03:32:46 55740 8192000000 10051519120 10544832512 1073480544 11.585 96 20 40 1 1145822656 68003728 4338384
03:34:43 40885 8192000000 10088675120 10524880896 1116726880 11.996 100 24 48 0 1188818512 67044048 5047584
03:36:31 77686 8192000000 10052507376 10488958976 1113556672 11.966 99 19 38 2 1141412640 23909968 3946000
03:38:52 50681 8192000000 10101553008 10560249856 1114458704 11.975 98 14 28 0 1176926496 59749200 2718592
03:40:43 71452 8192000000 10111504912 10542551040 1055847152 11.417 93 25 49 0 1139578192 78924176 4806864
03:42:56 60467 8192000000 10100951936 10596757504 1086141072 11.706 97 22 44 0 1204281552 113157088 4983392
03:45:10 35385 8192000000 10055676896 10502397952 1106052144 11.895 98 30 60 3 1145807280 32706064 7049072
03:46:52 34178 8192000000 10082468160 10514763776 1111910880 11.951 98 35 70 1 1168048976 49152544 6985552
03:48:30 42034 8192000000 10104428256 10561675264 1122956112 12.055 99 35 70 0 1170754656 41088512 6710032
03:50:59 63906 8192000000 10136586304 10619039744 1128456176 12.107 99 34 67 0 1240891296 105820784 6614336
03:53:05 114509 8192000000 10122596080 10566434816 1098837680 11.827 96 35 70 2 1153693344 47831136 7024528
03:56:54 91642 8192000000 10067354192 10562605056 1127246704 12.095 99 8 16 0 1164512784 35294064 1972016
03:59:27 70796 8192000000 10074005632 10544541696 1094261712 11.783 96 5 10 1 1127720928 32513776 945440
04:01:38 64638 8192000000 10085261152 10542374912 1118533056 12.013 98 19 38 0 1165393680 42732272 4128352
04:03:51 37389 8192000000 10081727632 10524147712 1131549520 12.136 100 21 42 1 1196036656 59821632 4665504
04:05:37 36721 8192000000 10134547936 10571276288 1108572848 11.919 97 30 59 0 1194062736 79224144 6265744
04:07:14 82732 8192000000 10252716608 10765438976 1164409088 12.445 100 26 52 3 1322988016 152698720 5880208
04:09:53 105760 8192000000 10066624016 10540228608 1103849904 11.874 96 11 22 1 1150860464 44991920 2018640
04:12:42 102607 8192000000 10058085536 10528096256 1116042832 11.990 98 35 70 1 1182426384 59361520 7022032
04:15:31 54317 8192000000 10050344144 10525691904 1130279328 12.124 100 3 6 0 1156461568 25695280 486960
04:18:08 70710 8192000000 10105445296 10543652864 1105650832 11.891 97 24 48 0 1164657664 53910608 5096224
04:20:32 61386 8192000000 10129952432 10621906944 1132145920 12.142 100 23 45 1 1253569296 116204016 5219360
04:22:39 69442 8192000000 10065151328 10539417600 1095216496 11.792 97 22 44 0 1165342432 65263680 4862256
04:24:49 51211 8192000000 10087578384 10568609792 1124882208 12.073 99 24 48 0 1181067408 50649392 5535808
04:26:47 20962 8192000000 8995569952 9232838656 142360368 1.708 13 2 3 0 152562752 9890144 312240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-04-2021 04:27:55 to 06-04-2021 04:28:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-04-2021 04:28:10 to 06-04-2021 04:28:12.

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          233862
MON$OLDEST_ACTIVE               233863
MON$OLDEST_SNAPSHOT             233863
MON$NEXT_TRANSACTION            233863
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                       2108992
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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'

06.04.21 04:28:13. 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
2021-04-06 04:28:16 No severe PSQL-related problems occured
06.04.21 04:28:17. End of report.