Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2021 07:38
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 17015460 kB
MemAvailable 31562404 kB
Buffers 0 kB
Cached 14613784 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-03-11 07:38:16
DTS_END                         2021-03-11 07:38:16.7670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47468
TRN_ID                          224159
ATT_ID                          570
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: 8527.68 25000 1023322
customer order: creation 156.83 395 18820
customer order: refuse 13.48 342 1618
order to supplier: creation 41.03 1588 4924
order to supplier: removal 3.18 137486 381
invoice (draft): creation 42.03 4422 5044
invoice (draft): removal 50.06 1116 6007
invoice accept: apply 37.83 18616 4540
invoice accept: cancel 45.01 962 5401
customer reserve: creation 3497.75 181 419730
customer reserve: removal 4364.03 78 523684
realization accept: apply 75.96 192 9115
realization accept: cancel 84.09 125 10091
payment from customer: creation 53.38 136 6405
payment from customer: removal 5.31 94 637
payment to supplier: creation 43.84 143 5261
payment to supplier: removal 3.35 72 402
service: total inventory turnovers 3.70 3875 444
service: total monetary turnovers 6.01 564 721
service: aggregate perf. data 0.78 17338 93
service: refresh index statistics 0.03 312277 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-03-2021 07:38:57 to 11-03-2021 07:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4889
TEST_TIME 2 4270
TEST_TIME 3 3860
TEST_TIME 4 3638
TEST_TIME 5 3931
TEST_TIME 6 4243
TEST_TIME 7 4658
TEST_TIME 8 4730
TEST_TIME 9 4773
TEST_TIME 10 4750
TEST_TIME 11 4810
TEST_TIME 12 5133
TEST_TIME 13 5415
TEST_TIME 14 5544
TEST_TIME 15 5609
TEST_TIME 16 5741
TEST_TIME 17 5955
TEST_TIME 18 6368
TEST_TIME 19 6797
TEST_TIME 20 6920
TEST_TIME 21 7060
TEST_TIME 22 7427
TEST_TIME 23 7641
TEST_TIME 24 7945
TEST_TIME 25 8111
TEST_TIME 26 8371
TEST_TIME 27 8381
TEST_TIME 28 9443
TEST_TIME 29 10793
TEST_TIME 30 10496
TEST_TIME 31 10288
TEST_TIME 32 10222
TEST_TIME 33 10245
TEST_TIME 34 10024
TEST_TIME 35 9910
TEST_TIME 36 9786
TEST_TIME 37 9610
TEST_TIME 38 9470
TEST_TIME 39 9395
TEST_TIME 40 9228
TEST_TIME 41 9132
TEST_TIME 42 9093
TEST_TIME 43 8974
TEST_TIME 44 8969
TEST_TIME 45 8981
TEST_TIME 46 9004
TEST_TIME 47 8940
TEST_TIME 48 8960
TEST_TIME 49 8901
TEST_TIME 50 8848
TEST_TIME 51 8785
TEST_TIME 52 8793
TEST_TIME 53 8759
TEST_TIME 54 8873
TEST_TIME 55 8791
TEST_TIME 56 8847
TEST_TIME 57 8880
TEST_TIME 58 8862
TEST_TIME 59 8962
TEST_TIME 60 8979
TEST_TIME 61 9026
TEST_TIME 62 9052
TEST_TIME 63 8964
TEST_TIME 64 8833
TEST_TIME 65 8799
TEST_TIME 66 8737
TEST_TIME 67 8670
TEST_TIME 68 8604
TEST_TIME 69 8524
TEST_TIME 70 8491
TEST_TIME 71 8457
TEST_TIME 72 8488
TEST_TIME 73 8531
TEST_TIME 74 8495
TEST_TIME 75 8500
TEST_TIME 76 8609
TEST_TIME 77 8604
TEST_TIME 78 8614
TEST_TIME 79 8611
TEST_TIME 80 8577
TEST_TIME 81 8736
TEST_TIME 82 8711
TEST_TIME 83 8804
TEST_TIME 84 8723
TEST_TIME 85 8684
TEST_TIME 86 8602
TEST_TIME 87 8589
TEST_TIME 88 8555
TEST_TIME 89 8545
TEST_TIME 90 8500
TEST_TIME 91 8540
TEST_TIME 92 8556
TEST_TIME 93 8528
TEST_TIME 94 8541
TEST_TIME 95 8534
TEST_TIME 96 8586
TEST_TIME 97 8564
TEST_TIME 98 8530
TEST_TIME 99 8592
TEST_TIME 100 8627
TEST_TIME 101 8631
TEST_TIME 102 8712
TEST_TIME 103 8741
TEST_TIME 104 8713
TEST_TIME 105 8636
TEST_TIME 106 8574
TEST_TIME 107 8526
TEST_TIME 108 8482
TEST_TIME 109 8525
TEST_TIME 110 8497
TEST_TIME 111 8483
TEST_TIME 112 8460
TEST_TIME 113 8517
TEST_TIME 114 8493
TEST_TIME 115 8513
TEST_TIME 116 8476
TEST_TIME 117 8513
TEST_TIME 118 8533
TEST_TIME 119 8535
TEST_TIME 120 8529

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-03-2021 07:39:01 to 11-03-2021 07:39:04.

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 1410245 1410245 0 0.00 0 20133 7 0 0
doc_list_biud 1410245 1410245 0 0.00 0 32019 37 0 0
sp_add_invoice_to_stock 4654 4540 114 2.44 35 228942 18616 0 114
sp_cancel_adding_invoice 5401 5401 0 0.00 5 83686 963 0 0
sp_cancel_client_order 1618 1618 0 0.00 5 12722 342 0 0
sp_cancel_customer_reserve 523684 523684 0 0.00 1 32025 78 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 7643 94 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 1646 73 0 0
sp_cancel_supplier_invoice 6010 6007 3 0.04 3 18466 1117 0 3
sp_cancel_supplier_order 381 381 0 0.00 7 1196787 137486 0 0
sp_cancel_write_off 10091 10091 0 0.00 1 6106 125 0 0
sp_client_order 18820 18820 0 0.00 4 28853 395 0 0
sp_customer_reserve 419730 419730 0 0.00 0 51778 182 0 0
sp_fill_shopping_cart 28779 28779 0 0.00 1 26470 53 0 0
sp_fill_shopping_cart_clo_res 419722 419722 0 0.00 0 26773 16 0 0
sp_get_clo_for_invoice 4532 4532 0 0.00 0 64368 464 0 0
sp_kill_cost_storno 16531 16531 0 0.00 0 10993 61 0 0
sp_kill_qstorno_ret_qs2qd 1063847 1063847 0 0.00 0 31709 43 0 0
sp_kill_qty_storno 560829 560829 0 0.00 0 32018 91 0 0
sp_lock_selected_doc 18926 18926 0 0.00 0 6770 4 0 0
sp_make_cost_storno 25313 25313 0 0.00 0 13375 66 0 0
sp_multiply_rows_for_pdistr 25313 25313 0 0.00 0 9740 34 0 0
sp_multiply_rows_for_qdistr 18820 18820 0 0.00 0 20435 205 0 0
sp_pay_from_customer 6405 6405 0 0.00 4 16734 137 0 0
sp_pay_to_supplier 5261 5261 0 0.00 5 12736 143 0 0
sp_payment_common 11666 11666 0 0.00 1 10352 35 0 0
sp_qd_handle_on_cancel_clo 1618 1618 0 0.00 0 12718 311 0 0
sp_qd_handle_on_invoice_upd_sts 9933 9933 0 0.00 0 31112 423 0 0
sp_qd_handle_on_reserve_upd_sts 9115 9115 0 0.00 0 12918 59 0 0
sp_reserve_write_off 9115 9115 0 0.00 6 14654 192 0 0
sp_supplier_invoice 5044 5044 0 0.00 5 110621 4422 0 0
sp_supplier_order 4924 4924 0 0.00 5 36765 1589 0 0
srv_aggregate_perf_data 93 93 0 0.00 2316 182519 17339 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1371 135177 50360 0 0
srv_find_qd_qs_mism 979190 979190 0 0.00 0 18090 6 0 0
srv_make_invnt_saldo 444 444 0 0.00 44 105289 3876 0 0
srv_make_money_saldo 721 721 0 0.00 1 11293 564 0 0
srv_recalc_idx_stat 4 4 0 0.00 123208 507415 312278 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4540 4540 0 0.00 0 20078 168 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 0 1950 75 0 0
t$perf-norm:sp_cancel_client_order 1618 1618 0 0.00 0 19137 23 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 5259 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 1411 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 1159 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 3348 26 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 22886 1757 0 0
t$perf-norm:sp_cancel_write_off 895 895 0 0.00 0 5122 17 0 0
t$perf-norm:sp_client_order 18820 18820 0 0.00 0 10269 19 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 5140 10 0 0
t$perf-norm:sp_pay_from_customer 6405 6405 0 0.00 0 6225 8 0 0
t$perf-norm:sp_pay_to_supplier 5261 5261 0 0.00 0 11018 11 0 0
t$perf-norm:sp_reserve_write_off 9115 9115 0 0.00 0 19091 13 0 0
t$perf-norm:sp_supplier_invoice 5044 5044 0 0.00 0 6782 17 0 0
t$perf-norm:sp_supplier_order 4924 4924 0 0.00 0 17303 26 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 956 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1044 35 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 6944 36 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 560 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9292 9064 228 2.45 0 10629 17 0 228
v_all_customers 34058 34058 0 0.00 0 155 1 0 0
v_all_suppliers 27438 27438 0 0.00 0 16 1 0 0
v_all_wares 37640 37640 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 875 6 0 0
v_cancel_client_order 3236 3236 0 0.00 0 9991 10 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 1091 7 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 435 3 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 937 5 0 0
v_cancel_supplier_invoice 700 694 6 0.85 0 1013 13 0 6
v_cancel_supplier_order 762 762 0 0.00 0 903 4 0 0
v_cancel_write_off 1790 1790 0 0.00 0 2192 15 0 0
v_max_id_clo_ord 4919 4919 0 0.00 0 3500 2 0 0
v_max_id_clo_res 932 932 0 0.00 0 3076 17 0 0
v_max_id_ord_sup 5040 5040 0 0.00 0 9551 9 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 1880 3 0 0
v_max_non_paid_realizn 3497 3497 0 0.00 0 3563 5 0 0
v_min_id_clo_ord 4919 4919 0 0.00 0 2226 5 0 0
v_min_id_clo_res 932 932 0 0.00 0 3064 18 0 0
v_min_id_ord_sup 5040 5040 0 0.00 0 5820 9 0 0
v_min_non_paid_invoice 5261 5261 0 0.00 0 9517 23 0 0
v_min_non_paid_realizn 6405 6405 0 0.00 0 10347 29 0 0
v_our_firm 9398 9398 0 0.00 0 23 1 0 0
v_reserve_write_off 18230 18230 0 0.00 0 3288 5 0 0
x_lock_depdocs_on_canc_invoice 11061 11061 0 0.00 0 14173 128 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 4147 395 0 0
x_make_qstorno_1000_1200 4924 4924 0 0.00 1 36755 1512 0 0
x_make_qstorno_1200_2000 5044 5044 0 0.00 2 110584 4204 0 0
x_make_qstorno_2100_3300 419730 419730 0 0.00 0 51777 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2021 07:39:05 to 11-03-2021 07:39:09.

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
05:08:20 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:09:21 27421 8192000000 9719833424 10014273536 799367584 8.890 100 87 174 1 828859104 7571248 21920272
05:10:52 20430 8192000000 9761185648 10064240640 837740208 9.277 100 91 182 0 871792048 12440960 21610880
05:12:20 24612 8192000000 9808657728 10129207296 875137776 9.651 100 80 160 0 920202160 27037360 18027024
05:13:52 11692 8192000000 9877893632 10209353728 923768480 10.133 100 75 150 0 982051856 41923680 16359696
05:15:07 4305 8192000000 9914178352 10257367040 963633472 10.525 100 87 169 2 1035279824 54323904 17322448
05:16:18 5789 8192000000 9984473744 10339704832 1007406608 10.950 100 82 164 0 1097482192 73564304 16511280
05:17:25 2429 8192000000 10027085408 10387496960 1028146368 11.151 100 86 172 0 1137828960 92914352 16768240
05:18:29 1655 8192000000 10024554432 10391793664 1052483520 11.384 100 91 182 0 1134767360 62801920 19481920
05:19:33 4115 8192000000 10010029424 10389123072 1069543792 11.548 100 72 142 1 1138512720 54737792 14231136
05:20:47 6077 8192000000 10009155136 10397495296 1080561264 11.653 100 38 76 0 1119079344 30187792 8330288
05:21:54 9848 8192000000 9991305328 10393665536 1083996032 11.686 100 31 63 1 1108031552 17683328 6352192
05:23:05 69364 8192000000 10015556528 10421923840 1092685968 11.768 100 31 62 0 1123839008 24030080 7122960
05:25:15 5071 8192000000 10017541360 10426499072 1100006368 11.838 100 45 90 1 1137895168 28464912 9423888
05:26:21 7947 8192000000 10010147264 10415415296 1088542832 11.729 99 40 80 0 1119863520 22157680 9163008
05:27:31 11576 8192000000 10016453296 10422681600 1095584064 11.796 100 27 54 1 1123065744 21659568 5822112
05:28:45 56449 8192000000 9986101968 10398023680 1061981488 11.475 98 30 60 1 1101213856 32678816 6553552
05:30:42 7400 8192000000 9958073040 10357829632 1022162944 11.093 98 27 53 2 1049335792 21707296 5465552
05:31:50 8258 8192000000 9920160912 10308120576 966235840 10.550 94 24 47 1 993422752 22657568 4529344
05:33:00 11827 8192000000 9937999488 10326069248 1012718096 11.002 98 24 48 1 1037205104 19340736 5146272
05:34:15 61524 8192000000 9968179728 10377175040 1049190928 11.353 100 27 54 1 1079743216 24523456 6028832
05:36:18 10439 8192000000 9973296032 10378395648 1065264096 11.507 100 36 70 2 1092647008 18649872 8733040
05:37:29 5741 8192000000 9977959072 10382303232 1068505216 11.538 100 39 76 2 1090102752 12815280 8782256
05:38:40 28820 8192000000 10013070208 10409267200 1095201536 11.792 100 43 86 2 1115393216 10630304 9561376
05:40:14 103340 8192000000 10045247776 10462056448 1113964992 11.970 100 33 66 4 1170433024 48946992 7521040
05:43:02 32509 8192000000 10081485824 10497998848 1127648624 12.099 100 33 66 2 1195621168 60610496 7362048
05:44:38 11261 8192000000 10132351280 10537717760 1136850496 12.186 100 50 98 1 1238954976 91168416 10936064
05:45:54 3850 8192000000 10144707328 10552082432 1154714368 12.354 100 51 102 0 1244589520 79051808 10823344
05:47:03 12141 8192000000 10188310320 10602573824 1164599872 12.446 100 46 91 1 1298008944 123004880 10404192
05:48:20 7152 8192000000 10222793328 10637324288 1176257824 12.555 100 65 129 3 1334684592 144374768 14052000
05:49:38 13466 8192000000 10291156128 10707070976 1188858608 12.673 100 71 137 1 1391579424 187651872 15068944
05:50:57 1364 8192000000 10346336256 10755137536 1200786432 12.784 100 83 161 0 1447586800 228518576 18281792
05:52:05 3650 8192000000 10345624000 10772606976 1200410736 12.780 100 63 125 2 1454933744 240114432 14408576
05:53:12 2286 8192000000 10413447792 10820042752 1211787712 12.886 100 87 174 0 1518319584 285836464 20695408
05:54:18 1734 8192000000 10459656048 10863886336 1202825488 12.803 100 96 194 1 1557301408 331710032 22765888
05:55:26 1845 8192000000 10479452224 10896490496 1199696512 12.774 100 80 156 1 1579930768 361790864 18443392
05:56:33 2724 8192000000 10512123200 10937638912 1205469904 12.827 100 88 175 0 1610764256 384864176 20430176
05:57:42 5024 8192000000 10506108112 10944942080 1170358912 12.500 99 61 122 1 1626590944 440742112 15489920
05:58:52 6006 8192000000 10507727680 10928484352 1165020144 12.450 100 64 128 2 1605025456 423686784 16318528
06:00:08 1594 8192000000 10520275088 10926817280 1160273440 12.406 100 81 161 2 1621278480 442008256 18996784
06:01:13 4553 8192000000 10461740208 10929123328 1139584992 12.212 99 60 118 1 1552243824 397876672 14782160
06:02:28 4906 8192000000 10513730112 10924384256 1149289856 12.303 100 62 124 0 1613602864 449427632 14885376
06:03:40 11028 8192000000 10459317472 10888949760 1121274560 12.039 98 38 76 0 1540871520 409381616 10215344
06:04:56 12502 8192000000 10459352208 10919505920 1111704048 11.949 100 31 61 3 1549905840 429761728 8440064
06:06:18 19300 8192000000 10422907168 10915921920 1108896816 11.922 100 29 58 0 1543058512 426369856 7791840
06:07:39 26210 8192000000 10038940848 10448601088 1073714320 11.588 96 38 70 2 1118175968 36872592 7589056
06:09:07 45000 8192000000 10082728736 10503024640 1089892208 11.742 98 46 94 1 1165618416 66016304 9709904
06:11:07 114310 8192000000 10052473072 10488254464 1094928928 11.790 98 13 26 0 1134733136 37041952 2762256
06:14:03 114456 8192000000 10055972480 10498273280 1120304544 12.030 99 43 83 1 1155814576 26531440 8978592
06:17:30 53338 8192000000 10054070720 10502246400 1119470464 12.022 99 17 33 2 1171324224 48217776 3635984
06:19:25 33695 8192000000 10101587792 10532261888 1131665440 12.137 100 34 66 1 1211731712 73119424 6946848
06:21:04 39771 8192000000 10137343664 10570137600 1113920304 11.970 98 17 34 1 1217093696 99242576 3930816
06:22:49 66376 8192000000 10141943584 10625544192 1138475104 12.201 100 16 32 0 1266189200 123494160 4219936
06:24:57 55382 8192000000 10092214080 10596470784 1133656208 12.156 100 10 20 0 1224418480 88256640 2505632
06:27:15 110172 8192000000 10062730928 10517008384 1118598896 12.014 98 8 16 0 1146081248 25954832 1527520
06:30:07 72779 8192000000 10104163712 10552008704 1131590464 12.136 99 13 26 1 1173216656 39134352 2491840
06:32:22 88440 8192000000 10129669728 10578493440 1140373216 12.219 99 10 20 1 1194335168 51913360 2048592
06:35:10 57626 8192000000 10079440848 10513833984 1137112192 12.188 99 23 45 2 1173368592 31889520 4366880
06:37:15 72901 8192000000 10104082688 10617606144 1154632144 12.353 100 22 44 0 1249880320 90751376 4496800
06:39:28 92920 8192000000 10076817648 10558935040 1122970656 12.055 97 13 26 1 1160486688 35239616 2276416
06:42:10 70913 8192000000 10070312432 10521632768 1106387488 11.898 96 17 34 0 1139959632 30092448 3479696
06:44:31 56384 8192000000 10069913616 10541641728 1129284576 12.115 99 5 10 0 1169833952 39555456 993920
06:46:32 48861 8192000000 10052220864 10516520960 1124896336 12.073 100 7 13 1 1157777632 31681952 1199344
06:48:21 77761 8192000000 10112079104 10551160832 1100529104 11.843 96 14 28 1 1179069856 75511392 3029360
06:50:56 77276 8192000000 10088991296 10567880704 1089391952 11.737 96 18 37 0 1144356320 51126464 3837904
06:53:23 85710 8192000000 10087589184 10577514496 1124106768 12.066 99 25 50 0 1224082928 94579440 5396720
06:55:50 33279 8192000000 10057475856 10499170304 1077259040 11.621 96 34 68 0 1127119904 42949104 6911760
06:57:33 36288 8192000000 10092753136 10517512192 1091426768 11.756 98 31 62 2 1151638576 54152144 6059664
06:59:20 91383 8192000000 10069058240 10505400320 1087147584 11.716 97 15 30 1 1174476752 84319664 3009504
07:01:55 89766 8192000000 10079354096 10514882560 1100693536 11.844 99 28 56 0 1167107072 60545712 5867824
07:05:11 49376 8192000000 10062282432 10563788800 1103042064 11.866 99 9 18 0 1175941040 70844720 2054256
07:07:45 96830 8192000000 10108724384 10569490432 1090748592 11.750 97 27 53 2 1156072176 59136624 6186960
07:11:23 106935 8192000000 10052596064 10501058560 1078426352 11.632 96 13 26 0 1121927680 41151840 2349488
07:14:17 89165 8192000000 10057569936 10486263808 1101017264 11.847 98 15 29 1 1145071728 41236672 2817792
07:16:47 23758 8192000000 10143963008 10591981568 1132249600 12.143 100 64 126 1 1220680320 75743520 12687200
07:18:14 74336 8192000000 10092601520 10529271808 1111327504 11.945 98 12 24 0 1154857424 41068448 2461472
07:20:41 135177 8192000000 10063460896 10553769984 1118017104 12.008 99 11 22 0 1162301712 42201344 2083264
07:23:57 106858 8192000000 10068604544 10527264768 1088151088 11.725 96 13 26 0 1136597824 45358096 3088640
07:26:50 125373 8192000000 10075649472 10583334912 1092278496 11.764 97 12 24 0 1158363600 63379184 2705920
07:29:56 57898 8192000000 10094827296 10582138880 1119354016 12.021 99 14 28 0 1184229072 61664448 3210608
07:31:55 52577 8192000000 10065992784 10502041600 1093916320 11.780 97 23 44 2 1141673664 43146384 4610960
07:33:48 57938 8192000000 10094761376 10527932416 1105718784 11.892 98 23 46 0 1160024624 49352368 4953472
07:35:49 84578 8192000000 10096857232 10566311936 1095233584 11.792 97 7 14 0 1152156112 55564320 1358208
07:38:15 9066 8192000000 9058239856 9305833472 205708384 2.449 19 1 2 0 215049136 9305344 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-03-2021 07:39:11 to 11-03-2021 07:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-03-2021 07:39:26 to 11-03-2021 07:39:28.

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          224456
MON$OLDEST_ACTIVE               224457
MON$OLDEST_SNAPSHOT             224457
MON$NEXT_TRANSACTION            224457
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                       2088800
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2385 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2385 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2385 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.2385 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Mar 11 07:38:29 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

11.03.21 07:39:29. 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-03-11 07:39:31 No severe PSQL-related problems occured
11.03.21 07:39:33. End of report.