Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.2021 10: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 16696740 kB
MemAvailable 31423700 kB
Buffers 0 kB
Cached 14927576 kB
SwapCached 0 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-03 10:26:57
DTS_END                         2021-04-03 10:26:57.2840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39454
TRN_ID                          230874
ATT_ID                          635
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: 9034.78 22262 1084174
customer order: creation 170.73 400 20487
customer order: refuse 15.08 372 1809
order to supplier: creation 45.98 1392 5517
order to supplier: removal 3.43 121150 411
invoice (draft): creation 45.31 3929 5437
invoice (draft): removal 52.25 904 6270
invoice accept: apply 40.48 18142 4858
invoice accept: cancel 47.12 979 5654
customer reserve: creation 3722.38 177 446685
customer reserve: removal 4594.38 72 551325
realization accept: apply 81.23 193 9748
realization accept: cancel 88.29 124 10595
payment from customer: creation 59.74 108 7169
payment from customer: removal 5.83 81 699
payment to supplier: creation 48.25 110 5790
payment to supplier: removal 3.42 62 410
service: total inventory turnovers 3.84 3768 461
service: total monetary turnovers 6.03 556 724
service: aggregate perf. data 1.00 12468 120
service: refresh index statistics 0.04 280248 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2021 10:27:46 to 03-04-2021 10:27:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1805
TEST_TIME 2 1749
TEST_TIME 3 2555
TEST_TIME 4 2857
TEST_TIME 5 3423
TEST_TIME 6 3651
TEST_TIME 7 3961
TEST_TIME 8 4060
TEST_TIME 9 4795
TEST_TIME 10 4966
TEST_TIME 11 5386
TEST_TIME 12 5651
TEST_TIME 13 5736
TEST_TIME 14 5787
TEST_TIME 15 5984
TEST_TIME 16 6398
TEST_TIME 17 6498
TEST_TIME 18 6867
TEST_TIME 19 7090
TEST_TIME 20 7283
TEST_TIME 21 7887
TEST_TIME 22 7910
TEST_TIME 23 7907
TEST_TIME 24 8112
TEST_TIME 25 8225
TEST_TIME 26 8737
TEST_TIME 27 8807
TEST_TIME 28 9504
TEST_TIME 29 10389
TEST_TIME 30 10498
TEST_TIME 31 10660
TEST_TIME 32 10548
TEST_TIME 33 10453
TEST_TIME 34 10333
TEST_TIME 35 10150
TEST_TIME 36 9986
TEST_TIME 37 10009
TEST_TIME 38 9863
TEST_TIME 39 9815
TEST_TIME 40 9766
TEST_TIME 41 9679
TEST_TIME 42 9811
TEST_TIME 43 9736
TEST_TIME 44 9613
TEST_TIME 45 9618
TEST_TIME 46 9719
TEST_TIME 47 9696
TEST_TIME 48 9672
TEST_TIME 49 9675
TEST_TIME 50 9621
TEST_TIME 51 9660
TEST_TIME 52 9640
TEST_TIME 53 9781
TEST_TIME 54 9784
TEST_TIME 55 9751
TEST_TIME 56 9857
TEST_TIME 57 9793
TEST_TIME 58 9819
TEST_TIME 59 9821
TEST_TIME 60 9994
TEST_TIME 61 9932
TEST_TIME 62 9813
TEST_TIME 63 9752
TEST_TIME 64 9685
TEST_TIME 65 9627
TEST_TIME 66 9577
TEST_TIME 67 9498
TEST_TIME 68 9398
TEST_TIME 69 9312
TEST_TIME 70 9277
TEST_TIME 71 9283
TEST_TIME 72 9353
TEST_TIME 73 9303
TEST_TIME 74 9277
TEST_TIME 75 9336
TEST_TIME 76 9279
TEST_TIME 77 9360
TEST_TIME 78 9319
TEST_TIME 79 9368
TEST_TIME 80 9385
TEST_TIME 81 9462
TEST_TIME 82 9474
TEST_TIME 83 9528
TEST_TIME 84 9479
TEST_TIME 85 9449
TEST_TIME 86 9410
TEST_TIME 87 9348
TEST_TIME 88 9376
TEST_TIME 89 9313
TEST_TIME 90 9292
TEST_TIME 91 9205
TEST_TIME 92 9204
TEST_TIME 93 9181
TEST_TIME 94 9176
TEST_TIME 95 9161
TEST_TIME 96 9128
TEST_TIME 97 9108
TEST_TIME 98 9176
TEST_TIME 99 9156
TEST_TIME 100 9241
TEST_TIME 101 9229
TEST_TIME 102 9247
TEST_TIME 103 9247
TEST_TIME 104 9319
TEST_TIME 105 9277
TEST_TIME 106 9223
TEST_TIME 107 9176
TEST_TIME 108 9148
TEST_TIME 109 9095
TEST_TIME 110 9065
TEST_TIME 111 9035
TEST_TIME 112 9048
TEST_TIME 113 9020
TEST_TIME 114 9000
TEST_TIME 115 8985
TEST_TIME 116 8998
TEST_TIME 117 9053
TEST_TIME 118 9041
TEST_TIME 119 9014
TEST_TIME 120 9036

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-04-2021 10:27:50 to 03-04-2021 10:27:53.

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 1498514 1498514 0 0.00 0 17193 7 0 0
doc_list_biud 1498513 1498513 0 0.00 0 39958 34 0 0
sp_add_invoice_to_stock 4979 4858 121 2.43 44 208575 18142 0 121
sp_cancel_adding_invoice 5654 5654 0 0.00 6 86712 979 0 0
sp_cancel_client_order 1809 1809 0 0.00 4 12849 372 0 0
sp_cancel_customer_reserve 551325 551325 0 0.00 1 39961 73 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 4140 82 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 1373 62 0 0
sp_cancel_supplier_invoice 6281 6270 11 0.17 2 28313 904 0 11
sp_cancel_supplier_order 411 411 0 0.00 7 1266899 121151 0 0
sp_cancel_write_off 10595 10595 0 0.00 1 18197 125 0 0
sp_client_order 20487 20487 0 0.00 4 18274 400 0 0
sp_customer_reserve 446685 446685 0 0.00 0 182531 177 0 0
sp_fill_shopping_cart 31436 31436 0 0.00 1 15012 42 0 0
sp_fill_shopping_cart_clo_res 446681 446681 0 0.00 0 16572 17 0 0
sp_get_clo_for_invoice 4854 4854 0 0.00 0 31570 367 0 0
sp_kill_cost_storno 17358 17358 0 0.00 0 14504 58 0 0
sp_kill_qstorno_ret_qs2qd 1119926 1119926 0 0.00 0 39425 39 0 0
sp_kill_qty_storno 590665 590665 0 0.00 0 39957 84 0 0
sp_lock_selected_doc 20386 20386 0 0.00 0 10522 6 0 0
sp_make_cost_storno 27560 27560 0 0.00 0 14926 56 0 0
sp_multiply_rows_for_pdistr 27560 27560 0 0.00 0 10564 29 0 0
sp_multiply_rows_for_qdistr 20487 20487 0 0.00 0 17442 217 0 0
sp_pay_from_customer 7169 7169 0 0.00 4 18632 109 0 0
sp_pay_to_supplier 5790 5790 0 0.00 5 13046 111 0 0
sp_payment_common 12959 12959 0 0.00 1 7448 15 0 0
sp_qd_handle_on_cancel_clo 1809 1809 0 0.00 0 12843 344 0 0
sp_qd_handle_on_invoice_upd_sts 10508 10508 0 0.00 0 39290 417 0 0
sp_qd_handle_on_reserve_upd_sts 9747 9747 0 0.00 0 27072 58 0 0
sp_reserve_write_off 9748 9748 0 0.00 7 27093 193 0 0
sp_supplier_invoice 5437 5437 0 0.00 9 129810 3930 0 0
sp_supplier_order 5517 5517 0 0.00 5 120369 1393 0 0
srv_aggregate_perf_data 120 120 0 0.00 2566 129235 12469 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1203 189886 48624 0 0
srv_find_qd_qs_mism 1037557 1037557 0 0.00 0 17192 6 0 0
srv_make_invnt_saldo 461 461 0 0.00 36 101652 3769 0 0
srv_make_money_saldo 724 724 0 0.00 1 13878 556 0 0
srv_recalc_idx_stat 5 5 0 0.00 194596 371255 280248 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4858 4858 0 0.00 0 10365 178 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 2612 98 0 0
t$perf-norm:sp_cancel_client_order 1809 1809 0 0.00 0 9258 19 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 1033 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 3423 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 289 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 1320 19 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 32450 1774 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1809 7 0 0
t$perf-norm:sp_client_order 20487 20487 0 0.00 0 5310 19 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 1877 7 0 0
t$perf-norm:sp_pay_from_customer 7169 7169 0 0.00 0 3594 8 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 5514 13 0 0
t$perf-norm:sp_reserve_write_off 9748 9748 0 0.00 0 4198 9 0 0
t$perf-norm:sp_supplier_invoice 5437 5437 0 0.00 0 5877 20 0 0
t$perf-norm:sp_supplier_order 5517 5517 0 0.00 0 4337 18 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 427 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3260 76 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 1309 18 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 1621 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9950 9708 242 2.43 0 6787 18 0 242
v_all_customers 37756 37756 0 0.00 0 59 1 0 0
v_all_suppliers 30330 30330 0 0.00 0 105 1 0 0
v_all_wares 40972 40972 0 0.00 0 107 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 838 7 0 0
v_cancel_client_order 3618 3618 0 0.00 0 7164 7 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 4134 10 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 4450 10 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 638 4 0 0
v_cancel_supplier_invoice 920 898 22 2.39 0 266 13 0 22
v_cancel_supplier_order 822 822 0 0.00 0 712 3 0 0
v_cancel_write_off 1940 1940 0 0.00 0 7062 17 0 0
v_max_id_clo_ord 5515 5515 0 0.00 0 4426 3 0 0
v_max_id_clo_res 996 996 0 0.00 0 8963 26 0 0
v_max_id_ord_sup 5435 5435 0 0.00 0 5072 4 0 0
v_max_non_paid_invoice 1574 1574 0 0.00 0 1029 2 0 0
v_max_non_paid_realizn 3677 3677 0 0.00 0 4427 4 0 0
v_min_id_clo_ord 5515 5515 0 0.00 0 2495 5 0 0
v_min_id_clo_res 996 996 0 0.00 0 5776 10 0 0
v_min_id_ord_sup 5435 5435 0 0.00 0 2097 4 0 0
v_min_non_paid_invoice 5790 5790 0 0.00 0 1540 6 0 0
v_min_non_paid_realizn 7169 7169 0 0.00 0 3127 7 0 0
v_our_firm 10200 10200 0 0.00 0 17 1 0 0
v_reserve_write_off 19494 19494 0 0.00 0 7538 7 0 0
x_lock_depdocs_on_canc_invoice 11475 11475 0 0.00 0 18533 114 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 18106 401 0 0
x_make_qstorno_1000_1200 5517 5517 0 0.00 2 118693 1313 0 0
x_make_qstorno_1200_2000 5437 5437 0 0.00 4 129803 3769 0 0
x_make_qstorno_2100_3300 446685 446685 0 0.00 0 182529 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2021 10:27:54 to 03-04-2021 10:27:58.

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
07:57:01 7 8192000000 8798554368 8809799680 4481232 0.054 2 1 2 0 4581184 64176 35776
07:58:01 27158 8192000000 9715747552 10013872128 795639088 8.852 100 82 163 1 827734880 11729904 20365888
07:59:36 21364 8192000000 9771976992 10080690176 842022112 9.320 100 91 182 0 875791824 11866960 21902752
08:01:01 19351 8192000000 9808281232 10128211968 874645104 9.646 100 85 170 0 913906240 19930688 19330448
08:02:25 13669 8192000000 9860550112 10191503360 915773280 10.054 100 83 166 0 980056496 46279344 18003872
08:03:42 7010 8192000000 9922967440 10260320256 946406320 10.356 100 85 169 0 1032304544 67918192 17980032
08:04:56 8558 8192000000 9947439152 10302681088 989570976 10.777 100 67 134 4 1066394192 62648288 14174928
08:06:09 3170 8192000000 9978914896 10340691968 1017881904 11.052 100 74 148 1 1090881152 58125536 14873712
08:07:14 3782 8192000000 10009504080 10374950912 1042452336 11.288 100 83 163 0 1121717712 62772240 16493136
08:08:20 1374 8192000000 10018747312 10385436672 1051935232 11.379 100 89 179 1 1132698784 63655072 17108480
08:09:25 8455 8192000000 10027157648 10402828288 1066876304 11.522 100 82 162 2 1141714576 58859168 15979104
08:10:36 2238 8192000000 10020892752 10403438592 1071688048 11.568 100 62 122 2 1139386880 54843888 12854944
08:11:40 3641 8192000000 9986407728 10376749056 1079174496 11.640 100 43 81 8 1102790192 14434128 9181568
08:12:45 65625 8192000000 10008182272 10397556736 1080961760 11.657 100 37 73 0 1111194480 22529216 7703504
08:14:52 6825 8192000000 9993827136 10395734016 1093642544 11.777 100 41 78 2 1119027296 16136672 9248080
08:16:02 7063 8192000000 10002440592 10413551616 1093364752 11.775 100 34 69 0 1127273616 26314560 7594304
08:17:11 15763 8192000000 9977474400 10372911104 1043474864 11.298 96 31 61 2 1077858592 28160976 6222752
08:18:28 10587 8192000000 10012290976 10395914240 1082960288 11.676 100 82 166 0 1117122608 15411552 18750768
08:19:45 8355 8192000000 9989679168 10390175744 1060934336 11.465 99 28 55 3 1095947600 29016448 5996816
08:20:58 4464 8192000000 9948419184 10333503488 1000402208 10.882 96 38 77 2 1030196624 21921136 7873280
08:22:03 60357 8192000000 9920533872 10300051456 974025792 10.626 95 30 60 0 997868608 17386688 6456128
08:24:04 8843 8192000000 9924811104 10305302528 987114368 10.753 96 39 75 0 1016428032 20405744 8907920
08:25:15 4674 8192000000 9979276944 10369331200 1040817824 11.273 100 67 133 3 1081735376 26301296 14616256
08:26:25 13254 8192000000 9971270096 10375876608 1059829152 11.455 100 24 48 1 1095648032 30366960 5451920
08:27:43 113712 8192000000 10016243040 10427285504 1091016112 11.752 100 29 58 2 1134547008 37284112 6246784
08:30:41 61672 8192000000 10101859552 10521272320 1117564672 12.004 100 38 75 1 1206212736 80410272 8237792
08:32:48 10363 8192000000 10152007472 10553610240 1137847840 12.195 100 45 90 1 1252057216 104802976 9406400
08:34:04 18830 8192000000 10188801712 10600710144 1147724752 12.288 100 71 136 1 1296761392 134203984 14832656
08:35:32 7646 8192000000 10204766160 10641862656 1158752528 12.392 100 59 118 1 1322684768 150573344 13358896
08:36:45 3851 8192000000 10245731152 10643935232 1160811200 12.411 100 79 158 2 1342167472 164327280 17028992
08:37:53 2922 8192000000 10255268080 10680274944 1153335648 12.341 99 72 144 1 1365152736 196598016 15219072
08:39:01 2787 8192000000 10294593136 10697977856 1175356720 12.547 100 77 150 3 1393420336 201489040 16574576
08:40:13 4731 8192000000 10305785088 10722004992 1178721168 12.578 100 57 111 1 1410245200 218661488 12862544
08:41:22 1201 8192000000 10346714640 10756784128 1192370528 12.705 100 79 154 3 1447348656 237997632 16980496
08:42:28 3441 8192000000 10375074432 10782646272 1192348000 12.705 100 81 163 0 1474460800 263614944 18497856
08:43:37 1898 8192000000 10404177120 10808512512 1189754224 12.681 100 81 160 0 1496355056 288738384 17862448
08:44:45 2804 8192000000 10424113216 10830295040 1201523648 12.790 100 84 168 0 1520005280 299093216 19388416
08:45:52 4119 8192000000 10446997264 10862141440 1179453712 12.585 99 78 158 0 1539345680 341785792 18106176
08:47:02 9202 8192000000 10425173344 10871488512 1188715744 12.671 100 54 108 0 1535878880 334096112 13067024
08:48:17 10634 8192000000 10393292352 10821758976 1180524528 12.595 100 53 103 0 1490467552 296864688 13078336
08:49:35 7660 8192000000 10446002768 10878849024 1172451936 12.520 99 43 86 2 1537424672 353549056 11423680
08:50:54 15645 8192000000 10446651152 10901213184 1177848688 12.570 100 43 86 0 1556942800 368360304 10733808
08:52:20 16339 8192000000 10420109280 10902687744 1170724544 12.504 100 36 72 0 1549657136 369397104 9535488
08:53:50 29379 8192000000 10328438064 10808713216 1120240832 12.029 99 26 51 0 1460750656 333312048 7197776
08:55:22 22049 8192000000 10081092240 10546003968 1098578416 11.824 97 15 30 0 1191288064 89285360 3424288
08:56:46 50863 8192000000 10073109584 10585583616 1133732832 12.157 100 10 20 0 1210074752 73937648 2404272
08:58:40 62611 8192000000 10053140944 10508230656 1103303568 11.869 98 14 27 4 1135558032 29739520 2514944
09:00:56 81157 8192000000 10049854032 10479620096 1114181200 11.972 100 29 58 1 1161152896 40780464 6191232
09:03:21 179473 8192000000 10081149232 10513379328 1048865584 11.350 92 22 42 3 1094535664 40863296 4806784
09:07:23 8373 8192000000 10096259072 10518077440 1111500960 11.947 99 60 117 1 1196338688 72556864 12280864
09:08:36 189886 8192000000 10122615936 10592940032 1114651072 11.976 98 15 30 1 1232237424 114195808 3390544
09:12:49 18914 8192000000 10073178896 10555944960 1124695680 12.071 100 2 4 0 1186474848 61403584 375584
09:14:08 67946 8192000000 10076022576 10506874880 1107261312 11.906 98 24 48 2 1158532656 46572448 4698896
09:16:17 53156 8192000000 10106116800 10544553984 1116784736 11.997 99 14 28 0 1193690704 73916016 2989952
09:18:15 55749 8192000000 10120027072 10581012480 1123506464 12.060 99 20 40 3 1212190496 83865104 4818928
09:20:15 65761 8192000000 10096652736 10518364160 1131173216 12.132 100 13 26 0 1168312576 34642560 2496800
09:22:21 86522 8192000000 10106226640 10552438784 1116912368 11.998 98 17 34 0 1155369264 35354368 3102528
09:24:51 54259 8192000000 10168483536 10648895488 1123626096 12.061 98 11 22 1 1260198864 134145392 2427376
09:26:49 87715 8192000000 10086816528 10590638080 1134910576 12.168 100 12 24 0 1191757056 53873280 2973200
09:29:28 46586 8192000000 10045932528 10498576384 1089083264 11.734 96 22 44 1 1137663872 44159328 4421280
09:31:15 42727 8192000000 10037648768 10503761920 1113958576 11.970 99 6 12 1 1141426064 25731840 1735648
09:32:58 60626 8192000000 10050603392 10496114688 1088996832 11.733 96 15 30 0 1129263904 37055392 3211680
09:35:46 60190 8192000000 10130286832 10594267136 1127977216 12.102 99 20 40 0 1234817600 102490128 4350256
09:37:46 70120 8192000000 10059264112 10576781312 1103988128 11.875 98 15 30 1 1192809200 85701248 3119824
09:39:57 64912 8192000000 10086764240 10549006336 1103033024 11.866 97 19 38 0 1185457936 78155472 4269440
09:42:10 27324 8192000000 10092051792 10523557888 1128286192 12.105 99 48 96 1 1168532176 29908304 10337680
09:43:40 47519 8192000000 10084812416 10569383936 1105880640 11.893 98 29 58 0 1248953808 136908688 6164480
09:45:38 46452 8192000000 10110919184 10564632576 1134280560 12.162 100 24 48 1 1203248528 63949200 5018768
09:47:32 97867 8192000000 10096458944 10530594816 1138228976 12.199 100 22 43 0 1185443120 42442928 4771216
09:50:15 48625 8192000000 10123673856 10560929792 1103227856 11.868 96 16 32 0 1152217520 45871184 3118480
09:52:08 96335 8192000000 10118398768 10565861376 1143097616 12.245 100 35 70 0 1229575136 78756768 7720752
09:55:08 56928 8192000000 10066621408 10524524544 1105164832 11.887 97 9 18 0 1142650128 35309040 2176256
09:57:11 32156 8192000000 10053831744 10512121856 1115435120 11.984 98 7 14 0 1153734848 36125824 2173904
09:58:52 46402 8192000000 10041432160 10493108224 1094919136 11.789 97 14 28 3 1129945840 31792352 3234352
10:00:39 67455 8192000000 10069693968 10524934144 1088798624 11.731 96 15 30 1 1132050656 39725776 3526256
10:02:47 65571 8192000000 10132168592 10567712768 1135036432 12.169 99 26 52 0 1195611184 55185088 5389664
10:04:54 47434 8192000000 10116153776 10569138176 1114199056 11.972 97 24 48 1 1199114704 80188560 4727088
10:06:45 57156 8192000000 10073730928 10514735104 1129428992 12.116 99 17 34 0 1183535296 50237792 3868512
10:08:43 79845 8192000000 10134660416 10617212928 1098042304 11.819 96 19 38 2 1167261040 65209184 4009552
10:11:12 38365 8192000000 10100749600 10535383040 1127177504 12.095 100 29 56 1 1172140528 39346288 5616736
10:12:55 128704 8192000000 10054581472 10528489472 1120363520 12.030 99 15 30 0 1168714720 44850016 3501184
10:16:07 59088 8192000000 10073915936 10553282560 1135969968 12.178 100 5 10 0 1174922000 38061520 890512
10:18:16 73766 8192000000 10086810496 10528022528 1121575360 12.042 98 20 40 1 1178842416 52409088 4857968
10:20:32 33110 8192000000 10121612832 10566795264 1127376144 12.097 99 16 32 1 1205784016 74549056 3858816
10:22:07 63927 8192000000 10080700192 10593099776 1123979744 12.065 99 18 36 1 1247172512 118683856 4508912
10:24:13 42397 8192000000 10072928336 10514128896 1120082352 12.028 99 16 32 1 1168775184 45463168 3229664
10:26:10 48364 8192000000 9982043616 10442166272 1022180832 11.093 90 21 41 2 1098414992 71953088 4281072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          228991
MON$OLDEST_ACTIVE               231213
MON$OLDEST_SNAPSHOT             231213
MON$NEXT_TRANSACTION            231213
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                       2102784
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2401 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.2401 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'

03.04.21 10:28:19. 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-03 10:28:21 No severe PSQL-related problems occured
03.04.21 10:28:23. End of report.