Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16661980 kB
MemAvailable 31257176 kB
Buffers 0 kB
Cached 14890436 kB
SwapCached 4864 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-01-09 10:26:53
DTS_END                         2021-01-09 10:26:53.8330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33954
TRN_ID                          228044
ATT_ID                          657
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: 8896.29 25419 1067555
customer order: creation 158.88 400 19065
customer order: refuse 13.84 312 1661
order to supplier: creation 42.36 1222 5083
order to supplier: removal 3.38 105672 405
invoice (draft): creation 42.48 3409 5097
invoice (draft): removal 52.08 816 6250
invoice accept: apply 39.26 17845 4711
invoice accept: cancel 46.48 759 5577
customer reserve: creation 3636.48 174 436377
customer reserve: removal 4578.23 61 549388
realization accept: apply 77.02 158 9242
realization accept: cancel 84.53 125 10143
payment from customer: creation 56.11 94 6733
payment from customer: removal 5.00 85 600
payment to supplier: creation 45.93 114 5512
payment to supplier: removal 3.35 113 402
service: total inventory turnovers 4.09 2884 491
service: total monetary turnovers 6.00 489 720
service: aggregate perf. data 0.78 18906 94
service: refresh index statistics 0.03 354741 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-01-2021 10:28:03 to 09-01-2021 10:28:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5682
TEST_TIME 2 4612
TEST_TIME 3 5189
TEST_TIME 4 5911
TEST_TIME 5 5859
TEST_TIME 6 6449
TEST_TIME 7 6358
TEST_TIME 8 5995
TEST_TIME 9 6155
TEST_TIME 10 6298
TEST_TIME 11 6450
TEST_TIME 12 6700
TEST_TIME 13 6738
TEST_TIME 14 6818
TEST_TIME 15 6930
TEST_TIME 16 7040
TEST_TIME 17 7388
TEST_TIME 18 7894
TEST_TIME 19 7962
TEST_TIME 20 7838
TEST_TIME 21 8366
TEST_TIME 22 8384
TEST_TIME 23 8400
TEST_TIME 24 8774
TEST_TIME 25 9865
TEST_TIME 26 10870
TEST_TIME 27 10543
TEST_TIME 28 10256
TEST_TIME 29 10121
TEST_TIME 30 10087
TEST_TIME 31 9897
TEST_TIME 32 9730
TEST_TIME 33 9717
TEST_TIME 34 9613
TEST_TIME 35 9408
TEST_TIME 36 9391
TEST_TIME 37 9393
TEST_TIME 38 9247
TEST_TIME 39 9122
TEST_TIME 40 9216
TEST_TIME 41 9319
TEST_TIME 42 9255
TEST_TIME 43 9281
TEST_TIME 44 9220
TEST_TIME 45 9305
TEST_TIME 46 9257
TEST_TIME 47 9200
TEST_TIME 48 9226
TEST_TIME 49 9335
TEST_TIME 50 9445
TEST_TIME 51 9421
TEST_TIME 52 9396
TEST_TIME 53 9568
TEST_TIME 54 9516
TEST_TIME 55 9548
TEST_TIME 56 9500
TEST_TIME 57 9344
TEST_TIME 58 9308
TEST_TIME 59 9258
TEST_TIME 60 9139
TEST_TIME 61 9126
TEST_TIME 62 9143
TEST_TIME 63 9092
TEST_TIME 64 9046
TEST_TIME 65 9068
TEST_TIME 66 9104
TEST_TIME 67 9220
TEST_TIME 68 9158
TEST_TIME 69 9147
TEST_TIME 70 9180
TEST_TIME 71 9261
TEST_TIME 72 9279
TEST_TIME 73 9279
TEST_TIME 74 9307
TEST_TIME 75 9216
TEST_TIME 76 9117
TEST_TIME 77 9074
TEST_TIME 78 9030
TEST_TIME 79 8960
TEST_TIME 80 8893
TEST_TIME 81 8947
TEST_TIME 82 8915
TEST_TIME 83 8976
TEST_TIME 84 8941
TEST_TIME 85 8951
TEST_TIME 86 8976
TEST_TIME 87 8983
TEST_TIME 88 9028
TEST_TIME 89 9065
TEST_TIME 90 9199
TEST_TIME 91 9239
TEST_TIME 92 9217
TEST_TIME 93 9131
TEST_TIME 94 9080
TEST_TIME 95 9024
TEST_TIME 96 8974
TEST_TIME 97 8951
TEST_TIME 98 8943
TEST_TIME 99 8919
TEST_TIME 100 8898
TEST_TIME 101 8898
TEST_TIME 102 8888
TEST_TIME 103 8912
TEST_TIME 104 8933
TEST_TIME 105 8984
TEST_TIME 106 8962
TEST_TIME 107 8991
TEST_TIME 108 9017
TEST_TIME 109 9071
TEST_TIME 110 9083
TEST_TIME 111 9090
TEST_TIME 112 9134
TEST_TIME 113 9076
TEST_TIME 114 9018
TEST_TIME 115 8991
TEST_TIME 116 8961
TEST_TIME 117 8923
TEST_TIME 118 8900
TEST_TIME 119 8886
TEST_TIME 120 8902

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2021 10:28:07 to 09-01-2021 10:28:10.

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 1470407 1470407 0 0.00 0 14113 6 0 0
doc_list_biud 1470405 1470405 0 0.00 0 54795 29 0 0
sp_add_invoice_to_stock 4845 4711 134 2.76 34 202973 17845 0 134
sp_cancel_adding_invoice 5577 5577 0 0.00 5 70961 759 0 0
sp_cancel_client_order 1661 1661 0 0.00 4 8400 312 0 0
sp_cancel_customer_reserve 549388 549388 0 0.00 1 17888 61 0 0
sp_cancel_pay_from_customer 600 600 0 0.00 2 5230 86 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 9348 113 0 0
sp_cancel_supplier_invoice 6260 6250 10 0.15 2 54800 816 0 10
sp_cancel_supplier_order 405 405 0 0.00 7 1038011 105672 0 0
sp_cancel_write_off 10143 10143 0 0.00 1 13413 126 0 0
sp_client_order 19065 19065 0 0.00 4 20314 401 0 0
sp_customer_reserve 436377 436377 0 0.00 0 41978 175 0 0
sp_fill_shopping_cart 29241 29241 0 0.00 1 13914 42 0 0
sp_fill_shopping_cart_clo_res 436367 436367 0 0.00 0 21757 17 0 0
sp_get_clo_for_invoice 4698 4698 0 0.00 0 56336 530 0 0
sp_kill_cost_storno 16722 16722 0 0.00 0 9340 54 0 0
sp_kill_qstorno_ret_qs2qd 1115574 1115574 0 0.00 0 54793 33 0 0
sp_kill_qty_storno 587359 587359 0 0.00 0 54794 71 0 0
sp_lock_selected_doc 19344 19344 0 0.00 0 10755 6 0 0
sp_make_cost_storno 26180 26180 0 0.00 0 14096 41 0 0
sp_multiply_rows_for_pdistr 26180 26180 0 0.00 0 7911 28 0 0
sp_multiply_rows_for_qdistr 19065 19065 0 0.00 0 19920 220 0 0
sp_pay_from_customer 6733 6733 0 0.00 4 8982 94 0 0
sp_pay_to_supplier 5512 5512 0 0.00 4 9434 114 0 0
sp_payment_common 12245 12245 0 0.00 1 9412 18 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 8368 276 0 0
sp_qd_handle_on_invoice_upd_sts 10270 10270 0 0.00 0 36778 328 0 0
sp_qd_handle_on_reserve_upd_sts 9242 9242 0 0.00 0 13284 40 0 0
sp_reserve_write_off 9242 9242 0 0.00 7 13948 158 0 0
sp_supplier_invoice 5097 5097 0 0.00 7 82547 3410 0 0
sp_supplier_order 5083 5083 0 0.00 6 22381 1223 0 0
srv_aggregate_perf_data 94 94 0 0.00 2348 279937 18906 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1048 130598 51144 0 0
srv_find_qd_qs_mism 1022264 1022264 0 0.00 0 13687 6 0 0
srv_make_invnt_saldo 491 491 0 0.00 55 93788 2884 0 0
srv_make_money_saldo 720 720 0 0.00 1 11962 490 0 0
srv_recalc_idx_stat 4 4 0 0.00 228420 484934 354741 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4711 4711 0 0.00 0 15540 174 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 13991 159 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 577 7 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 2328 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 600 600 0 0.00 0 1662 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 3939 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 403 7 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 27225 1237 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 2850 8 0 0
t$perf-norm:sp_client_order 19065 19065 0 0.00 0 10316 16 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 734 4 0 0
t$perf-norm:sp_pay_from_customer 6733 6733 0 0.00 0 7615 11 0 0
t$perf-norm:sp_pay_to_supplier 5512 5512 0 0.00 0 7596 10 0 0
t$perf-norm:sp_reserve_write_off 9242 9242 0 0.00 0 7801 14 0 0
t$perf-norm:sp_supplier_invoice 5097 5097 0 0.00 0 11718 16 0 0
t$perf-norm:sp_supplier_order 5083 5083 0 0.00 0 13970 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 301 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 250 5 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 3909 15 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 2809 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9652 9384 268 2.77 0 6799 17 0 268
v_all_customers 35262 35262 0 0.00 0 13 1 0 0
v_all_suppliers 28406 28406 0 0.00 0 236 1 0 0
v_all_wares 38130 38130 0 0.00 0 54 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1379 11 0 0
v_cancel_client_order 3322 3322 0 0.00 0 2873 7 0 0
v_cancel_customer_prepayment 1200 1200 0 0.00 0 2892 7 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 2816 6 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2639 16 0 0
v_cancel_supplier_invoice 778 758 20 2.57 0 2595 21 0 20
v_cancel_supplier_order 810 810 0 0.00 0 2961 18 0 0
v_cancel_write_off 1862 1862 0 0.00 0 3416 16 0 0
v_max_id_clo_ord 5083 5083 0 0.00 0 281 1 0 0
v_max_id_clo_res 937 937 0 0.00 0 7135 35 0 0
v_max_id_ord_sup 5093 5093 0 0.00 0 6054 7 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 3960 6 0 0
v_max_non_paid_realizn 3364 3364 0 0.00 0 2580 4 0 0
v_min_id_clo_ord 5083 5083 0 0.00 0 5555 7 0 0
v_min_id_clo_res 937 937 0 0.00 0 1130 3 0 0
v_min_id_ord_sup 5093 5093 0 0.00 0 2584 4 0 0
v_min_non_paid_invoice 5512 5512 0 0.00 0 4682 11 0 0
v_min_non_paid_realizn 6733 6733 0 0.00 0 5214 11 0 0
v_our_firm 9606 9606 0 0.00 0 16 1 0 0
v_reserve_write_off 18484 18484 0 0.00 0 5201 7 0 0
x_lock_depdocs_on_canc_invoice 11448 11448 0 0.00 0 15493 109 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 9666 330 0 0
x_make_qstorno_1000_1200 5083 5083 0 0.00 2 22361 1154 0 0
x_make_qstorno_1200_2000 5097 5097 0 0.00 4 72597 3244 0 0
x_make_qstorno_2100_3300 436377 436377 0 0.00 0 41977 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2021 10:28:11 to 09-01-2021 10:28:15.

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:56:57 6 8192000000 8799915504 8811700224 5559632 0.067 2 2 3 0 5724976 103984 61360
07:57:59 18629 8192000000 9714725424 10006659072 795348544 8.849 100 91 181 0 824774416 7495968 21929904
07:59:28 15170 8192000000 9754930976 10058481664 833244848 9.232 100 87 174 0 864728448 10825808 20657792
08:00:49 16804 8192000000 9791959392 10104778752 856839648 9.469 100 83 166 1 896973904 20840688 19293568
08:02:19 12674 8192000000 9866089280 10196545536 912160736 10.019 100 78 156 1 978142864 48769184 17212944
08:03:35 5482 8192000000 9904436704 10248765440 951983408 10.411 100 74 148 1 1016233600 48207344 16042848
08:04:43 6269 8192000000 9960196176 10313474048 991992352 10.801 100 81 162 0 1069231408 60245824 16993232
08:05:52 2726 8192000000 10007248848 10365837312 1025397840 11.124 100 87 174 0 1119227760 75098960 18730960
08:06:57 1581 8192000000 10009689984 10378866688 1048794576 11.349 100 84 168 0 1121749104 54923824 18030704
08:08:00 10851 8192000000 10041335168 10415763456 1064090768 11.496 100 81 163 1 1152923408 71111584 17721056
08:09:12 5592 8192000000 10001553616 10381471744 1072023072 11.571 100 49 99 0 1112310384 30286944 10000368
08:10:18 6277 8192000000 9987112032 10386878464 1083200752 11.678 100 31 62 1 1120027280 29916032 6910496
08:11:27 4602 8192000000 9988545360 10382000128 1086412528 11.709 100 35 68 2 1110415200 16200256 7802416
08:12:33 31885 8192000000 10017418592 10404847616 1093222528 11.773 100 65 130 0 1119800160 12273248 14304384
08:14:05 8564 8192000000 10002895968 10407395328 1092564144 11.767 100 31 60 2 1117348320 17934928 6849248
08:15:14 5398 8192000000 10024096256 10420523008 1096388128 11.803 100 46 92 5 1136552304 29790256 10373920
08:16:22 70964 8192000000 10005997840 10419179520 1048542320 11.347 96 34 68 0 1080171488 25261648 6367520
08:18:38 8731 8192000000 9973406304 10360561664 1014278640 11.017 95 40 79 0 1044636048 21480928 8876480
08:19:50 7587 8192000000 9939055984 10327343104 1010899632 10.984 98 35 70 2 1038090576 18536464 8654480
08:20:58 75782 8192000000 9926288768 10319839232 947284304 10.364 92 23 46 0 976464912 24310160 4870448
08:23:17 3350 8192000000 9948685168 10324291584 1014107968 11.015 98 58 119 0 1038603152 11615520 12879664
08:24:22 7300 8192000000 9966579024 10359672832 1053791936 11.397 100 56 110 3 1095566608 28858656 12916016
08:25:30 14092 8192000000 9968803488 10366943232 1064826512 11.503 100 20 36 2 1089381856 20281456 4273888
08:26:44 20158 8192000000 10003440240 10404659200 1071186976 11.563 100 28 56 0 1110866352 33987952 5691424
08:28:11 11435 8192000000 10017219616 10411171840 1087258848 11.717 100 46 92 0 1125899824 29274448 9366528
08:29:33 5118 8192000000 10081643344 10481168384 1101890480 11.856 100 55 110 1 1191842608 77611776 12340352
08:30:41 8935 8192000000 10117005936 10515836928 1112607168 11.957 100 61 120 1 1225792176 100623488 12561520
08:31:54 5531 8192000000 10123785392 10517893120 1124140848 12.066 100 68 137 1 1228662608 90714000 13807760
08:33:04 17412 8192000000 10091907648 10516672512 1131453504 12.135 100 32 64 1 1221876016 83399040 7023472
08:34:26 28123 8192000000 10156308608 10566332416 1144681392 12.260 100 51 102 0 1254848992 99386624 10780976
08:36:01 3175 8192000000 10200501520 10605162496 1152454848 12.333 100 66 132 1 1305227136 138108720 14663568
08:37:09 7634 8192000000 10225692192 10640896000 1167469376 12.473 100 56 111 0 1332135936 151411296 13255264
08:38:21 4143 8192000000 10248002304 10668810240 1173673088 12.531 100 64 129 2 1351846512 163691280 14482144
08:39:31 5112 8192000000 10289623616 10708545536 1186489504 12.651 100 64 127 0 1395060032 194226832 14343696
08:40:42 4024 8192000000 10298375008 10704625664 1177117264 12.563 100 74 147 0 1393965568 199605120 17243184
08:41:49 1045 8192000000 10328157008 10729967616 1174422432 12.538 100 82 163 1 1427674704 235310336 17941936
08:42:56 1175 8192000000 10395727504 10788904960 1168250944 12.480 99 89 179 1 1486892432 297577408 21064080
08:44:00 7416 8192000000 10412627376 10830659584 1166423104 12.463 99 57 114 0 1508374016 327441536 14509376
08:45:12 5864 8192000000 10417482544 10847928320 1172444080 12.520 99 54 106 2 1507951792 322437136 13070576
08:46:29 6571 8192000000 10418202864 10836041728 1158640224 12.391 100 48 94 3 1520172576 349959904 11572448
08:47:40 21507 8192000000 10394383728 10853990400 1158028608 12.385 100 29 57 2 1504761168 339262512 7470048
08:49:04 23412 8192000000 10391436944 10833620992 1099055904 11.829 97 32 64 0 1470765568 362607312 9102352
08:50:32 26969 8192000000 10362035280 10835599360 1105299472 11.888 99 30 60 1 1469635488 356027072 8308944
08:52:01 57210 8192000000 10049192864 10557947904 1071229648 11.564 96 12 23 1 1224766000 150055840 3480512
08:54:00 18119 8192000000 10059626880 10488565760 1115410384 11.984 100 29 56 1 1160618592 39328192 5880016
08:55:51 20137 8192000000 10097105424 10534174720 1132664224 12.146 100 27 53 0 1213260032 73990720 6605088
08:57:15 93190 8192000000 10092941760 10552750080 1126902416 12.092 100 12 24 0 1193104176 63231728 2970032
08:59:56 129627 8192000000 10112416192 10539761664 1097602352 11.815 97 34 68 2 1164810176 60535328 6672496
09:03:22 31157 8192000000 10050076656 10531758080 1109968848 11.932 99 14 28 1 1147792032 34974784 2848400
09:04:55 71233 8192000000 10169991440 10620825600 1136260672 12.180 100 26 52 1 1216757168 74690656 5805840
09:07:19 47480 8192000000 10082839408 10696298496 1131596688 12.136 100 8 15 1 1270766160 137128848 2040624
09:09:40 71383 8192000000 10090366080 10531233792 1110117696 11.934 97 22 39 2 1164001648 50194320 3689632
09:11:52 69860 8192000000 10078874784 10525630464 1124193488 12.067 99 20 40 0 1162160448 33652352 4314608
09:14:15 83156 8192000000 10149567296 10642169856 1154540000 12.352 100 15 30 1 1266325488 108351056 3434432
09:16:39 78691 8192000000 10090267184 10546454528 1090105600 11.744 95 15 30 1 1122617808 29782816 2729392
09:18:58 62116 8192000000 10096713280 10590810112 1149184416 12.302 100 25 50 1 1203220464 49022000 5014048
09:21:10 93552 8192000000 10106080560 10592141312 1107258864 11.906 96 23 46 0 1186825360 74896352 4670144
09:23:44 83841 8192000000 10111209568 10585567232 1144831632 12.261 100 26 52 0 1220415104 69864688 5718784
09:26:56 66967 8192000000 10106521008 10607476736 1126348048 12.087 98 7 14 0 1175424240 47420496 1655696
09:29:03 75996 8192000000 10118513872 10591686656 1143075248 12.244 100 17 33 1 1209450112 62983136 3391728
09:31:21 45157 8192000000 10108236560 10570387456 1136109264 12.179 99 20 40 1 1214557952 74279840 4168848
09:33:11 45792 8192000000 10075142224 10558795776 1128023504 12.103 100 13 26 0 1193844512 63232864 2588144
09:34:57 33921 8192000000 10094869664 10531307520 1100136432 11.839 97 28 57 0 1162853808 56920960 5796416
09:36:37 66572 8192000000 10079242240 10508177408 1073255920 11.583 95 19 38 0 1136685152 59641536 3787696
09:38:44 79448 8192000000 10043957152 10489319424 1058419200 11.441 94 15 30 0 1104361520 42885776 3056544
09:41:04 130597 8192000000 10034240176 10486517760 1074097072 11.591 98 20 41 0 1113802528 35610224 4095232
09:44:23 60095 8192000000 10030874032 10494169088 1088910896 11.732 99 5 10 0 1131916016 41995584 1009536
09:46:26 109648 8192000000 10062773232 10570309632 1080352624 11.651 96 15 29 3 1178434416 94921872 3159920
09:49:20 70618 8192000000 10057989168 10486231040 1110431824 11.937 100 20 40 1 1133327440 18677424 4218192
09:51:37 81152 8192000000 10116947904 10569166848 1096434960 11.804 97 25 50 0 1175983184 74019536 5528688
09:54:01 50216 8192000000 10111523824 10587025408 1099237568 11.830 98 17 34 1 1198885408 95719888 3927952
09:55:52 78877 8192000000 10100169536 10622803968 1128811232 12.110 100 27 54 1 1211689184 77219792 5658160
09:58:36 115038 8192000000 10071570800 10512363520 1114377152 11.974 99 17 34 0 1149547072 31903248 3266672
10:01:35 45001 8192000000 10054439184 10505125888 1102932096 11.865 99 7 14 0 1151674896 47267312 1475488
10:03:34 74629 8192000000 10055918064 10563809280 1124104688 12.066 100 10 20 1 1161922928 36023424 1794816
10:05:59 83127 8192000000 10084439072 10537263104 1137035952 12.188 100 20 40 0 1182404672 41068576 4300144
10:08:23 67535 8192000000 10108352832 10572320768 1135768672 12.176 99 21 42 0 1202845744 62965488 4111584
10:10:39 73384 8192000000 10072852352 10525396992 1118164928 12.010 98 16 32 1 1158010640 36773168 3072544
10:13:00 40706 8192000000 10157310896 10578735104 1142245008 12.237 99 43 86 2 1216576032 64261088 10069936
10:14:41 78766 8192000000 10100944176 10589057024 1133264816 12.152 99 18 36 0 1187463024 50983120 3215088
10:17:02 60153 8192000000 10110364064 10573524992 1134626144 12.165 100 23 46 0 1214234960 75033456 4575360
10:19:17 122961 8192000000 10088919776 10593165312 1134648160 12.165 100 13 26 0 1186460208 49007648 2804400
10:22:20 89636 8192000000 10061083136 10527141888 1092625680 11.768 96 8 16 0 1137177664 42926464 1625520
10:25:07 48180 8192000000 10092803968 10546995200 1142308000 12.237 100 16 32 0 1169624784 24209360 3107424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-01-2021 10:28:17 to 09-01-2021 10:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2021 10:28:33 to 09-01-2021 10:28:35.

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          227829
MON$OLDEST_ACTIVE               228272
MON$OLDEST_SNAPSHOT             228272
MON$NEXT_TRANSACTION            228272
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                       2090688
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53


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

09.01.21 10:28:36. 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-01-09 10:28:38 No severe PSQL-related problems occured
09.01.21 10:28:40. End of report.