Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.04.2021 13: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 16750500 kB
MemAvailable 31406788 kB
Buffers 0 kB
Cached 14866972 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-04 13:26:57
DTS_END                         2021-04-04 13:26:57.9200
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/57106
TRN_ID                          231714
ATT_ID                          616
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: 8784.58 25129 1054150
customer order: creation 165.05 413 19806
customer order: refuse 14.46 317 1735
order to supplier: creation 43.63 1295 5236
order to supplier: removal 3.25 113224 390
invoice (draft): creation 43.41 3429 5209
invoice (draft): removal 50.88 888 6105
invoice accept: apply 39.72 16671 4766
invoice accept: cancel 45.78 815 5493
customer reserve: creation 3616.56 164 433987
customer reserve: removal 4474.79 66 536975
realization accept: apply 81.05 152 9726
realization accept: cancel 82.68 120 9921
payment from customer: creation 57.48 105 6897
payment from customer: removal 4.88 95 585
payment to supplier: creation 46.83 121 5619
payment to supplier: removal 3.26 69 391
service: total inventory turnovers 4.08 2968 490
service: total monetary turnovers 5.79 482 695
service: aggregate perf. data 0.99 13550 119
service: refresh index statistics 0.04 347640 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-04-2021 13:28:13 to 04-04-2021 13:28:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3429
TEST_TIME 2 3403
TEST_TIME 3 4306
TEST_TIME 4 4007
TEST_TIME 5 3795
TEST_TIME 6 4519
TEST_TIME 7 5070
TEST_TIME 8 5242
TEST_TIME 9 5516
TEST_TIME 10 5780
TEST_TIME 11 5745
TEST_TIME 12 5876
TEST_TIME 13 6263
TEST_TIME 14 6292
TEST_TIME 15 6458
TEST_TIME 16 6671
TEST_TIME 17 6916
TEST_TIME 18 7595
TEST_TIME 19 7676
TEST_TIME 20 8223
TEST_TIME 21 8279
TEST_TIME 22 8611
TEST_TIME 23 8783
TEST_TIME 24 9561
TEST_TIME 25 9564
TEST_TIME 26 9949
TEST_TIME 27 10797
TEST_TIME 28 10584
TEST_TIME 29 10236
TEST_TIME 30 9981
TEST_TIME 31 9928
TEST_TIME 32 9952
TEST_TIME 33 9825
TEST_TIME 34 9715
TEST_TIME 35 9555
TEST_TIME 36 9411
TEST_TIME 37 9322
TEST_TIME 38 9320
TEST_TIME 39 9301
TEST_TIME 40 9226
TEST_TIME 41 9171
TEST_TIME 42 9218
TEST_TIME 43 9258
TEST_TIME 44 9278
TEST_TIME 45 9216
TEST_TIME 46 9140
TEST_TIME 47 9340
TEST_TIME 48 9467
TEST_TIME 49 9413
TEST_TIME 50 9477
TEST_TIME 51 9405
TEST_TIME 52 9302
TEST_TIME 53 9128
TEST_TIME 54 9300
TEST_TIME 55 9301
TEST_TIME 56 9224
TEST_TIME 57 9141
TEST_TIME 58 9107
TEST_TIME 59 9111
TEST_TIME 60 9114
TEST_TIME 61 9183
TEST_TIME 62 9190
TEST_TIME 63 9165
TEST_TIME 64 9185
TEST_TIME 65 9299
TEST_TIME 66 9292
TEST_TIME 67 9385
TEST_TIME 68 9334
TEST_TIME 69 9415
TEST_TIME 70 9399
TEST_TIME 71 9306
TEST_TIME 72 9224
TEST_TIME 73 9156
TEST_TIME 74 9136
TEST_TIME 75 9071
TEST_TIME 76 9001
TEST_TIME 77 8995
TEST_TIME 78 8951
TEST_TIME 79 8897
TEST_TIME 80 8931
TEST_TIME 81 8948
TEST_TIME 82 8941
TEST_TIME 83 8913
TEST_TIME 84 8917
TEST_TIME 85 8953
TEST_TIME 86 8917
TEST_TIME 87 8955
TEST_TIME 88 8940
TEST_TIME 89 9010
TEST_TIME 90 9039
TEST_TIME 91 8947
TEST_TIME 92 8882
TEST_TIME 93 8936
TEST_TIME 94 8902
TEST_TIME 95 8867
TEST_TIME 96 8852
TEST_TIME 97 8842
TEST_TIME 98 8914
TEST_TIME 99 8875
TEST_TIME 100 8906
TEST_TIME 101 8877
TEST_TIME 102 8846
TEST_TIME 103 8902
TEST_TIME 104 8937
TEST_TIME 105 9010
TEST_TIME 106 9024
TEST_TIME 107 9080
TEST_TIME 108 9086
TEST_TIME 109 9048
TEST_TIME 110 8977
TEST_TIME 111 8930
TEST_TIME 112 8882
TEST_TIME 113 8831
TEST_TIME 114 8792
TEST_TIME 115 8807
TEST_TIME 116 8786
TEST_TIME 117 8769
TEST_TIME 118 8756
TEST_TIME 119 8760
TEST_TIME 120 8787

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-04-2021 13:28:16 to 04-04-2021 13:28:20.

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 1456777 1456777 0 0.00 0 20698 6 0 0
doc_list_biud 1456777 1456777 0 0.00 0 51870 31 0 0
sp_add_invoice_to_stock 4863 4766 97 1.99 15 182670 16671 0 97
sp_cancel_adding_invoice 5493 5493 0 0.00 4 77506 815 0 0
sp_cancel_client_order 1735 1735 0 0.00 5 11239 318 0 0
sp_cancel_customer_reserve 536975 536975 0 0.00 1 48426 66 0 0
sp_cancel_pay_from_customer 585 585 0 0.00 3 7475 96 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 5972 69 0 0
sp_cancel_supplier_invoice 6109 6105 4 0.06 2 51873 888 0 4
sp_cancel_supplier_order 390 390 0 0.00 9 1041896 113224 0 0
sp_cancel_write_off 9921 9921 0 0.00 1 18340 121 0 0
sp_client_order 19806 19806 0 0.00 4 17629 414 0 0
sp_customer_reserve 433987 433987 0 0.00 0 113080 165 0 0
sp_fill_shopping_cart 30243 30243 0 0.00 1 35164 43 0 0
sp_fill_shopping_cart_clo_res 433986 433986 0 0.00 0 22548 18 0 0
sp_get_clo_for_invoice 4762 4762 0 0.00 0 41075 450 0 0
sp_kill_cost_storno 16390 16390 0 0.00 0 20683 56 0 0
sp_kill_qstorno_ret_qs2qd 1090366 1090366 0 0.00 0 51868 36 0 0
sp_kill_qty_storno 575107 575107 0 0.00 0 51869 77 0 0
sp_lock_selected_doc 20069 20069 0 0.00 0 8826 5 0 0
sp_make_cost_storno 27004 27004 0 0.00 0 18695 51 0 0
sp_multiply_rows_for_pdistr 27004 27004 0 0.00 0 10881 28 0 0
sp_multiply_rows_for_qdistr 19806 19806 0 0.00 0 16260 256 0 0
sp_pay_from_customer 6897 6897 0 0.00 4 16753 105 0 0
sp_pay_to_supplier 5619 5619 0 0.00 5 18724 121 0 0
sp_payment_common 12516 12516 0 0.00 1 10907 19 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 8453 289 0 0
sp_qd_handle_on_invoice_upd_sts 10255 10255 0 0.00 0 37292 356 0 0
sp_qd_handle_on_reserve_upd_sts 9726 9726 0 0.00 0 14692 45 0 0
sp_reserve_write_off 9726 9726 0 0.00 7 19444 152 0 0
sp_supplier_invoice 5209 5209 0 0.00 7 107493 3430 0 0
sp_supplier_order 5236 5236 0 0.00 5 40366 1296 0 0
srv_aggregate_perf_data 119 119 0 0.00 2290 132269 13551 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2278 148924 48851 0 0
srv_find_qd_qs_mism 1009200 1009200 0 0.00 0 14379 5 0 0
srv_make_invnt_saldo 490 490 0 0.00 51 110558 2968 0 0
srv_make_money_saldo 695 695 0 0.00 1 14887 483 0 0
srv_recalc_idx_stat 5 5 0 0.00 275967 503606 347641 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4766 4766 0 0.00 0 16309 156 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 3119 72 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 9272 17 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 1435 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 585 585 0 0.00 0 170 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 1932 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 490 9 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 19317 1524 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 675 4 0 0
t$perf-norm:sp_client_order 19806 19806 0 0.00 0 9496 17 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 2689 12 0 0
t$perf-norm:sp_pay_from_customer 6897 6897 0 0.00 0 5582 8 0 0
t$perf-norm:sp_pay_to_supplier 5619 5619 0 0.00 0 4035 7 0 0
t$perf-norm:sp_reserve_write_off 9726 9726 0 0.00 0 7622 8 0 0
t$perf-norm:sp_supplier_invoice 5209 5209 0 0.00 0 10202 14 0 0
t$perf-norm:sp_supplier_order 5236 5236 0 0.00 0 6866 16 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 231 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 3644 66 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 894 8 0 0
t$perf-norm:srv_make_money_saldo 695 695 0 0.00 0 409 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 18 6 0 0
v_add_invoice_to_stock 9716 9522 194 1.99 0 6184 14 0 194
v_all_customers 36154 36154 0 0.00 0 12 1 0 0
v_all_suppliers 28766 28766 0 0.00 0 55 1 0 0
v_all_wares 39612 39612 0 0.00 0 57 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1156 7 0 0
v_cancel_client_order 3470 3470 0 0.00 0 7348 5 0 0
v_cancel_customer_prepayment 1170 1170 0 0.00 0 1237 7 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 689 5 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 585 5 0 0
v_cancel_supplier_invoice 768 760 8 1.04 0 816 12 0 8
v_cancel_supplier_order 780 780 0 0.00 0 7723 24 0 0
v_cancel_write_off 1970 1970 0 0.00 0 840 12 0 0
v_max_id_clo_ord 5236 5236 0 0.00 0 87 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 3187 13 0 0
v_max_id_ord_sup 5201 5201 0 0.00 0 10063 8 0 0
v_max_non_paid_invoice 1673 1673 0 0.00 0 339 2 0 0
v_max_non_paid_realizn 3598 3598 0 0.00 0 10904 5 0 0
v_min_id_clo_ord 5236 5236 0 0.00 0 4963 5 0 0
v_min_id_clo_res 981 981 0 0.00 0 1860 8 0 0
v_min_id_ord_sup 5201 5201 0 0.00 0 6252 8 0 0
v_min_non_paid_invoice 5619 5619 0 0.00 0 6122 12 0 0
v_min_non_paid_realizn 6897 6897 0 0.00 0 5469 11 0 0
v_our_firm 10056 10056 0 0.00 0 17 1 0 0
v_reserve_write_off 19452 19452 0 0.00 0 3667 4 0 0
x_lock_depdocs_on_canc_invoice 11218 11218 0 0.00 0 26084 109 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 24638 384 0 0
x_make_qstorno_1000_1200 5236 5236 0 0.00 2 40347 1230 0 0
x_make_qstorno_1200_2000 5209 5209 0 0.00 4 107437 3250 0 0
x_make_qstorno_2100_3300 433987 433987 0 0.00 0 113078 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-04-2021 13:28:20 to 04-04-2021 13:28:24.

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
10:57:01 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 31929 8192000000 9727071024 10023690240 806825456 8.965 100 92 183 1 836864928 7707200 22332272
10:59:40 15764 8192000000 9762739312 10066219008 837571248 9.275 100 88 176 0 872118400 13338896 21208256
11:01:05 18428 8192000000 9815138912 10136289280 872316224 9.623 100 81 162 0 925019392 34262672 18440496
11:02:39 10886 8192000000 9875024208 10213953536 933285456 10.227 100 79 158 0 991696768 41765296 16646016
11:03:53 11708 8192000000 9933214064 10282659840 967150656 10.559 100 76 152 0 1043696576 60331520 16214400
11:05:07 4176 8192000000 9982928976 10335506432 1000761680 10.886 100 84 167 1 1101412928 83822096 16829152
11:06:12 2623 8192000000 9995347504 10358386688 1028577168 11.155 100 86 173 0 1113658624 67695200 17386256
11:07:17 2025 8192000000 10031268928 10398404608 1057584992 11.433 100 88 177 2 1133088592 57944912 17558688
11:08:20 25284 8192000000 10013754352 10418262016 1066754192 11.521 100 56 112 3 1141474640 62948544 11771904
11:09:48 3161 8192000000 9991555872 10374443008 1077166736 11.620 100 46 91 2 1102150160 14325248 10658176
11:10:52 3998 8192000000 9999011232 10391080960 1081965392 11.666 100 40 80 2 1108130256 17470800 8694064
11:12:00 4776 8192000000 10002322320 10400436224 1088136560 11.725 100 36 71 1 1122378752 25871152 8371040
11:13:08 56196 8192000000 10020394032 10407636992 1094465840 11.785 100 59 118 1 1122120976 14459984 13195152
11:15:05 5963 8192000000 10032714224 10431299584 1102459232 11.861 100 52 105 1 1159822976 46464272 10899472
11:16:15 5311 8192000000 10022101312 10421215232 1102338096 11.860 100 55 108 1 1138610720 23643072 12629552
11:17:24 6444 8192000000 9991470528 10389782528 1068373136 11.537 98 24 48 0 1090090224 16328896 5388192
11:18:31 44715 8192000000 9967618736 10382577664 987494064 10.757 91 30 58 3 1010106016 15775744 6836208
11:20:16 10042 8192000000 9950333600 10344386560 1020146992 11.073 98 37 73 3 1047100336 18073792 8879552
11:21:28 6143 8192000000 9931290224 10317766656 991425504 10.795 96 35 70 0 1011652384 12874208 7352672
11:22:38 10356 8192000000 9956880864 10352033792 1024981120 11.120 99 17 34 0 1049269968 19908432 4380416
11:23:49 40211 8192000000 9978550736 10392510464 1050039536 11.361 100 29 57 1 1082718240 26146176 6532528
11:25:34 4661 8192000000 10002708256 10389618688 1067455136 11.528 100 53 106 2 1120815328 41672432 11687760
11:26:41 6116 8192000000 9992292416 10391388160 1079490208 11.643 100 50 98 2 1102713984 11899472 11324304
11:27:58 40069 8192000000 10061070256 10459877376 1110604640 11.938 100 47 93 2 1161126144 41373520 9147984
11:29:40 83786 8192000000 10064864848 10476740608 1118697600 12.015 100 25 49 2 1177408272 53163840 5546832
11:32:14 16246 8192000000 10136370992 10548027392 1140445664 12.220 100 52 103 0 1245943488 94960688 10537136
11:33:34 2751 8192000000 10140194880 10561896448 1149225504 12.302 100 53 106 1 1245579248 84758160 11595584
11:34:41 9323 8192000000 10209074112 10614120448 1163402000 12.435 100 75 148 2 1316250688 137078192 15770496
11:35:54 5117 8192000000 10226718704 10645925888 1175262768 12.546 100 60 120 2 1323506896 134864816 13379312
11:37:03 2765 8192000000 10248901776 10656710656 1177072976 12.563 100 72 144 4 1350244848 157212960 15958912
11:38:11 6123 8192000000 10315560992 10721566720 1176639504 12.559 100 75 148 1 1410509648 217158720 16711424
11:39:29 6853 8192000000 10309509056 10758545408 1187074432 12.656 100 56 112 0 1421179088 220719296 13385360
11:40:42 3759 8192000000 10330188672 10741796864 1188620800 12.671 100 80 161 1 1430711408 222833264 19257344
11:41:50 9093 8192000000 10360614144 10776985600 1183620080 12.624 100 71 141 0 1468150080 267967632 16562368
11:43:03 2644 8192000000 10352721984 10758750208 1155494544 12.361 100 69 138 2 1452908976 280554608 16859824
11:44:10 2275 8192000000 10379131216 10798215168 1153089616 12.338 99 80 156 1 1484020896 312578720 18352560
11:45:17 3804 8192000000 10345147632 10763169792 1167156528 12.470 100 59 117 1 1447530768 266873392 13500848
11:46:23 4876 8192000000 10358537984 10772537344 1167028032 12.469 99 67 129 2 1457028448 273819584 16180832
11:47:33 12095 8192000000 10393657632 10834534400 1160570928 12.409 100 40 80 1 1495049456 323812864 10665664
11:48:56 16515 8192000000 10393111536 10822037504 1150663872 12.316 100 43 86 0 1511852288 350044176 11144240
11:50:30 18213 8192000000 10317681648 10769305600 1138815408 12.204 100 23 46 0 1421464320 276884704 5764208
11:51:55 39679 8192000000 10199884704 10640347136 1051426192 11.374 95 11 22 0 1277017920 222474272 3117456
11:53:35 29111 8192000000 10084408112 10557071360 1113413872 11.965 99 12 24 0 1161078176 44920976 2743328
11:55:07 117368 8192000000 10128116400 10563801088 1108927440 11.922 98 39 79 0 1187528240 70177504 8423296
11:58:17 123203 8192000000 10041282928 10503901184 1039949088 11.264 93 10 20 0 1079088192 37458256 1680848
12:01:31 97297 8192000000 10082675936 10528055296 1110937536 11.941 98 17 34 1 1175410880 61070512 3402832
12:05:23 73304 8192000000 10093918128 10543849472 1139858800 12.214 100 12 24 1 1175714848 33459952 2396096
12:07:41 81341 8192000000 10078967824 10539245568 1094029712 11.781 96 25 51 1 1182591568 82961872 5599984
12:10:10 58633 8192000000 10097745232 10567929856 1119022240 12.018 98 26 49 0 1182583008 58520080 5040688
12:12:11 57634 8192000000 10196593472 10645843968 1144001712 12.253 99 22 44 1 1261645600 112901696 4742192
12:14:10 70090 8192000000 10076281440 10542166016 1133456704 12.154 99 6 12 0 1181572928 46930016 1186208
12:16:21 66465 8192000000 10171024176 10627002368 1136975616 12.187 98 20 40 1 1227934896 86535568 4423712
12:18:31 148924 8192000000 10126986544 10654666752 1154425808 12.351 100 19 37 0 1256999152 98594560 3978784
12:22:10 67705 8192000000 10073693280 10529488896 1122969856 12.055 98 10 20 0 1159240432 34366208 1904368
12:25:02 57971 8192000000 10106232176 10553331712 1102266032 11.859 95 20 40 0 1159887424 53301024 4320368
12:27:05 28579 8192000000 10098128064 10555080704 1113394416 11.965 97 24 48 1 1165699552 47582880 4722256
12:28:36 40348 8192000000 10107419488 10545836032 1138293648 12.199 99 19 38 2 1196218800 54185968 3739184
12:30:18 48722 8192000000 10106873696 10590863360 1114843568 11.978 97 15 28 3 1223247632 105619232 2784832
12:32:07 46284 8192000000 10103743376 10543288320 1128205888 12.104 99 18 36 0 1188229504 56346128 3677488
12:33:56 29909 8192000000 10075140576 10504359936 1115683648 11.986 98 32 65 1 1158049264 36239472 6126144
12:35:29 87051 8192000000 10072135968 10529988608 1052474368 11.384 93 31 62 0 1111837600 53572160 5791072
12:38:12 60485 8192000000 10048048768 10509570048 1106629216 11.900 99 11 22 0 1136922944 27750240 2543488
12:40:14 31616 8192000000 10058368960 10524725248 1105258864 11.888 98 6 12 0 1154538400 47843504 1436032
12:41:54 36289 8192000000 10046895632 10497064960 1105461024 11.889 99 9 18 0 1150616352 43656640 1498688
12:45:17 34324 8192000000 10055484624 10493132800 1107667840 11.910 99 20 40 1 1132877040 21205504 4003696
12:46:54 90641 8192000000 10024463632 10456514560 1041279040 11.277 93 17 33 1 1094715152 49597024 3839088
12:49:31 23109 8192000000 10069061072 10501820416 1103261200 11.869 99 38 76 1 1141683936 30648544 7774192
12:50:58 47509 8192000000 10074468960 10547482624 1125464304 12.079 100 32 64 1 1186812240 55027232 6320704
12:52:54 19545 8192000000 10076763552 10516705280 1106607136 11.900 99 47 94 0 1166492352 50529728 9355488
12:54:19 37077 8192000000 10100685216 10535796736 1117716464 12.005 99 31 62 0 1165941664 42215280 6009920
12:55:59 104655 8192000000 10124901200 10604470272 1108754448 11.921 98 15 30 0 1154822352 43443104 2624800
12:59:49 97327 8192000000 10083042080 10574761984 1112061568 11.952 98 5 10 0 1161754144 48724112 968464
13:02:32 71974 8192000000 10116410160 10578231296 1122512320 12.051 98 18 36 1 1163082832 36772880 3797632
13:05:04 115878 8192000000 10113775104 10594131968 1137720512 12.194 98 10 20 1 1200170256 60313456 2136288
13:08:02 39019 8192000000 10174113968 10612539392 1143508864 12.249 99 27 54 1 1241898928 92598016 5792048
13:09:53 43002 8192000000 10133172688 10626621440 1137510368 12.192 99 9 18 0 1236545344 97241440 1793536
13:12:35 36095 8192000000 10096219856 10608025600 1126315072 12.087 98 18 36 0 1207827424 77911584 3600768
13:14:16 136130 8192000000 10050267824 10533302272 1094852736 11.789 96 13 26 0 1147853152 49564432 3435984
13:17:34 61113 8192000000 10044809152 10494599168 1101173840 11.849 98 11 22 0 1147127536 43771648 2182048
13:19:50 85806 8192000000 10061493792 10529255424 1093576304 11.777 97 10 20 1 1130236112 34622992 2036816
13:22:28 69181 8192000000 10075420400 10539008000 1110142768 11.934 99 8 16 0 1154306512 42337904 1825840
13:24:47 49409 8192000000 10103044512 10580463616 1108678576 11.920 98 26 52 0 1169522864 55074768 5769520
13:26:54 24869 8192000000 8990940672 9234927616 139735424 1.677 13 1 2 0 154277264 14506432 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-04-2021 13:28:42 to 04-04-2021 13:28:44.

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          232325
MON$OLDEST_ACTIVE               232326
MON$OLDEST_SNAPSHOT             232326
MON$NEXT_TRANSACTION            232326
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                       2097792
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             793
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'

04.04.21 13:28:44. 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-04 13:28:47 No severe PSQL-related problems occured
04.04.21 13:28:48. End of report.