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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16583512 kB
MemAvailable 31143004 kB
Buffers 0 kB
Cached 15076544 kB
SwapCached 4640 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-15 10:26:55
DTS_END                         2021-02-15 10:26:55.4660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36092
TRN_ID                          232449
ATT_ID                          675
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: 8745.61 21110 1049473
customer order: creation 166.20 413 19944
customer order: refuse 14.11 331 1693
order to supplier: creation 43.09 1422 5171
order to supplier: removal 3.21 123714 385
invoice (draft): creation 43.66 4141 5239
invoice (draft): removal 49.48 957 5937
invoice accept: apply 39.89 18324 4787
invoice accept: cancel 44.73 916 5367
customer reserve: creation 3660.46 179 439255
customer reserve: removal 4396.97 71 527636
realization accept: apply 81.32 223 9758
realization accept: cancel 78.68 115 9442
payment from customer: creation 57.57 138 6908
payment from customer: removal 5.33 115 640
payment to supplier: creation 47.05 195 5646
payment to supplier: removal 3.58 110 430
service: total inventory turnovers 3.78 3848 453
service: total monetary turnovers 5.71 660 685
service: aggregate perf. data 0.78 16457 93
service: refresh index statistics 0.03 249878 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-02-2021 10:27:52 to 15-02-2021 10:27:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1283
TEST_TIME 2 2045
TEST_TIME 3 2123
TEST_TIME 4 2942
TEST_TIME 5 3465
TEST_TIME 6 4047
TEST_TIME 7 4668
TEST_TIME 8 4804
TEST_TIME 9 4935
TEST_TIME 10 5292
TEST_TIME 11 5308
TEST_TIME 12 5363
TEST_TIME 13 5611
TEST_TIME 14 5917
TEST_TIME 15 6112
TEST_TIME 16 6282
TEST_TIME 17 6682
TEST_TIME 18 6752
TEST_TIME 19 6768
TEST_TIME 20 7236
TEST_TIME 21 8102
TEST_TIME 22 8278
TEST_TIME 23 8728
TEST_TIME 24 8664
TEST_TIME 25 8755
TEST_TIME 26 8716
TEST_TIME 27 8757
TEST_TIME 28 8978
TEST_TIME 29 9253
TEST_TIME 30 9826
TEST_TIME 31 10098
TEST_TIME 32 9986
TEST_TIME 33 10000
TEST_TIME 34 9819
TEST_TIME 35 9887
TEST_TIME 36 9747
TEST_TIME 37 9585
TEST_TIME 38 9610
TEST_TIME 39 9471
TEST_TIME 40 9336
TEST_TIME 41 9298
TEST_TIME 42 9156
TEST_TIME 43 9054
TEST_TIME 44 8868
TEST_TIME 45 9138
TEST_TIME 46 9263
TEST_TIME 47 9090
TEST_TIME 48 9035
TEST_TIME 49 8978
TEST_TIME 50 9002
TEST_TIME 51 8919
TEST_TIME 52 8814
TEST_TIME 53 8703
TEST_TIME 54 8696
TEST_TIME 55 8618
TEST_TIME 56 8683
TEST_TIME 57 8744
TEST_TIME 58 8736
TEST_TIME 59 8799
TEST_TIME 60 8732
TEST_TIME 61 8787
TEST_TIME 62 8912
TEST_TIME 63 8879
TEST_TIME 64 8929
TEST_TIME 65 8948
TEST_TIME 66 8939
TEST_TIME 67 9028
TEST_TIME 68 9041
TEST_TIME 69 9005
TEST_TIME 70 9098
TEST_TIME 71 9023
TEST_TIME 72 8908
TEST_TIME 73 8854
TEST_TIME 74 8808
TEST_TIME 75 8739
TEST_TIME 76 8681
TEST_TIME 77 8604
TEST_TIME 78 8723
TEST_TIME 79 8671
TEST_TIME 80 8690
TEST_TIME 81 8659
TEST_TIME 82 8750
TEST_TIME 83 8768
TEST_TIME 84 8754
TEST_TIME 85 8765
TEST_TIME 86 8745
TEST_TIME 87 8759
TEST_TIME 88 8823
TEST_TIME 89 8802
TEST_TIME 90 8767
TEST_TIME 91 8684
TEST_TIME 92 8645
TEST_TIME 93 8618
TEST_TIME 94 8593
TEST_TIME 95 8620
TEST_TIME 96 8595
TEST_TIME 97 8614
TEST_TIME 98 8666
TEST_TIME 99 8680
TEST_TIME 100 8665
TEST_TIME 101 8643
TEST_TIME 102 8679
TEST_TIME 103 8731
TEST_TIME 104 8746
TEST_TIME 105 8833
TEST_TIME 106 8818
TEST_TIME 107 8831
TEST_TIME 108 8780
TEST_TIME 109 8725
TEST_TIME 110 8688
TEST_TIME 111 8633
TEST_TIME 112 8597
TEST_TIME 113 8622
TEST_TIME 114 8601
TEST_TIME 115 8620
TEST_TIME 116 8620
TEST_TIME 117 8595
TEST_TIME 118 8571
TEST_TIME 119 8577
TEST_TIME 120 8748

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-02-2021 10:27:56 to 15-02-2021 10:27:59.

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 1454584 1454584 0 0.00 0 19714 7 0 0
doc_list_biud 1454584 1454584 0 0.00 0 64701 33 0 0
sp_add_invoice_to_stock 4858 4787 71 1.46 43 174278 18325 0 71
sp_cancel_adding_invoice 5367 5367 0 0.00 5 92450 916 0 0
sp_cancel_client_order 1693 1693 0 0.00 4 8440 331 0 0
sp_cancel_customer_reserve 527636 527636 0 0.00 1 34532 72 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 15502 116 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 5049 111 0 0
sp_cancel_supplier_invoice 5942 5937 5 0.08 1 64704 957 0 5
sp_cancel_supplier_order 385 385 0 0.00 5 1113567 123714 0 0
sp_cancel_write_off 9442 9442 0 0.00 1 6596 115 0 0
sp_client_order 19944 19944 0 0.00 4 21570 413 0 0
sp_customer_reserve 439255 439255 0 0.00 0 44863 180 0 0
sp_fill_shopping_cart 30342 30342 0 0.00 1 32546 52 0 0
sp_fill_shopping_cart_clo_res 439249 439249 0 0.00 0 29550 18 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 34500 389 0 0
sp_kill_cost_storno 15879 15879 0 0.00 0 15495 64 0 0
sp_kill_qstorno_ret_qs2qd 1071036 1071036 0 0.00 0 64700 39 0 0
sp_kill_qty_storno 564999 564999 0 0.00 0 64701 83 0 0
sp_lock_selected_doc 20024 20024 0 0.00 0 9429 5 0 0
sp_make_cost_storno 27093 27093 0 0.00 0 18824 81 0 0
sp_multiply_rows_for_pdistr 27093 27093 0 0.00 0 14062 40 0 0
sp_multiply_rows_for_qdistr 19944 19944 0 0.00 0 18452 220 0 0
sp_pay_from_customer 6908 6908 0 0.00 4 18757 139 0 0
sp_pay_to_supplier 5646 5646 0 0.00 6 19734 196 0 0
sp_payment_common 12554 12554 0 0.00 1 18686 31 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 8432 304 0 0
sp_qd_handle_on_invoice_upd_sts 10148 10148 0 0.00 0 31120 397 0 0
sp_qd_handle_on_reserve_upd_sts 9758 9758 0 0.00 0 12111 59 0 0
sp_reserve_write_off 9758 9758 0 0.00 6 24245 224 0 0
sp_supplier_invoice 5239 5239 0 0.00 7 114780 4142 0 0
sp_supplier_order 5171 5171 0 0.00 5 29811 1422 0 0
srv_aggregate_perf_data 93 93 0 0.00 2461 139736 16458 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1319 141191 47639 0 0
srv_find_qd_qs_mism 1002987 1002987 0 0.00 0 19714 6 0 0
srv_make_invnt_saldo 453 453 0 0.00 51 121871 3849 0 0
srv_make_money_saldo 685 685 0 0.00 1 14630 661 0 0
srv_recalc_idx_stat 4 4 0 0.00 141912 351098 249878 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4787 4787 0 0.00 0 9937 179 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 4897 108 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 6755 18 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 3040 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 1399 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 722 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 901 19 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 20684 1607 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 1994 10 0 0
t$perf-norm:sp_client_order 19944 19944 0 0.00 0 6094 18 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 426 4 0 0
t$perf-norm:sp_pay_from_customer 6908 6908 0 0.00 0 9173 12 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 9161 10 0 0
t$perf-norm:sp_reserve_write_off 9758 9758 0 0.00 0 6015 12 0 0
t$perf-norm:sp_supplier_invoice 5239 5239 0 0.00 0 5833 19 0 0
t$perf-norm:sp_supplier_order 5171 5171 0 0.00 0 5780 19 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 379 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 278 6 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 4953 19 0 0
t$perf-norm:srv_make_money_saldo 685 685 0 0.00 0 1533 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 9704 9562 142 1.46 0 9214 24 0 142
v_all_customers 36386 36386 0 0.00 0 20 1 0 0
v_all_suppliers 28708 28708 0 0.00 0 21 1 0 0
v_all_wares 39888 39888 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1204 9 0 0
v_cancel_client_order 3386 3386 0 0.00 0 1351 3 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 5215 9 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 1515 6 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 3452 10 0 0
v_cancel_supplier_invoice 766 756 10 1.30 0 1564 15 0 10
v_cancel_supplier_order 770 770 0 0.00 0 2175 10 0 0
v_cancel_write_off 1858 1858 0 0.00 0 1254 14 0 0
v_max_id_clo_ord 5168 5168 0 0.00 0 4142 2 0 0
v_max_id_clo_res 934 934 0 0.00 0 3466 17 0 0
v_max_id_ord_sup 5230 5230 0 0.00 0 5171 5 0 0
v_max_non_paid_invoice 1690 1690 0 0.00 0 722 2 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 2866 5 0 0
v_min_id_clo_ord 5168 5168 0 0.00 0 1465 4 0 0
v_min_id_clo_res 934 934 0 0.00 0 3368 12 0 0
v_min_id_ord_sup 5230 5230 0 0.00 0 16365 13 0 0
v_min_non_paid_invoice 5646 5646 0 0.00 0 8426 22 0 0
v_min_non_paid_realizn 6908 6908 0 0.00 0 4636 18 0 0
v_our_firm 9976 9976 0 0.00 0 17 1 0 0
v_reserve_write_off 19516 19516 0 0.00 0 9222 7 0 0
x_lock_depdocs_on_canc_invoice 10926 10926 0 0.00 0 23312 140 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 19761 412 0 0
x_make_qstorno_1000_1200 5171 5171 0 0.00 2 29797 1333 0 0
x_make_qstorno_1200_2000 5239 5239 0 0.00 3 113653 3932 0 0
x_make_qstorno_2100_3300 439255 439255 0 0.00 0 44860 160 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:59 5 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:57:59 25869 8192000000 9714673728 10007875584 796724624 8.863 100 88 176 0 826619072 7699328 22195120
07:59:31 21879 8192000000 9762347600 10065104896 834683536 9.246 100 88 176 0 867075616 11499904 20892176
08:00:57 13812 8192000000 9786404240 10104004608 864297296 9.543 100 80 160 0 900937008 18372256 18267456
08:02:17 8997 8192000000 9866943360 10198310912 923881824 10.134 100 85 170 0 974683152 32156992 18644336
08:03:29 12055 8192000000 9931940480 10273615872 963639456 10.525 100 89 178 0 1040557376 58524272 18393648
08:04:44 5106 8192000000 9968961280 10318569472 990963008 10.791 100 86 170 0 1084105648 76150496 16992144
08:05:52 3977 8192000000 10014788512 10376667136 1020289392 11.075 100 88 176 0 1123597360 85830112 17477856
08:06:57 1201 8192000000 10009860768 10377117696 1050543200 11.366 100 94 186 3 1124898672 54935072 19420400
08:08:00 1500 8192000000 10059771312 10430050304 1065259888 11.507 100 92 186 1 1172615296 88386768 18968640
08:09:04 14190 8192000000 10026280640 10421489664 1069564960 11.548 100 41 82 1 1166580128 89107152 7908016
08:10:19 9214 8192000000 10000589216 10388107264 1079510784 11.643 100 36 70 1 1117711680 30256800 7944096
08:11:34 9082 8192000000 10012306960 10412077056 1087410576 11.718 100 22 44 4 1114203568 21094048 5698944
08:12:45 55821 8192000000 9994200080 10395627520 1094433792 11.785 100 25 50 2 1119627328 19365088 5828448
08:14:56 4155 8192000000 10027177920 10422661120 1099533776 11.833 100 37 75 1 1140509120 32392560 8582784
08:16:01 5495 8192000000 10014784768 10417754112 1100257232 11.840 100 37 74 2 1131192448 21830288 9104928
08:17:08 8265 8192000000 9997882496 10396610560 1065022272 11.505 98 34 68 2 1105944976 34073232 6849472
08:18:18 13996 8192000000 9959900400 10351648768 1003727280 10.915 93 40 80 0 1028988160 16445072 8815808
08:19:36 4437 8192000000 9947119120 10321403904 1026534752 11.135 99 38 66 6 1056963664 22225248 8203664
08:20:43 11801 8192000000 9923631504 10309509120 988260864 10.765 98 22 43 2 1024765136 31631280 4872992
08:21:56 47594 8192000000 9942420896 10321252352 1031383344 11.182 100 39 75 2 1051169200 12006016 7779840
08:23:45 9237 8192000000 9971378736 10375413760 1048872224 11.350 100 32 65 2 1082444496 26328672 7243600
08:24:56 18037 8192000000 9969990192 10377814016 1058328016 11.440 100 19 38 0 1083515760 21040560 4147184
08:26:17 4370 8192000000 9990063360 10384842752 1081249632 11.659 100 38 77 1 1106875552 17373568 8252352
08:27:23 92634 8192000000 10041846784 10440769536 1100522976 11.843 100 23 47 1 1129514912 24172704 4819232
08:30:01 21788 8192000000 10107066464 10513596416 1117118928 12.000 100 51 101 0 1216525104 89011984 10394192
08:31:32 5352 8192000000 10143177120 10546216960 1141010576 12.225 100 57 113 0 1242650416 89602176 12037664
08:32:45 2916 8192000000 10188141440 10588069888 1151930624 12.328 100 73 139 0 1288400352 120976416 15493312
08:33:51 16579 8192000000 10172076896 10587873280 1155454896 12.361 100 39 78 1 1286311072 122441728 8414448
08:35:19 20554 8192000000 10256138432 10681913344 1160269184 12.406 100 48 96 1 1365771008 194733616 10768208
08:36:56 4712 8192000000 10222192544 10640596992 1171372736 12.510 100 52 104 0 1331208800 148428912 11407152
08:38:06 7603 8192000000 10252392240 10666237952 1162944672 12.431 100 47 93 3 1355434720 182303568 10186480
08:39:22 2885 8192000000 10243182016 10650173440 1152876912 12.336 100 62 123 0 1346322464 179864448 13581104
08:40:28 5336 8192000000 10276289760 10702438400 1156177456 12.367 100 72 141 0 1381494736 209806560 15510720
08:41:38 4326 8192000000 10306310128 10716459008 1165407856 12.454 100 72 144 0 1405114288 223314064 16392368
08:42:49 2473 8192000000 10342427072 10758082560 1164693264 12.447 100 90 178 0 1442428720 256935648 20799808
08:43:55 1655 8192000000 10329148192 10728493056 1134832960 12.167 99 83 163 2 1417931136 264064720 19033456
08:45:06 1316 8192000000 10355199168 10751160320 1122657440 12.052 98 78 155 2 1445387968 303642064 19088464
08:46:14 2603 8192000000 10382804592 10779930624 1117298208 12.001 99 84 169 1 1478729424 340701184 20730032
08:47:19 10345 8192000000 10350181168 10766692352 1132565824 12.146 100 41 82 0 1446672016 302888352 11217840
08:48:35 10398 8192000000 10287896896 10721988608 1127083792 12.094 100 39 78 0 1392289968 255291616 9914560
08:49:49 15302 8192000000 10239302128 10678255616 1117830992 12.006 98 32 64 0 1348449568 222484800 8133776
08:51:25 13160 8192000000 10263959696 10701172736 1127761120 12.100 100 29 58 2 1374744160 239160752 7822288
08:52:44 30565 8192000000 10324876352 10752843776 1122752336 12.053 99 23 46 3 1405560464 276794848 6013280
08:54:36 15840 8192000000 10323351232 10766962688 1127601760 12.099 100 28 57 0 1425054464 290387392 7065312
08:56:00 24178 8192000000 10173748112 10663501824 1125098688 12.075 100 17 34 2 1275095456 146027232 3969536
08:57:27 12092 8192000000 10130921248 10555547648 1139075824 12.207 100 29 58 1 1224779344 78835056 6868464
08:58:39 95820 8192000000 10111962544 10547494912 1117598032 12.004 98 31 62 2 1239027824 114424368 7005424
09:01:28 35151 8192000000 10071481808 10510950400 1124365344 12.068 99 31 61 0 1165373232 34860672 6147216
09:03:05 72381 8192000000 10094839520 10544660480 1142903792 12.243 100 33 66 1 1196010016 45988240 7117984
09:05:23 93169 8192000000 10069090016 10502017024 1118276368 12.011 98 16 33 1 1157810976 36315984 3218624
09:08:22 59992 8192000000 10146725968 10579111936 1134826928 12.167 99 22 44 0 1218527824 78468624 5232272
09:10:30 112787 8192000000 10094321392 10711252992 1139629024 12.212 99 19 38 0 1296322352 152336944 4356384
09:13:34 47479 8192000000 10098632144 10566389760 1134820848 12.167 99 32 63 2 1183679312 42294176 6564288
09:15:22 69169 8192000000 10103217056 10583072768 1151588256 12.324 100 18 36 0 1219816320 64673840 3554224
09:17:36 84863 8192000000 10102114576 10545152000 1151228336 12.321 100 12 24 0 1197183456 42927376 3027744
09:20:07 43068 8192000000 10117957584 10613047296 1158099232 12.385 100 13 26 1 1220762096 59440448 3222416
09:21:50 33773 8192000000 10133292304 10593075200 1145993600 12.272 100 14 28 0 1234349808 85065616 3290592
09:23:33 81825 8192000000 10101466848 10578563072 1139976160 12.215 99 10 20 0 1221963552 79785168 2202224
09:26:06 45038 8192000000 10132868112 10579095552 1137566784 12.193 99 38 76 2 1191316560 46117840 7631936
09:27:58 58924 8192000000 10089519360 10599608320 1137475040 12.192 99 13 25 1 1205803728 65714160 2614528
09:30:00 36026 8192000000 10073906976 10523418624 1079508128 11.643 95 24 46 2 1134577280 49964160 5104992
09:31:38 70587 8192000000 10114657840 10571030528 1099358384 11.832 97 17 34 0 1167013216 64116016 3538816
09:35:18 61811 8192000000 10088362704 10557177856 1111155824 11.943 98 30 58 2 1164001600 46753968 6091808
09:37:24 115039 8192000000 10042682384 10477256704 1086580352 11.710 97 20 42 1 1130918080 40158608 4179120
09:40:43 28658 8192000000 10040730256 10500833280 1091866912 11.760 98 15 30 1 1126389376 31526848 2995616
09:42:12 38361 8192000000 10071271984 10524590080 1103057520 11.867 98 16 32 0 1155217328 48565264 3594544
09:43:51 86093 8192000000 10101723776 10629083136 1100377728 11.841 98 16 32 0 1190193312 86142224 3673360
09:46:50 46493 8192000000 10070118112 10508070912 1053232304 11.392 95 16 32 2 1133774240 76817904 3724032
09:48:37 48389 8192000000 10011631888 10469040128 1077064160 11.619 99 21 42 0 1139640960 57559824 5016976
09:50:26 23070 8192000000 10036439856 10471227392 1072195728 11.573 99 44 88 1 1120154208 39034896 8923584
09:51:53 39941 8192000000 10053080032 10473263104 1097835200 11.817 100 41 80 0 1161457760 55671984 7950576
09:53:38 60013 8192000000 10058717696 10513563648 1079230096 11.640 98 28 56 0 1147559360 62633488 5695776
09:55:44 107861 8192000000 10029392880 10469232640 1097973808 11.818 99 27 54 1 1135635040 32480064 5181168
09:59:03 80609 8192000000 10050083696 10514059264 1097687280 11.816 98 9 18 1 1129015312 29669488 1658544
10:01:34 100651 8192000000 10112173488 10581475328 1132809536 12.148 100 23 45 1 1204953760 67408608 4735616
10:04:18 40572 8192000000 10084854816 10554048512 1112688912 11.958 98 24 47 2 1188350432 70213408 5448112
10:06:03 35262 8192000000 10069101152 10507132928 1134417824 12.163 100 32 63 3 1165798496 24843808 6536864
10:07:40 60346 8192000000 10123331504 10597564416 1140527488 12.220 100 33 65 1 1208602800 60792176 7283136
10:09:47 141189 8192000000 10076033536 10558238720 1093253616 11.774 95 8 16 0 1171139456 75678688 2207152
10:13:27 88061 8192000000 10083516768 10537857024 1117787248 12.006 99 22 44 1 1166042288 43583408 4671632
10:16:46 93927 8192000000 10078224496 10527748096 1129820800 12.120 99 8 16 1 1180957248 49125264 2011184
10:19:35 104941 8192000000 10056926128 10522595328 1098717856 11.825 97 13 27 1 1152457600 51293504 2446240
10:22:21 63778 8192000000 10119291776 10574962688 1141671472 12.231 100 23 46 1 1220414624 73755440 4987712
10:24:38 76114 8192000000 10184353920 10650099712 1128736784 12.109 98 21 42 3 1248777872 115106752 4934336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-02-2021 10:28:06 to 15-02-2021 10:28:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 71 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-02-2021 10:28:22 to 15-02-2021 10:28:24.

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          231960
MON$OLDEST_ACTIVE               232669
MON$OLDEST_SNAPSHOT             232669
MON$NEXT_TRANSACTION            232669
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                       2089504
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:483: 
:484: mscs-test-161.budzdorov.ru    Mon Feb 15 10:26:59 2021
:485:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:486: 
:487: 
:488: mscs-test-161.budzdorov.ru    Mon Feb 15 10:27:15 2021
:489:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:490: 
--- end of diff output ---

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

15.02.21 10:28:25. 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-15 10:28:28 No severe PSQL-related problems occured
15.02.21 10:28:29. End of report.