Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2021 07:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16539244 kB
MemAvailable 31102500 kB
Buffers 0 kB
Cached 15110680 kB
SwapCached 3904 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-02-20 07:36:44
DTS_END                         2021-02-20 07:36:44.7210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55464
TRN_ID                          227912
ATT_ID                          673
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: 8720.79 23404 1046495
customer order: creation 159.28 437 19114
customer order: refuse 14.28 344 1714
order to supplier: creation 42.27 1472 5072
order to supplier: removal 3.23 121495 387
invoice (draft): creation 43.73 3855 5248
invoice (draft): removal 50.00 1062 6000
invoice accept: apply 39.20 16514 4704
invoice accept: cancel 45.39 929 5447
customer reserve: creation 3616.63 160 433995
customer reserve: removal 4430.21 69 531625
realization accept: apply 77.11 185 9253
realization accept: cancel 79.05 129 9486
payment from customer: creation 56.82 103 6818
payment from customer: removal 5.30 58 636
payment to supplier: creation 44.92 146 5390
payment to supplier: removal 3.26 93 391
service: total inventory turnovers 3.66 3478 439
service: total monetary turnovers 5.69 576 683
service: aggregate perf. data 0.74 19873 89
service: refresh index statistics 0.03 297109 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2021 07:37:35 to 20-02-2021 07:37:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2904
TEST_TIME 2 3604
TEST_TIME 3 4190
TEST_TIME 4 4238
TEST_TIME 5 4798
TEST_TIME 6 5443
TEST_TIME 7 5497
TEST_TIME 8 5408
TEST_TIME 9 5539
TEST_TIME 10 5456
TEST_TIME 11 5688
TEST_TIME 12 5669
TEST_TIME 13 5933
TEST_TIME 14 6407
TEST_TIME 15 6839
TEST_TIME 16 6653
TEST_TIME 17 6807
TEST_TIME 18 7321
TEST_TIME 19 7571
TEST_TIME 20 7911
TEST_TIME 21 8016
TEST_TIME 22 8079
TEST_TIME 23 8522
TEST_TIME 24 8718
TEST_TIME 25 9090
TEST_TIME 26 9500
TEST_TIME 27 10868
TEST_TIME 28 11018
TEST_TIME 29 11026
TEST_TIME 30 10993
TEST_TIME 31 10816
TEST_TIME 32 10702
TEST_TIME 33 10577
TEST_TIME 34 10411
TEST_TIME 35 10198
TEST_TIME 36 10063
TEST_TIME 37 9967
TEST_TIME 38 9930
TEST_TIME 39 9794
TEST_TIME 40 9583
TEST_TIME 41 9593
TEST_TIME 42 9532
TEST_TIME 43 9466
TEST_TIME 44 9544
TEST_TIME 45 9577
TEST_TIME 46 9453
TEST_TIME 47 9302
TEST_TIME 48 9177
TEST_TIME 49 9123
TEST_TIME 50 9036
TEST_TIME 51 9029
TEST_TIME 52 8976
TEST_TIME 53 9006
TEST_TIME 54 8998
TEST_TIME 55 8974
TEST_TIME 56 8962
TEST_TIME 57 8980
TEST_TIME 58 8921
TEST_TIME 59 8886
TEST_TIME 60 8880
TEST_TIME 61 9159
TEST_TIME 62 9190
TEST_TIME 63 9194
TEST_TIME 64 9238
TEST_TIME 65 9275
TEST_TIME 66 9222
TEST_TIME 67 9106
TEST_TIME 68 9062
TEST_TIME 69 9035
TEST_TIME 70 9049
TEST_TIME 71 9055
TEST_TIME 72 9024
TEST_TIME 73 8975
TEST_TIME 74 8933
TEST_TIME 75 9012
TEST_TIME 76 9042
TEST_TIME 77 9055
TEST_TIME 78 9073
TEST_TIME 79 9093
TEST_TIME 80 9173
TEST_TIME 81 9176
TEST_TIME 82 9112
TEST_TIME 83 9192
TEST_TIME 84 9106
TEST_TIME 85 9010
TEST_TIME 86 8986
TEST_TIME 87 8928
TEST_TIME 88 8858
TEST_TIME 89 8801
TEST_TIME 90 8777
TEST_TIME 91 8866
TEST_TIME 92 8853
TEST_TIME 93 8835
TEST_TIME 94 8839
TEST_TIME 95 8880
TEST_TIME 96 8940
TEST_TIME 97 8887
TEST_TIME 98 8948
TEST_TIME 99 8995
TEST_TIME 100 9016
TEST_TIME 101 8985
TEST_TIME 102 8986
TEST_TIME 103 9022
TEST_TIME 104 9046
TEST_TIME 105 8974
TEST_TIME 106 8922
TEST_TIME 107 8874
TEST_TIME 108 8833
TEST_TIME 109 8814
TEST_TIME 110 8763
TEST_TIME 111 8738
TEST_TIME 112 8701
TEST_TIME 113 8688
TEST_TIME 114 8687
TEST_TIME 115 8688
TEST_TIME 116 8709
TEST_TIME 117 8713
TEST_TIME 118 8753
TEST_TIME 119 8728
TEST_TIME 120 8727

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-02-2021 07:37:38 to 20-02-2021 07:37:42.

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 1447451 1447451 0 0.00 0 17665 6 0 0
doc_list_biud 1447447 1447447 0 0.00 0 25987 33 0 0
sp_add_invoice_to_stock 4806 4704 102 2.12 23 154488 16515 0 102
sp_cancel_adding_invoice 5447 5447 0 0.00 4 83488 929 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 10281 345 0 0
sp_cancel_customer_reserve 531625 531625 0 0.00 1 18964 69 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 2230 59 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 3903 94 0 0
sp_cancel_supplier_invoice 6014 6000 14 0.23 3 25992 1063 0 14
sp_cancel_supplier_order 387 387 0 0.00 7 1162754 121496 0 0
sp_cancel_write_off 9486 9486 0 0.00 1 10371 130 0 0
sp_client_order 19114 19114 0 0.00 4 28983 438 0 0
sp_customer_reserve 433995 433995 0 0.00 0 33096 161 0 0
sp_fill_shopping_cart 29431 29431 0 0.00 1 24050 53 0 0
sp_fill_shopping_cart_clo_res 433982 433982 0 0.00 0 27232 17 0 0
sp_get_clo_for_invoice 4691 4691 0 0.00 0 20449 424 0 0
sp_kill_cost_storno 15960 15960 0 0.00 0 8581 59 0 0
sp_kill_qstorno_ret_qs2qd 1079123 1079123 0 0.00 0 25987 38 0 0
sp_kill_qty_storno 568602 568602 0 0.00 0 25987 82 0 0
sp_lock_selected_doc 19319 19319 0 0.00 0 10446 6 0 0
sp_make_cost_storno 26152 26152 0 0.00 0 17238 54 0 0
sp_multiply_rows_for_pdistr 26151 26151 0 0.00 0 10490 30 0 0
sp_multiply_rows_for_qdistr 19114 19114 0 0.00 0 28972 236 0 0
sp_pay_from_customer 6818 6818 0 0.00 4 13492 104 0 0
sp_pay_to_supplier 5390 5390 0 0.00 5 14115 147 0 0
sp_payment_common 12208 12208 0 0.00 1 13471 23 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 9170 299 0 0
sp_qd_handle_on_invoice_upd_sts 10137 10137 0 0.00 0 18517 388 0 0
sp_qd_handle_on_reserve_upd_sts 9253 9253 0 0.00 0 17027 54 0 0
sp_reserve_write_off 9253 9253 0 0.00 7 17695 185 0 0
sp_supplier_invoice 5248 5248 0 0.00 7 126849 3856 0 0
sp_supplier_order 5072 5072 0 0.00 5 29435 1473 0 0
srv_aggregate_perf_data 89 89 0 0.00 2333 319255 19874 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2389 113229 46857 0 0
srv_find_qd_qs_mism 1001414 1001414 0 0.00 0 11032 5 0 0
srv_make_invnt_saldo 439 439 0 0.00 27 109469 3479 0 0
srv_make_money_saldo 683 683 0 0.00 1 15809 576 0 0
srv_recalc_idx_stat 4 4 0 0.00 127518 355871 297109 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4708 4708 0 0.00 0 29397 160 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 1693 80 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 1699 11 0 0
t$perf-norm:sp_cancel_customer_reserve 607 607 0 0.00 0 436 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 611 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 17958 52 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 290 7 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 14616 1439 0 0
t$perf-norm:sp_cancel_write_off 897 897 0 0.00 0 3282 9 0 0
t$perf-norm:sp_client_order 19114 19114 0 0.00 0 14865 18 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 613 4 0 0
t$perf-norm:sp_pay_from_customer 6818 6818 0 0.00 0 4841 9 0 0
t$perf-norm:sp_pay_to_supplier 5390 5390 0 0.00 0 13126 12 0 0
t$perf-norm:sp_reserve_write_off 9253 9253 0 0.00 0 14381 13 0 0
t$perf-norm:sp_supplier_invoice 5248 5248 0 0.00 0 9762 20 0 0
t$perf-norm:sp_supplier_order 5072 5072 0 0.00 0 3731 16 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 384 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 1359 11 0 0
t$perf-norm:srv_make_money_saldo 683 683 0 0.00 0 771 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9584 9380 204 2.12 0 17139 21 0 204
v_all_customers 35542 35542 0 0.00 0 146 1 0 0
v_all_suppliers 28578 28578 0 0.00 0 23 1 0 0
v_all_wares 38228 38228 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 3173 13 0 0
v_cancel_client_order 3428 3428 0 0.00 0 9958 10 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 339 4 0 0
v_cancel_customer_reserve 1214 1214 0 0.00 0 1201 4 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3875 15 0 0
v_cancel_supplier_invoice 748 720 28 3.74 0 871 14 0 28
v_cancel_supplier_order 774 774 0 0.00 0 2429 9 0 0
v_cancel_write_off 1794 1794 0 0.00 0 10362 18 0 0
v_max_id_clo_ord 5072 5072 0 0.00 0 624 1 0 0
v_max_id_clo_res 929 929 0 0.00 0 7594 14 0 0
v_max_id_ord_sup 5245 5245 0 0.00 0 6526 7 0 0
v_max_non_paid_invoice 1418 1418 0 0.00 0 1295 3 0 0
v_max_non_paid_realizn 3450 3450 0 0.00 0 7218 4 0 0
v_min_id_clo_ord 5072 5072 0 0.00 0 2092 6 0 0
v_min_id_clo_res 929 929 0 0.00 0 7335 23 0 0
v_min_id_ord_sup 5245 5245 0 0.00 0 10429 14 0 0
v_min_non_paid_invoice 5390 5390 0 0.00 0 8049 19 0 0
v_min_non_paid_realizn 6818 6818 0 0.00 0 13466 14 0 0
v_our_firm 9422 9422 0 0.00 0 16 1 0 0
v_reserve_write_off 18506 18506 0 0.00 0 12704 9 0 0
x_lock_depdocs_on_canc_invoice 11087 11087 0 0.00 0 11009 122 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 11770 414 0 0
x_make_qstorno_1000_1200 5072 5072 0 0.00 1 29314 1393 0 0
x_make_qstorno_1200_2000 5248 5248 0 0.00 3 120462 3643 0 0
x_make_qstorno_2100_3300 433995 433995 0 0.00 0 33095 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2021 07:37:43 to 20-02-2021 07:37:47.

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:06:48 14 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:07:48 34142 8192000000 9715502720 10011168768 795491104 8.851 100 84 168 0 823395968 7468656 20436208
05:09:27 13435 8192000000 9761767024 10065993728 833638448 9.236 100 89 177 0 866848816 11977008 21233360
05:11:02 12530 8192000000 9816885280 10133090304 874643856 9.646 100 85 170 0 918158304 24169104 19345344
05:12:19 11587 8192000000 9879582144 10211766272 917800448 10.074 100 76 152 0 980920560 46814176 16305936
05:13:33 6835 8192000000 9929756640 10270961664 961855088 10.507 100 83 167 1 1040395904 61492768 17048048
05:14:41 4527 8192000000 9977180400 10330333184 997816960 10.857 100 83 166 1 1088519824 74445376 16257488
05:15:47 2660 8192000000 9997803744 10361450496 1028150768 11.151 100 83 165 0 1106926352 62104288 16671296
05:16:57 1474 8192000000 10022776736 10390032384 1060113200 11.458 100 89 178 1 1144189152 65567136 18508816
05:17:59 6391 8192000000 10026181088 10415706112 1070093776 11.553 100 50 100 0 1134914928 55020256 9800896
05:19:07 9209 8192000000 9993893312 10399535104 1081492304 11.662 100 40 81 2 1113253856 23841888 7919664
05:20:19 33321 8192000000 10003938288 10414669824 1090729040 11.750 100 36 72 0 1111779824 12236720 8814064
05:22:17 4469 8192000000 10020340080 10419720192 1092369104 11.765 100 48 92 3 1130554928 28453152 9732672
05:23:21 4364 8192000000 10024222704 10427228160 1101333872 11.850 100 59 118 1 1144579072 30865088 12380112
05:24:28 6668 8192000000 10042075600 10444062720 1103947776 11.875 100 39 77 1 1151917952 39888304 8081872
05:25:36 74126 8192000000 10034107504 10442547200 1069412544 11.546 97 33 64 0 1098462464 22234752 6815168
05:27:52 3513 8192000000 9992598896 10386018304 1059265408 11.449 98 52 102 1 1091268864 19244640 12758816
05:28:59 3962 8192000000 9949907664 10330574848 1008338656 10.959 97 42 83 0 1040767200 23204720 9223824
05:30:05 4000 8192000000 9910666016 10272047104 969163504 10.579 95 57 110 2 990534160 9183232 12187424
05:31:09 26019 8192000000 9923342176 10313658368 986049712 10.743 96 24 47 0 1014960224 23779824 5130688
05:32:39 3632 8192000000 9961084992 10344714240 1034358688 11.210 100 68 137 3 1068940336 18974560 15607088
05:33:46 7492 8192000000 9973695632 10364628992 1047873344 11.340 100 44 87 4 1077493216 19477264 10142608
05:34:57 7947 8192000000 9963343952 10357075968 1054410784 11.403 100 24 48 1 1073421200 13828064 5182352
05:36:07 28989 8192000000 9988634160 10392576000 1071736704 11.569 100 25 50 2 1100682992 23723152 5223136
05:37:41 17114 8192000000 10022306960 10421325824 1087562752 11.719 100 41 82 1 1126127968 30631184 7934032
05:39:03 52477 8192000000 10085380640 10491916288 1121908752 12.045 100 43 86 0 1192346864 61587632 8850480
05:41:04 9830 8192000000 10154057520 10558898176 1131885680 12.139 100 60 120 2 1265003712 119976784 13141248
05:42:18 32768 8192000000 10176765568 10587471872 1144496816 12.258 100 50 100 3 1294935232 139826880 10611536
05:43:58 3667 8192000000 10173804848 10586742784 1156823744 12.374 100 44 87 0 1285100864 118572416 9704704
05:45:07 21967 8192000000 10236865968 10651865088 1153772704 12.345 99 53 106 1 1328217680 162506672 11938304
05:46:35 8608 8192000000 10287881424 10704150528 1177467632 12.567 100 68 134 3 1392575280 200123552 14984096
05:47:49 4529 8192000000 10315788960 10726125568 1182295440 12.612 100 69 138 1 1417706096 219400032 16010624
05:48:59 6512 8192000000 10366666784 10782388224 1186693808 12.653 100 68 135 0 1464313104 261479920 16139376
05:50:11 2388 8192000000 10360503600 10773757952 1172078464 12.516 99 75 148 0 1454388384 265762640 16547280
05:51:17 7202 8192000000 10393602496 10807472128 1156679344 12.372 99 59 118 0 1488521328 317916944 13925040
05:52:27 7570 8192000000 10396676656 10808856576 1161600368 12.418 100 60 120 1 1494000480 318408016 13992096
05:53:42 2748 8192000000 10475131568 10881216512 1160019984 12.403 100 86 167 1 1574139216 395104832 19014400
05:54:48 11189 8192000000 10450055904 10879512576 1159696624 12.400 100 40 78 1 1559818160 390460080 9661456
05:56:07 4264 8192000000 10414271056 10853228544 1142981600 12.244 99 52 103 1 1514322752 358705152 12636000
05:57:15 7323 8192000000 10418108400 10857136128 1133128864 12.151 100 40 80 1 1527298160 384003536 10165760
05:58:26 12311 8192000000 10460057584 10882985984 1135960496 12.178 100 40 80 1 1559508592 413134208 10413888
05:59:41 3781 8192000000 10420277616 10860711936 1117137424 12.000 99 37 74 2 1512489744 386231424 9120896
06:00:52 20897 8192000000 10391095792 10846990336 1104158576 11.877 99 26 52 2 1508614256 396621248 7834432
06:02:16 27500 8192000000 10365003392 10852302848 1110648112 11.939 100 25 50 0 1489356240 371644384 7063744
06:03:55 32851 8192000000 10084177504 10536652800 1100739200 11.845 99 13 26 0 1197095264 93618384 2737680
06:05:31 75328 8192000000 10062683936 10509258752 1100882992 11.846 98 24 48 1 1150363456 44572352 4908112
06:07:49 89985 8192000000 10046720928 10497273856 1108354144 11.917 99 31 60 2 1150322528 35902176 6066208
06:10:35 51381 8192000000 10056179072 10487230464 1106669344 11.901 99 26 50 1 1137384912 26145056 4570512
06:12:28 75579 8192000000 10103020752 10550992896 1106835568 11.902 98 25 50 0 1183990608 71898944 5256096
06:14:49 113228 8192000000 10073579248 10544013312 1123172944 12.057 100 22 44 1 1171401152 43181024 5047184
06:18:00 67870 8192000000 10112634672 10577408000 1130070576 12.122 99 15 30 0 1192996896 59478432 3447888
06:20:34 65268 8192000000 10055411568 10589073408 1111627280 11.948 98 8 14 1 1187368160 74004288 1736592
06:22:41 81861 8192000000 10050048096 10502279168 1120611888 12.033 99 11 22 0 1151584208 28776736 2195584
06:25:06 86666 8192000000 10077065392 10563465216 1124486048 12.069 99 16 33 1 1192323056 64573120 3263888
06:29:16 51975 8192000000 10074036064 10531635200 1141120512 12.226 100 12 24 0 1165540992 21875344 2545136
06:31:08 33271 8192000000 10073220224 10512867328 1133634016 12.156 99 27 54 1 1163866752 24647024 5585712
06:32:45 39970 8192000000 10073540992 10531287040 1129330192 12.115 99 21 42 0 1165298832 31112144 4856496
06:34:31 29766 8192000000 10120103424 10568466432 1143961536 12.253 100 20 40 1 1219534288 71802944 3769808
06:36:01 46377 8192000000 10177736000 10649341952 1142408160 12.238 100 18 36 2 1273011808 126606288 3997360
06:38:07 55953 8192000000 10077423968 10546491392 1123966944 12.064 99 9 18 1 1158458368 32939536 1551888
06:40:26 54116 8192000000 10055400896 10505187328 1079109600 11.639 95 11 22 1 1105860976 24766224 1985152
06:42:22 103083 8192000000 10085427200 10533154816 1129007104 12.112 99 32 64 1 1170795904 35067152 6721648
06:45:09 63948 8192000000 10107116240 10588016640 1146919936 12.281 100 12 24 0 1224176224 74735552 2520736
06:48:27 54536 8192000000 10082648864 10529353728 1124310704 12.068 98 15 28 0 1176919248 50254368 2354176
06:50:24 108385 8192000000 10105939600 10558054400 1043128672 11.295 92 19 38 2 1130344160 82756624 4458864
06:53:13 63664 8192000000 10078686912 10516496384 1090061344 11.743 98 33 66 0 1145913248 48870704 6981200
06:56:23 61066 8192000000 10062915984 10521653248 1067821024 11.531 96 20 40 1 1144947664 73074976 4051664
06:58:30 33456 8192000000 10066169456 10508316672 1083392976 11.680 99 35 70 2 1148337952 57562832 7382144
07:00:13 84686 8192000000 10014664048 10453508096 1095914128 11.799 100 14 28 1 1132470976 33154624 3402224
07:02:38 48061 8192000000 10039829024 10490552320 1097473056 11.814 100 12 24 0 1139689424 39630960 2585408
07:04:27 45435 8192000000 10095427088 10550841344 1112165328 11.953 100 6 12 0 1188313168 74664336 1483504
07:06:13 71398 8192000000 10112475872 10607161344 1109502192 11.928 99 10 20 0 1218364896 107018960 1843744
07:08:26 36595 8192000000 10063554432 10517008384 1133061280 12.150 100 25 49 1 1180544288 42815664 4667344
07:10:06 62726 8192000000 10127324528 10591571968 1150454784 12.314 100 23 46 0 1249894736 94634352 4805600
07:12:16 77685 8192000000 10145869184 10595794944 1121382048 12.040 98 10 20 0 1193507840 69872304 2253488
07:14:37 51345 8192000000 10093904240 10566139904 1114355312 11.974 98 13 26 0 1192985584 76006128 2624144
07:16:44 85230 8192000000 10105319392 10529640448 1119142928 12.019 98 31 62 2 1149128000 23885280 6099792
07:19:13 101674 8192000000 10099447488 10606415872 1139618768 12.212 99 20 40 1 1189123712 45548576 3956368
07:23:09 50139 8192000000 10076673920 10537963520 1129686480 12.118 98 7 14 1 1150982224 19868960 1426784
07:25:00 78888 8192000000 10068231936 10558316544 1142783328 12.242 100 5 10 1 1188527216 44876208 867680
07:27:19 77915 8192000000 10086434896 10541969408 1142865728 12.242 100 20 40 0 1191656752 44885808 3905216
07:29:45 49929 8192000000 10114263888 10559205376 1097523264 11.814 95 28 55 1 1167034624 63875104 5636256
07:31:42 61937 8192000000 10101620416 10556895232 1100228288 11.840 96 19 38 3 1188586576 83935168 4423120
07:33:44 57157 8192000000 10101326416 10559807488 1119206288 12.019 98 22 44 2 1185157552 61477040 4474224
07:35:42 13048 8192000000 10076839520 10497982464 1106850656 11.903 98 49 96 1 1143345952 26434800 10060496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-02-2021 07:37:49 to 20-02-2021 07:38:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-02-2021 07:38:05 to 20-02-2021 07:38:06.

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          226091
MON$OLDEST_ACTIVE               228050
MON$OLDEST_SNAPSHOT             228050
MON$NEXT_TRANSACTION            228050
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                       2088096
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2371 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2371 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2371 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.2371 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    Sat Feb 20 07:36:46 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Feb 20 07:36:55 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Feb 20 07:36:55 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Feb 20 07:36:56 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

20.02.21 07:38:08. 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-02-20 07:38:10 No severe PSQL-related problems occured
20.02.21 07:38:11. End of report.