Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2020 13:28
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16921896 kB
MemAvailable 31591188 kB
Buffers 0 kB
Cached 14771064 kB
SwapCached 5448 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 2020-10-18 13:26:52
DTS_END                         2020-10-18 13:26:52.0110
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(67:17)
IP                              ::1/56518
TRN_ID                          235101
ATT_ID                          593
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: 8715.43 26777 1045852
customer order: creation 166.50 356 19980
customer order: refuse 14.15 308 1698
order to supplier: creation 45.10 1328 5412
order to supplier: removal 3.32 93351 398
invoice (draft): creation 44.93 3479 5392
invoice (draft): removal 48.38 892 5805
invoice accept: apply 40.82 15742 4898
invoice accept: cancel 43.36 941 5203
customer reserve: creation 3720.65 154 446478
customer reserve: removal 4309.57 57 517148
realization accept: apply 82.94 169 9953
realization accept: cancel 69.72 121 8366
payment from customer: creation 58.16 119 6979
payment from customer: removal 5.44 84 653
payment to supplier: creation 48.18 119 5781
payment to supplier: removal 3.62 65 434
service: total inventory turnovers 3.82 3196 458
service: total monetary turnovers 5.88 471 706
service: aggregate perf. data 0.88 14632 106
service: refresh index statistics 0.03 399949 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2020 13:29:01 to 18-10-2020 13:29:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2214
TEST_TIME 2 3223
TEST_TIME 3 4937
TEST_TIME 4 5568
TEST_TIME 5 5392
TEST_TIME 6 5629
TEST_TIME 7 5709
TEST_TIME 8 5891
TEST_TIME 9 6294
TEST_TIME 10 6431
TEST_TIME 11 6921
TEST_TIME 12 7672
TEST_TIME 13 7972
TEST_TIME 14 8137
TEST_TIME 15 8296
TEST_TIME 16 8873
TEST_TIME 17 8781
TEST_TIME 18 8783
TEST_TIME 19 8888
TEST_TIME 20 9052
TEST_TIME 21 9419
TEST_TIME 22 9906
TEST_TIME 23 10459
TEST_TIME 24 10948
TEST_TIME 25 11313
TEST_TIME 26 11134
TEST_TIME 27 10811
TEST_TIME 28 10726
TEST_TIME 29 10656
TEST_TIME 30 10451
TEST_TIME 31 10242
TEST_TIME 32 10155
TEST_TIME 33 9993
TEST_TIME 34 9963
TEST_TIME 35 9821
TEST_TIME 36 9740
TEST_TIME 37 9863
TEST_TIME 38 9823
TEST_TIME 39 9659
TEST_TIME 40 9690
TEST_TIME 41 9562
TEST_TIME 42 9552
TEST_TIME 43 9401
TEST_TIME 44 9308
TEST_TIME 45 9193
TEST_TIME 46 9145
TEST_TIME 47 9058
TEST_TIME 48 8995
TEST_TIME 49 9022
TEST_TIME 50 9110
TEST_TIME 51 9019
TEST_TIME 52 9026
TEST_TIME 53 9254
TEST_TIME 54 9262
TEST_TIME 55 9263
TEST_TIME 56 9220
TEST_TIME 57 9307
TEST_TIME 58 9420
TEST_TIME 59 9364
TEST_TIME 60 9322
TEST_TIME 61 9242
TEST_TIME 62 9194
TEST_TIME 63 9117
TEST_TIME 64 9073
TEST_TIME 65 9003
TEST_TIME 66 9030
TEST_TIME 67 9014
TEST_TIME 68 8996
TEST_TIME 69 9000
TEST_TIME 70 9009
TEST_TIME 71 9067
TEST_TIME 72 9155
TEST_TIME 73 9103
TEST_TIME 74 9054
TEST_TIME 75 9143
TEST_TIME 76 9137
TEST_TIME 77 9111
TEST_TIME 78 9091
TEST_TIME 79 8988
TEST_TIME 80 9013
TEST_TIME 81 8937
TEST_TIME 82 8852
TEST_TIME 83 8862
TEST_TIME 84 8807
TEST_TIME 85 8784
TEST_TIME 86 8883
TEST_TIME 87 8867
TEST_TIME 88 8850
TEST_TIME 89 8896
TEST_TIME 90 8851
TEST_TIME 91 8843
TEST_TIME 92 8990
TEST_TIME 93 9041
TEST_TIME 94 9113
TEST_TIME 95 9091
TEST_TIME 96 9103
TEST_TIME 97 9071
TEST_TIME 98 9032
TEST_TIME 99 9011
TEST_TIME 100 8976
TEST_TIME 101 8947
TEST_TIME 102 8971
TEST_TIME 103 8937
TEST_TIME 104 8960
TEST_TIME 105 8936
TEST_TIME 106 8940
TEST_TIME 107 8911
TEST_TIME 108 8930
TEST_TIME 109 8929
TEST_TIME 110 8965
TEST_TIME 111 8986
TEST_TIME 112 9019
TEST_TIME 113 8955
TEST_TIME 114 8887
TEST_TIME 115 8860
TEST_TIME 116 8861
TEST_TIME 117 8809
TEST_TIME 118 8797
TEST_TIME 119 8752
TEST_TIME 120 8717

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2020 13:29:05 to 18-10-2020 13:29:08.

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 1458901 1458901 0 0.00 0 18982 6 0 0
doc_list_biud 1458901 1458901 0 0.00 0 36409 26 0 0
sp_add_invoice_to_stock 5086 4898 188 3.69 19 151092 15742 0 188
sp_cancel_adding_invoice 5203 5203 0 0.00 6 91742 942 0 0
sp_cancel_client_order 1698 1698 0 0.00 5 11917 308 0 0
sp_cancel_customer_reserve 517148 517148 0 0.00 1 26616 57 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 7830 84 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 3086 65 0 0
sp_cancel_supplier_invoice 5823 5805 18 0.30 2 22327 893 0 18
sp_cancel_supplier_order 398 398 0 0.00 9 982349 93352 0 0
sp_cancel_write_off 8366 8366 0 0.00 1 10029 122 0 0
sp_client_order 19980 19980 0 0.00 4 28990 356 0 0
sp_customer_reserve 446478 446478 0 0.00 0 37990 154 0 0
sp_fill_shopping_cart 30779 30779 0 0.00 1 38798 51 0 0
sp_fill_shopping_cart_clo_res 446475 446475 0 0.00 0 22658 17 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 48053 447 0 0
sp_kill_cost_storno 14656 14656 0 0.00 0 9712 55 0 0
sp_kill_qstorno_ret_qs2qd 1048865 1048865 0 0.00 0 26589 31 0 0
sp_kill_qty_storno 553466 553466 0 0.00 0 36409 69 0 0
sp_lock_selected_doc 20474 20474 0 0.00 0 8060 5 0 0
sp_make_cost_storno 27608 27608 0 0.00 0 15857 50 0 0
sp_multiply_rows_for_pdistr 27608 27608 0 0.00 0 7761 31 0 0
sp_multiply_rows_for_qdistr 19980 19980 0 0.00 0 28980 191 0 0
sp_pay_from_customer 6979 6979 0 0.00 4 12090 119 0 0
sp_pay_to_supplier 5781 5781 0 0.00 4 12676 120 0 0
sp_payment_common 12760 12760 0 0.00 1 10728 20 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 11908 280 0 0
sp_qd_handle_on_invoice_upd_sts 10098 10098 0 0.00 0 36409 356 0 0
sp_qd_handle_on_reserve_upd_sts 9953 9953 0 0.00 0 10552 51 0 0
sp_reserve_write_off 9953 9953 0 0.00 7 15086 169 0 0
sp_supplier_invoice 5392 5392 0 0.00 6 87023 3480 0 0
sp_supplier_order 5412 5412 0 0.00 5 49064 1328 0 0
srv_aggregate_perf_data 106 106 0 0.00 2887 158601 14632 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1904 177656 55047 0 0
srv_find_qd_qs_mism 999262 999262 0 0.00 0 14072 4 0 0
srv_make_invnt_saldo 458 458 0 0.00 26 81775 3196 0 0
srv_make_money_saldo 706 706 0 0.00 1 13330 471 0 0
srv_recalc_idx_stat 4 4 0 0.00 207763 541480 399950 0 0
t$perf-abend:sp_add_invoice_to_stock 188 188 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4898 4898 0 0.00 0 9152 144 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 20301 157 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 14254 22 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 639 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 2131 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 3216 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 19029 82 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 33014 1370 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 1938 6 0 0
t$perf-norm:sp_client_order 19980 19980 0 0.00 0 17119 17 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 256 2 0 0
t$perf-norm:sp_pay_from_customer 6979 6979 0 0.00 0 11874 10 0 0
t$perf-norm:sp_pay_to_supplier 5781 5781 0 0.00 0 4205 8 0 0
t$perf-norm:sp_reserve_write_off 9953 9953 0 0.00 0 19121 14 0 0
t$perf-norm:sp_supplier_invoice 5392 5392 0 0.00 0 7619 19 0 0
t$perf-norm:sp_supplier_order 5412 5412 0 0.00 0 11861 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 276 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 298 10 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 265 5 0 0
t$perf-norm:srv_make_money_saldo 706 706 0 0.00 0 511 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 31 10 0 0
v_add_invoice_to_stock 10158 9782 376 3.70 0 7716 17 0 376
v_all_customers 36572 36572 0 0.00 0 43 1 0 0
v_all_suppliers 29784 29784 0 0.00 0 25 1 0 0
v_all_wares 39960 39960 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3518 11 0 0
v_cancel_client_order 3396 3396 0 0.00 0 3536 7 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 1950 5 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 355 3 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 3072 10 0 0
v_cancel_supplier_invoice 916 880 36 3.93 0 5419 31 0 36
v_cancel_supplier_order 796 796 0 0.00 0 429 3 0 0
v_cancel_write_off 1872 1872 0 0.00 0 2370 15 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 392 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 3835 17 0 0
v_max_id_ord_sup 5387 5387 0 0.00 0 5987 6 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 3006 4 0 0
v_max_non_paid_realizn 3748 3748 0 0.00 0 2458 3 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 2401 5 0 0
v_min_id_clo_res 965 965 0 0.00 0 4238 11 0 0
v_min_id_ord_sup 5387 5387 0 0.00 0 5389 12 0 0
v_min_non_paid_invoice 5781 5781 0 0.00 0 2488 11 0 0
v_min_non_paid_realizn 6979 6979 0 0.00 0 4585 12 0 0
v_our_firm 9850 9850 0 0.00 0 14 1 0 0
v_reserve_write_off 19906 19906 0 0.00 0 5380 5 0 0
x_lock_depdocs_on_canc_invoice 10568 10568 0 0.00 0 24038 111 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 8959 318 0 0
x_make_qstorno_1000_1200 5412 5412 0 0.00 2 48376 1244 0 0
x_make_qstorno_1200_2000 5392 5392 0 0.00 3 83789 3279 0 0
x_make_qstorno_2100_3300 446478 446478 0 0.00 0 37988 134 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2020 13:29:09 to 18-10-2020 13:29:13.

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:56:55 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
10:57:57 21773 8192000000 9717423728 10013794304 793109088 8.826 100 84 168 0 820962416 7750336 20102992
10:59:24 21655 8192000000 9763929344 10069385216 832203760 9.221 100 94 188 0 867639600 12723648 22712192
11:00:52 25097 8192000000 9801828272 10115338240 855315088 9.453 100 84 168 0 902809328 28195360 19298880
11:02:22 11024 8192000000 9875153104 10206511104 915103840 10.048 100 75 150 0 982225808 49877952 17244016
11:03:37 4709 8192000000 9936511552 10283126784 966975808 10.557 100 80 159 1 1044970048 61541968 16452272
11:04:46 3340 8192000000 9965821168 10324779008 1004535648 10.922 100 78 155 2 1074727536 55270528 14921360
11:05:50 2906 8192000000 10015180800 10378035200 1033921184 11.206 100 85 166 1 1126578080 75939616 16717280
11:06:54 1789 8192000000 10025818992 10394673152 1051830144 11.378 100 93 187 0 1131027632 59716768 19480720
11:08:00 12175 8192000000 10034559264 10417389568 1064777600 11.502 100 64 126 1 1152256592 74970720 12508272
11:09:13 9006 8192000000 10006265056 10401705984 1074804176 11.598 100 51 102 0 1108325792 22656176 10865440
11:10:23 6410 8192000000 9997667648 10392174592 1083048656 11.677 100 49 98 1 1106923088 11627088 12247344
11:11:32 58690 8192000000 9998856976 10417037312 1086433200 11.709 100 21 42 1 1119991216 28917952 4640064
11:13:33 8433 8192000000 10017562288 10427744256 1093922256 11.780 100 32 65 2 1132375056 31668512 6784288
11:14:45 11072 8192000000 10006926352 10407456768 1093603888 11.777 100 27 55 0 1124672384 25172864 5895632
11:15:57 55533 8192000000 10006655408 10408390656 1094672480 11.787 100 37 73 2 1113188144 9998768 8516896
11:17:53 4877 8192000000 9980320144 10374164480 1050984880 11.370 98 34 69 1 1072418448 12463920 8969648
11:19:00 7295 8192000000 9957728288 10346176512 1010902624 10.984 97 31 62 2 1037643872 20408688 6332560
11:20:09 7087 8192000000 9954551280 10335068160 1012135312 10.996 99 42 83 1 1043560256 21593648 9831296
11:21:18 30274 8192000000 9960478960 10347089920 1028514592 11.154 100 30 60 2 1067970816 33597104 5859120
11:22:52 8929 8192000000 9949388784 10338156544 1040176896 11.266 100 37 73 2 1070980448 21860064 8943488
11:24:02 10581 8192000000 9964041152 10364276736 1053310320 11.392 100 28 56 2 1076578960 16703840 6564800
11:25:15 7396 8192000000 9978309248 10381406208 1061519152 11.471 100 25 51 1 1082411792 15533856 5358784
11:26:25 60605 8192000000 9987491312 10391498752 1075147952 11.601 100 21 41 1 1105339040 25809360 4381728
11:28:27 32636 8192000000 10055152080 10457608192 1107472784 11.908 100 39 76 2 1168994416 53564272 7957360
11:30:04 21614 8192000000 10106964208 10513092608 1128203664 12.104 100 42 84 2 1207515872 69244032 10068176
11:31:28 4312 8192000000 10117894864 10520223744 1134484800 12.164 100 55 107 0 1227831504 82409344 10937360
11:32:36 3890 8192000000 10135473824 10544078848 1138469056 12.201 100 50 98 2 1246317824 96763856 11084912
11:33:44 2513 8192000000 10176721136 10578178048 1158457968 12.389 100 70 139 0 1275251584 101590384 15203232
11:34:49 1977 8192000000 10207589296 10612281344 1158387728 12.388 100 68 132 3 1311774976 139153952 14233296
11:35:56 2625 8192000000 10243756624 10649100288 1159311696 12.397 100 83 162 1 1344302672 166566208 18424768
11:37:05 4292 8192000000 10246804672 10665615360 1160458592 12.408 100 80 160 1 1351963584 173070560 18434432
11:38:15 6355 8192000000 10234382912 10671788032 1165726016 12.457 100 58 116 1 1347248624 167867056 13655552
11:39:26 9403 8192000000 10229660144 10637651968 1158157472 12.386 100 71 140 2 1331367920 158259632 14950816
11:40:40 2043 8192000000 10272707216 10668531712 1144759552 12.260 100 82 164 1 1367515824 204533808 18222464
11:41:46 1903 8192000000 10353819072 10751152128 1151368464 12.322 99 84 164 2 1439972464 269389936 19214064
11:42:51 8602 8192000000 10249287184 10665345024 1116428736 11.993 97 31 62 1 1333393008 209133872 7830400
11:44:12 8000 8192000000 10339936048 10756530176 1139689792 12.213 100 45 90 1 1425581728 275268576 10623360
11:45:23 12208 8192000000 10363551760 10786238464 1126500736 12.088 99 43 88 0 1457590416 319780752 11308928
11:46:37 8428 8192000000 10323066336 10733490176 1098679232 11.825 98 43 83 2 1403159760 294263488 10217040
11:47:50 17961 8192000000 10271140800 10722021376 1100013200 11.838 99 31 62 0 1385097040 277325232 7758608
11:49:18 7882 8192000000 10238986112 10651975680 1101621392 11.853 100 42 83 2 1330365760 219186896 9557472
11:50:29 28519 8192000000 10143401520 10571612160 1108155424 11.915 100 17 34 0 1282061232 169744192 4161616
11:51:59 74462 8192000000 10061165248 10495733760 1087757488 11.721 98 24 48 1 1135988496 42349712 5881296
11:54:15 37287 8192000000 10071487168 10523762688 1116661264 11.995 100 28 57 1 1184838208 61373344 6803600
11:56:13 55682 8192000000 10069859184 10525245440 1121059456 12.037 100 13 26 0 1178478944 55012752 2406736
11:58:23 71707 8192000000 10112658320 10534252544 1121839696 12.044 99 47 92 2 1202082736 70694688 9548352
12:00:35 65999 8192000000 10154673696 10631491584 1128336384 12.106 99 12 24 0 1262196144 131294128 2565632
12:02:43 79999 8192000000 10113174656 10643771392 1134738400 12.166 100 19 38 0 1232353424 93466944 4148080
12:05:05 51728 8192000000 10085005664 10514911232 1132238112 12.142 99 24 48 0 1181637712 43832912 5566688
12:07:00 72344 8192000000 10107040176 10572656640 1147468928 12.286 100 17 34 0 1223580672 72531072 3580672
12:09:20 69354 8192000000 10047276400 10524413952 1129286720 12.115 99 8 15 0 1165562272 35087360 1188192
12:11:30 73655 8192000000 10054021376 10524422144 1143815200 12.251 100 19 40 1 1187353232 39713680 3824352
12:13:52 74378 8192000000 10091635408 10571821056 1134769552 12.166 99 8 15 1 1191902672 55239744 1893376
12:16:07 73036 8192000000 10124569904 10563579904 1144474560 12.258 99 12 24 0 1212660128 65441568 2744000
12:18:21 45589 8192000000 10164802272 10620526592 1141081760 12.226 99 20 40 0 1262111248 116179216 4850272
12:20:14 39543 8192000000 10085072224 10521063424 1148675232 12.297 100 24 48 2 1181657200 28259344 4722624
12:21:58 50281 8192000000 10123571040 10571350016 1154464480 12.351 100 21 40 1 1214965360 56046000 4454880
12:24:04 98869 8192000000 10099893616 10534440960 1133393504 12.153 98 10 20 0 1165246112 30217264 1635344
12:26:43 137306 8192000000 10080418192 10538278912 1126938752 12.092 97 25 50 1 1174653696 42653088 5061856
12:30:02 63677 8192000000 10061232720 10516570112 1109102272 11.924 97 10 16 1 1132768416 22545168 1120976
12:32:24 47171 8192000000 10072686960 10542911488 1092056608 11.762 96 12 24 0 1167940496 73140240 2743648
12:34:16 50549 8192000000 10090708064 10525614080 1089552288 11.738 96 22 44 0 1135985248 41811424 4621536
12:36:12 123764 8192000000 10105723232 10610126848 1100161632 11.839 97 31 60 2 1182732544 75986128 6584784
12:39:19 85322 8192000000 10072883568 10500800512 1020492400 11.077 91 20 41 0 1044756656 20114176 4150080
12:41:44 104316 8192000000 10040816880 10468454400 1022935056 11.100 92 14 28 0 1083453968 57896784 2622128
12:44:29 117734 8192000000 10047121952 10517094400 1086920864 11.713 98 26 52 1 1171351328 79017168 5413296
12:47:52 43499 8192000000 10045572480 10514489344 1096478080 11.804 100 7 14 0 1145730608 47893216 1359312
12:49:39 60038 8192000000 10054750848 10474541056 1080840912 11.655 98 13 26 0 1102920896 19153216 2926768
12:51:39 150412 8192000000 10087520208 10549301248 1093981504 11.781 98 24 47 4 1144485696 45022000 5482192
12:55:10 46928 8192000000 10096195200 10558533632 1120014720 12.027 99 30 61 0 1220744864 93564352 7165792
12:57:04 77395 8192000000 10123730160 10589114368 1117422144 12.003 98 17 35 1 1265978816 144420624 4136048
12:59:23 29161 8192000000 10096081808 10521223168 1134830368 12.167 100 31 63 0 1157597824 16180064 6587392
13:00:58 96884 8192000000 10092749824 10541228032 1125094720 12.075 98 10 20 1 1158137248 31167312 1875216
13:03:38 103574 8192000000 10105039840 10583990272 1155024512 12.357 100 20 40 0 1218629440 59253232 4351696
13:06:29 54321 8192000000 10092011504 10527027200 1133541216 12.155 98 12 26 1 1151098080 15376160 2180704
13:08:24 75063 8192000000 10072566112 10507530240 1092847232 11.770 95 17 31 2 1147736816 51166560 3723024
13:10:41 177655 8192000000 10112195904 10572230656 1097171792 11.811 95 15 30 2 1156926960 56743808 3011360
13:14:43 31423 8192000000 10102473760 10529513472 1152738544 12.335 100 35 70 2 1194783728 34809616 7235568
13:16:16 86264 8192000000 10115906848 10579410944 1093979136 11.780 95 16 32 0 1167443360 70503888 2960336
13:18:43 113204 8192000000 10105103536 10573864960 1149346544 12.303 100 12 24 1 1194514672 42478144 2689984
13:22:09 78505 8192000000 10062265616 10555789312 1102860800 11.865 96 11 22 0 1155536688 50090672 2585216
13:24:28 68927 8192000000 10048844016 10505555968 1097725456 11.816 97 5 10 0 1121284736 22659328 899952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-10-2020 13:29:15 to 18-10-2020 13:29:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 188 v_add_invoice_to_stock 335544517
user_exc 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2020 13:29:30 to 18-10-2020 13:29:32.

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          235458
MON$OLDEST_ACTIVE               235459
MON$OLDEST_SNAPSHOT             235459
MON$NEXT_TRANSACTION            235459
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                       2108032
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             778
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2226 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.2226 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 ---
:180: 
:181: mscs-test-161.budzdorov.ru    Sun Oct 18 13:27:18 2020
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:183: 
--- end of diff output ---

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

18.10.20 13:29:33. 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
2020-10-18 13:29:35 No severe PSQL-related problems occured
18.10.20 13:29:37. End of report.