Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.11.2020 10:29
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 16918944 kB
MemAvailable 31565024 kB
Buffers 0 kB
Cached 14698912 kB
SwapCached 2036 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-11-30 10:26:52
DTS_END                         2020-11-30 10:26:52.6530
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/54742
TRN_ID                          246601
ATT_ID                          686
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: 9043.01 18841 1085161
customer order: creation 189.01 328 22681
customer order: refuse 15.89 320 1907
order to supplier: creation 50.17 1284 6020
order to supplier: removal 3.38 89773 406
invoice (draft): creation 49.28 2714 5914
invoice (draft): removal 47.58 769 5710
invoice accept: apply 44.42 16228 5330
invoice accept: cancel 43.26 667 5191
customer reserve: creation 4069.79 162 488375
customer reserve: removal 4223.83 57 506860
realization accept: apply 92.03 152 11043
realization accept: cancel 75.97 126 9117
payment from customer: creation 63.24 107 7589
payment from customer: removal 6.48 64 778
payment to supplier: creation 53.08 107 6370
payment to supplier: removal 3.80 93 456
service: total inventory turnovers 4.32 2971 518
service: total monetary turnovers 6.47 505 776
service: aggregate perf. data 0.97 14568 116
service: refresh index statistics 0.03 245833 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-11-2020 10:29:46 to 30-11-2020 10:29:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2025
TEST_TIME 2 3764
TEST_TIME 3 4535
TEST_TIME 4 4315
TEST_TIME 5 4775
TEST_TIME 6 4782
TEST_TIME 7 5021
TEST_TIME 8 5283
TEST_TIME 9 5479
TEST_TIME 10 5479
TEST_TIME 11 5949
TEST_TIME 12 6644
TEST_TIME 13 6813
TEST_TIME 14 7398
TEST_TIME 15 7290
TEST_TIME 16 7673
TEST_TIME 17 7753
TEST_TIME 18 8283
TEST_TIME 19 8702
TEST_TIME 20 8669
TEST_TIME 21 8751
TEST_TIME 22 9117
TEST_TIME 23 10058
TEST_TIME 24 10513
TEST_TIME 25 10492
TEST_TIME 26 10925
TEST_TIME 27 10801
TEST_TIME 28 10792
TEST_TIME 29 10674
TEST_TIME 30 10657
TEST_TIME 31 10528
TEST_TIME 32 10683
TEST_TIME 33 10759
TEST_TIME 34 10811
TEST_TIME 35 10820
TEST_TIME 36 10767
TEST_TIME 37 10542
TEST_TIME 38 10296
TEST_TIME 39 10119
TEST_TIME 40 10120
TEST_TIME 41 10039
TEST_TIME 42 10104
TEST_TIME 43 10141
TEST_TIME 44 10031
TEST_TIME 45 9961
TEST_TIME 46 9954
TEST_TIME 47 9996
TEST_TIME 48 9909
TEST_TIME 49 9963
TEST_TIME 50 9939
TEST_TIME 51 9925
TEST_TIME 52 9933
TEST_TIME 53 9940
TEST_TIME 54 10112
TEST_TIME 55 10000
TEST_TIME 56 10029
TEST_TIME 57 10094
TEST_TIME 58 10119
TEST_TIME 59 10142
TEST_TIME 60 9983
TEST_TIME 61 9867
TEST_TIME 62 9824
TEST_TIME 63 9764
TEST_TIME 64 9750
TEST_TIME 65 9760
TEST_TIME 66 9747
TEST_TIME 67 9751
TEST_TIME 68 9719
TEST_TIME 69 9722
TEST_TIME 70 9793
TEST_TIME 71 9773
TEST_TIME 72 9745
TEST_TIME 73 9702
TEST_TIME 74 9893
TEST_TIME 75 9861
TEST_TIME 76 9879
TEST_TIME 77 9892
TEST_TIME 78 9987
TEST_TIME 79 9884
TEST_TIME 80 9790
TEST_TIME 81 9759
TEST_TIME 82 9694
TEST_TIME 83 9625
TEST_TIME 84 9556
TEST_TIME 85 9517
TEST_TIME 86 9475
TEST_TIME 87 9440
TEST_TIME 88 9403
TEST_TIME 89 9468
TEST_TIME 90 9438
TEST_TIME 91 9440
TEST_TIME 92 9398
TEST_TIME 93 9434
TEST_TIME 94 9426
TEST_TIME 95 9433
TEST_TIME 96 9456
TEST_TIME 97 9429
TEST_TIME 98 9447
TEST_TIME 99 9445
TEST_TIME 100 9481
TEST_TIME 101 9394
TEST_TIME 102 9344
TEST_TIME 103 9323
TEST_TIME 104 9261
TEST_TIME 105 9207
TEST_TIME 106 9227
TEST_TIME 107 9213
TEST_TIME 108 9215
TEST_TIME 109 9206
TEST_TIME 110 9205
TEST_TIME 111 9204
TEST_TIME 112 9192
TEST_TIME 113 9182
TEST_TIME 114 9173
TEST_TIME 115 9181
TEST_TIME 116 9186
TEST_TIME 117 9212
TEST_TIME 118 9177
TEST_TIME 119 9103
TEST_TIME 120 9043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-11-2020 10:29:50 to 30-11-2020 10:29:53.

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 1537557 1537557 0 0.00 0 21713 7 0 0
doc_list_biud 1537557 1537557 0 0.00 0 38885 24 0 0
sp_add_invoice_to_stock 5489 5330 159 2.89 27 165237 16229 0 159
sp_cancel_adding_invoice 5191 5191 0 0.00 5 64786 667 0 0
sp_cancel_client_order 1907 1907 0 0.00 4 9052 321 0 0
sp_cancel_customer_reserve 506860 506860 0 0.00 1 22398 58 0 0
sp_cancel_pay_from_customer 778 778 0 0.00 3 5283 65 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 3 5852 93 0 0
sp_cancel_supplier_invoice 5727 5710 17 0.29 2 14124 769 0 17
sp_cancel_supplier_order 406 406 0 0.00 8 1158770 89774 0 0
sp_cancel_write_off 9117 9117 0 0.00 1 10883 126 0 0
sp_client_order 22681 22681 0 0.00 4 18059 328 0 0
sp_customer_reserve 488375 488375 0 0.00 0 52789 163 0 0
sp_fill_shopping_cart 34612 34612 0 0.00 1 17280 38 0 0
sp_fill_shopping_cart_clo_res 488374 488374 0 0.00 0 16800 14 0 0
sp_get_clo_for_invoice 5329 5329 0 0.00 0 35968 295 0 0
sp_kill_cost_storno 15542 15542 0 0.00 0 8116 53 0 0
sp_kill_qstorno_ret_qs2qd 1028953 1028953 0 0.00 0 22345 31 0 0
sp_kill_qty_storno 545563 545563 0 0.00 0 38885 67 0 0
sp_lock_selected_doc 22604 22604 0 0.00 0 8664 4 0 0
sp_make_cost_storno 30331 30331 0 0.00 0 21319 51 0 0
sp_multiply_rows_for_pdistr 30331 30331 0 0.00 0 13271 24 0 0
sp_multiply_rows_for_qdistr 22681 22681 0 0.00 0 17574 160 0 0
sp_pay_from_customer 7589 7589 0 0.00 4 13435 107 0 0
sp_pay_to_supplier 6370 6370 0 0.00 5 9321 107 0 0
sp_payment_common 13959 13959 0 0.00 1 7397 18 0 0
sp_qd_handle_on_cancel_clo 1907 1907 0 0.00 0 8121 291 0 0
sp_qd_handle_on_invoice_upd_sts 10520 10520 0 0.00 0 38884 290 0 0
sp_qd_handle_on_reserve_upd_sts 11043 11043 0 0.00 0 22089 44 0 0
sp_reserve_write_off 11043 11043 0 0.00 6 22101 152 0 0
sp_supplier_invoice 5914 5914 0 0.00 6 74907 2714 0 0
sp_supplier_order 6020 6020 0 0.00 6 32437 1284 0 0
srv_aggregate_perf_data 116 116 0 0.00 2622 174977 14568 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2903 151044 47047 0 0
srv_find_qd_qs_mism 1033964 1033964 0 0.00 0 10192 7 0 0
srv_make_invnt_saldo 518 518 0 0.00 27 118612 2972 0 0
srv_make_money_saldo 776 776 0 0.00 1 22866 505 0 0
srv_recalc_idx_stat 4 4 0 0.00 86057 459347 245833 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5330 5330 0 0.00 0 23908 143 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 3250 65 0 0
t$perf-norm:sp_cancel_client_order 1907 1907 0 0.00 0 3920 13 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 3002 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 778 778 0 0.00 0 4470 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 719 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 2496 25 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 15049 1296 0 0
t$perf-norm:sp_cancel_write_off 1159 1159 0 0.00 0 2968 8 0 0
t$perf-norm:sp_client_order 22681 22681 0 0.00 0 39163 15 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 1908 5 0 0
t$perf-norm:sp_pay_from_customer 7589 7589 0 0.00 0 3214 6 0 0
t$perf-norm:sp_pay_to_supplier 6370 6370 0 0.00 0 4570 10 0 0
t$perf-norm:sp_reserve_write_off 11043 11043 0 0.00 0 6588 9 0 0
t$perf-norm:sp_supplier_invoice 5914 5914 0 0.00 0 3493 12 0 0
t$perf-norm:sp_supplier_order 6020 6020 0 0.00 0 7581 11 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 426 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 675 18 0 0
t$perf-norm:srv_make_invnt_saldo 518 518 0 0.00 0 1682 12 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 1188 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 10 0 0
v_add_invoice_to_stock 10976 10658 318 2.89 0 5466 15 0 318
v_all_customers 40632 40632 0 0.00 0 15 1 0 0
v_all_suppliers 32688 32688 0 0.00 0 18 1 0 0
v_all_wares 45362 45362 0 0.00 0 128 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 5496 12 0 0
v_cancel_client_order 3814 3814 0 0.00 0 2461 5 0 0
v_cancel_customer_prepayment 1556 1556 0 0.00 0 1576 5 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 708 3 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 2810 10 0 0
v_cancel_supplier_invoice 860 826 34 3.95 0 855 11 0 34
v_cancel_supplier_order 812 812 0 0.00 0 2220 6 0 0
v_cancel_write_off 2318 2318 0 0.00 0 5207 14 0 0
v_max_id_clo_ord 6017 6017 0 0.00 0 454 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 5972 20 0 0
v_max_id_ord_sup 5913 5913 0 0.00 0 6029 5 0 0
v_max_non_paid_invoice 1956 1956 0 0.00 0 302 1 0 0
v_max_non_paid_realizn 4284 4284 0 0.00 0 1546 3 0 0
v_min_id_clo_ord 6017 6017 0 0.00 0 3605 5 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 7749 13 0 0
v_min_id_ord_sup 5913 5913 0 0.00 0 8731 14 0 0
v_min_non_paid_invoice 6370 6370 0 0.00 0 7345 12 0 0
v_min_non_paid_realizn 7589 7589 0 0.00 0 7392 11 0 0
v_our_firm 11340 11340 0 0.00 0 14 1 0 0
v_reserve_write_off 22086 22086 0 0.00 0 6076 5 0 0
x_lock_depdocs_on_canc_invoice 10488 10488 0 0.00 0 8990 82 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 2879 225 0 0
x_make_qstorno_1000_1200 6020 6020 0 0.00 2 32415 1216 0 0
x_make_qstorno_1200_2000 5914 5914 0 0.00 3 74531 2568 0 0
x_make_qstorno_2100_3300 488375 488375 0 0.00 0 46069 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-11-2020 10:29:54 to 30-11-2020 10:29:58.

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:56 1 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:56 33776 8192000000 9722683616 10017841152 793196304 8.827 100 89 177 1 822708448 8074000 21438144
07:59:40 26155 8192000000 9765022512 10074161152 838484512 9.285 100 90 179 1 874170528 14822624 20863392
08:01:10 20761 8192000000 9807167568 10128375808 866320144 9.563 100 80 160 0 911546208 27244272 17981792
08:02:34 10230 8192000000 9868302512 10202890240 911573584 10.013 100 77 152 1 981863632 53835536 16454512
08:03:47 10846 8192000000 9921800576 10265088000 951997696 10.411 100 87 174 2 1034605408 63940112 18667600
08:05:01 5172 8192000000 9966631936 10318594048 989818448 10.780 100 83 161 3 1076135904 69389360 16928096
08:06:09 2837 8192000000 9989378320 10349932544 1013381168 11.008 100 85 169 0 1099918688 69392864 17144656
08:07:13 2466 8192000000 10011566832 10380427264 1041427568 11.278 100 88 175 1 1120172560 60725360 18019632
08:08:17 1018 8192000000 10016282592 10385457152 1060130224 11.458 100 92 183 2 1133512016 54017920 19363872
08:09:20 3768 8192000000 10003803488 10377191424 1058274272 11.440 100 70 139 0 1117636736 44666400 14696064
08:10:24 5446 8192000000 9999899888 10392621056 1072877632 11.580 100 34 69 1 1125726640 45908304 6940704
08:11:32 52976 8192000000 9989204000 10387849216 1082803072 11.674 100 37 74 3 1104776832 13197888 8775872
08:13:27 11588 8192000000 10002998784 10412081152 1085347728 11.698 100 21 41 3 1120104576 30385504 4371344
08:14:39 8046 8192000000 10009157152 10404855808 1093056288 11.772 100 45 88 1 1125695616 22884800 9754528
08:15:48 59015 8192000000 10010082368 10405654528 1083069856 11.677 99 33 66 0 1107105472 16361360 7674256
08:17:49 5198 8192000000 10000370912 10397523968 1063730688 11.492 98 42 82 1 1107878160 35281472 8866000
08:18:54 5930 8192000000 10000593760 10390839296 1073067936 11.581 100 35 71 0 1107481360 25834528 8578896
08:20:04 65517 8192000000 9933167232 10325864448 992889808 10.810 95 38 74 1 1013365696 11845248 8630640
08:22:10 5246 8192000000 9941807552 10323800064 1005825136 10.935 98 27 54 1 1043469888 31991232 5653520
08:23:16 2992 8192000000 9964956928 10344665088 1043849200 11.302 100 50 98 3 1080466656 25616768 11000688
08:24:19 26904 8192000000 9973615808 10356264960 1056280656 11.421 100 77 154 1 1080765776 7897392 16587728
08:25:46 12311 8192000000 9983895456 10391568384 1048210320 11.344 99 28 56 0 1076890672 22618944 6061408
08:27:03 21739 8192000000 10001465168 10400428032 1086642400 11.711 100 38 76 1 1106232272 11457888 8131984
08:28:29 19352 8192000000 10073135504 10473652224 1110683792 11.939 100 56 112 2 1174192080 51861792 11646496
08:29:53 22548 8192000000 10078446688 10489507840 1120635104 12.033 100 47 94 1 1193650064 63907568 9107392
08:31:22 16415 8192000000 10098892192 10513309696 1136346768 12.181 100 36 71 3 1215804656 72102336 7355552
08:32:51 9705 8192000000 10176554752 10583707648 1140148816 12.217 99 59 118 2 1285105856 132706688 12250352
08:34:05 5560 8192000000 10221655776 10627256320 1154683824 12.353 100 76 151 2 1331326352 160254720 16387808
08:35:17 13945 8192000000 10240181632 10646237184 1151597120 12.324 99 58 116 0 1335739664 171153344 12989200
08:36:35 4727 8192000000 10287485824 10702921728 1176835200 12.561 100 61 121 1 1402517408 211524944 14157264
08:37:46 2901 8192000000 10326219408 10734964736 1179558096 12.586 100 78 155 3 1424861232 226661840 18641296
08:38:53 7720 8192000000 10346638448 10788233216 1185732064 12.644 100 60 119 1 1453137280 252988464 14416752
08:40:10 5086 8192000000 10352878768 10784628736 1195850496 12.738 100 63 126 1 1452357376 240813552 15693328
08:41:21 2932 8192000000 10362033392 10780450816 1192717280 12.709 100 68 133 3 1464006944 256280736 15008928
08:42:47 4518 8192000000 10329484640 10752167936 1174937152 12.543 100 47 92 0 1430176976 244211600 11028224
08:43:54 3089 8192000000 10368038512 10785484800 1162199712 12.424 100 73 146 2 1468355024 288650464 17504848
08:45:06 12110 8192000000 10342315952 10776510464 1147652208 12.287 99 48 96 1 1438804544 279663328 11489008
08:46:27 9361 8192000000 10301294368 10733977600 1106252960 11.897 97 37 73 0 1384043632 268704688 9085984
08:47:38 28553 8192000000 10319629552 10765578240 1116137248 11.990 100 27 54 1 1427740352 304277600 7325504
08:49:08 11813 8192000000 10261486736 10734956544 1118845120 12.016 100 21 42 2 1380741696 256523248 5373328
08:50:23 31539 8192000000 10111466832 10559795200 1091306736 11.755 97 19 37 1 1239488528 143311488 4870304
08:51:55 46478 8192000000 10036929456 10551328768 1058951648 11.446 97 3 6 0 1094496320 35005760 538912
08:53:42 48802 8192000000 10056814448 10510266368 1066332864 11.517 96 14 28 3 1105531488 36091904 3106720
08:55:41 80841 8192000000 10053969232 10516762624 1109851760 11.931 100 23 46 0 1171688272 57246880 4589632
08:58:11 66656 8192000000 10108809824 10585722880 1126927200 12.092 100 11 22 1 1216921312 87837328 2156784
09:00:18 121074 8192000000 10072000656 10525151232 1090879648 11.751 96 7 14 1 1125541920 33623360 1038912
09:03:21 68293 8192000000 10113028272 10540335104 1120037712 12.027 98 55 105 4 1152521632 21511008 10972912
09:05:53 47239 8192000000 10068902768 10509074432 1102894304 11.865 97 18 36 1 1168869040 61810416 4164320
09:07:41 73482 8192000000 10109809872 10594459648 1125764976 12.081 99 27 54 1 1238098304 105693296 6640032
09:09:59 62213 8192000000 10093582720 10536046592 1135588704 12.174 100 23 46 0 1199719296 58767184 5363408
09:12:02 38217 8192000000 10099692736 10572935168 1145897680 12.271 100 11 22 1 1202735168 54050656 2786832
09:15:33 47108 8192000000 10068615728 10510028800 1122199184 12.048 98 16 31 1 1158894768 33476064 3219520
09:17:20 16138 8192000000 10108198544 10549293056 1143151792 12.245 100 37 74 2 1206340128 55951344 7236992
09:18:39 38236 8192000000 10088599360 10529996800 1124735424 12.072 98 21 42 1 1174150112 44507632 4907056
09:20:18 62910 8192000000 10108291600 10595770368 1129946288 12.121 98 14 28 2 1182990992 50518512 2526192
09:22:36 45439 8192000000 10087502512 10586484736 1098843936 11.827 96 20 40 1 1158627584 55627120 4156528
09:24:32 131011 8192000000 10072064848 10565623808 1101063168 11.848 96 12 24 1 1156308992 52826672 2419152
09:27:44 43257 8192000000 10103347392 10560995328 1149944304 12.309 100 16 32 0 1186709440 33763168 3001968
09:29:27 58124 8192000000 10129256832 10634821632 1152800304 12.336 100 10 20 0 1208106448 53741264 1564880
09:31:25 65850 8192000000 10090693600 10529378304 1105796064 11.893 96 21 41 0 1152859504 43063616 3999824
09:33:31 41013 8192000000 10057118768 10512551936 1122488656 12.050 98 13 25 3 1154458864 29874816 2095392
09:35:13 55054 8192000000 10090172176 10546749440 1072614768 11.577 94 16 32 1 1130547904 54349984 3583152
09:37:08 54693 8192000000 10056790000 10499350528 1099093920 11.829 98 27 52 0 1135280224 30912416 5273888
09:39:17 38168 8192000000 10146245664 10578784256 1109959456 11.932 100 42 84 1 1216819088 98365040 8494592
09:40:57 135499 8192000000 10059829488 10508472320 965427696 10.542 87 17 34 0 995836496 27424672 2984128
09:44:13 96561 8192000000 10046061584 10559946752 1068369168 11.537 97 9 18 0 1163949168 93703984 1876016
09:47:24 55140 8192000000 10033221648 10499137536 1110451616 11.937 100 4 8 0 1146578400 35084656 1042128
09:49:20 56147 8192000000 10029752096 10483593216 1079916784 11.647 97 4 9 0 1101810144 21194752 698608
09:51:16 57942 8192000000 10031111424 10511495168 1085613184 11.701 98 6 12 0 1126947648 40442064 892400
09:53:14 33168 8192000000 10077506688 10523136000 1122258176 12.048 100 23 44 2 1167192032 40390784 4543072
09:54:48 66457 8192000000 10045821312 10520477696 1108421104 11.917 98 24 48 0 1161025056 47350176 5253776
09:56:56 38558 8192000000 10043113648 10496946176 1121290240 12.039 99 17 33 1 1144623456 20022848 3310368
09:58:36 26326 8192000000 10104089696 10543845376 1108463392 11.918 97 50 100 2 1170904944 51551920 10889632
10:00:12 24804 8192000000 10112721120 10542673920 1124119168 12.066 99 49 98 2 1184443776 50379136 9945472
10:01:40 64708 8192000000 10068815232 10521923584 1112989328 11.961 98 11 20 1 1164114768 49052656 2072784
10:03:50 46507 8192000000 10082009296 10518728704 1084485088 11.690 95 39 77 2 1149049456 57339952 7224416
10:05:36 132283 8192000000 10055815248 10560057344 1109833056 11.931 98 14 28 2 1195612720 82984656 2795008
10:08:49 28030 8192000000 10059543248 10512752640 1132006832 12.140 100 18 36 3 1161798176 26236432 3554912
10:10:20 40204 8192000000 10065429584 10518458368 1135221728 12.171 100 5 10 2 1168063072 31764864 1076480
10:12:03 102622 8192000000 10054310000 10521370624 1099350576 11.831 97 10 20 1 1140479248 39265824 1862848
10:14:47 56075 8192000000 10045356096 10498367488 1087164528 11.716 96 16 33 0 1123427872 32796960 3466384
10:16:43 52398 8192000000 10083334848 10527203328 1125580928 12.080 99 28 55 3 1181629584 49915728 6132928
10:18:39 23127 8192000000 10078381504 10514255872 1117966976 12.008 99 32 64 2 1165847376 41263152 6617248
10:20:02 76643 8192000000 10074206096 10540138496 1126890304 12.092 100 28 56 1 1205026816 72618992 5517520
10:22:35 62820 8192000000 10045378064 10509062144 1062588656 11.481 95 18 36 0 1120533216 54268224 3676336
10:24:43 151044 8192000000 9409607344 9804472320 503730208 5.792 45 14 27 1 568985232 62425680 2829344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 30-11-2020 10:30:00 to 30-11-2020 10:30:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-11-2020 10:30:15 to 30-11-2020 10:30:17.

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          243384
MON$OLDEST_ACTIVE               246919
MON$OLDEST_SNAPSHOT             246919
MON$NEXT_TRANSACTION            246919
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                       2097856
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             822
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2281 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2281 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2281 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.2281 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Mon Nov 30 10:27:21 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Mon Nov 30 10:28:06 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

30.11.20 10:30:18. 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-11-30 10:30:21 No severe PSQL-related problems occured
30.11.20 10:30:22. End of report.