Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.10.2020 07:36
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16977808 kB
MemAvailable 31485452 kB
Buffers 0 kB
Cached 14676408 kB
SwapCached 3064 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-30 07:35:24
DTS_END                         2020-10-30 07:35:23.9710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/40594
TRN_ID                          238538
ATT_ID                          627
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: 8907.83 29044 1068939
customer order: creation 163.54 390 19625
customer order: refuse 14.58 301 1750
order to supplier: creation 45.47 1327 5456
order to supplier: removal 3.23 122682 387
invoice (draft): creation 44.23 3467 5308
invoice (draft): removal 50.63 924 6076
invoice accept: apply 40.58 18003 4869
invoice accept: cancel 45.44 842 5453
customer reserve: creation 3746.94 177 449633
customer reserve: removal 4473.98 69 536878
realization accept: apply 81.73 173 9808
realization accept: cancel 74.12 135 8894
payment from customer: creation 56.75 83 6810
payment from customer: removal 5.87 56 704
payment to supplier: creation 46.70 109 5604
payment to supplier: removal 3.38 62 406
service: total inventory turnovers 3.72 3358 446
service: total monetary turnovers 5.94 498 713
service: aggregate perf. data 0.96 12798 115
service: refresh index statistics 0.03 415431 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-10-2020 07:36:21 to 30-10-2020 07:36:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6173
TEST_TIME 2 5304
TEST_TIME 3 5591
TEST_TIME 4 5214
TEST_TIME 5 5451
TEST_TIME 6 6043
TEST_TIME 7 5777
TEST_TIME 8 5946
TEST_TIME 9 5795
TEST_TIME 10 6205
TEST_TIME 11 6220
TEST_TIME 12 6293
TEST_TIME 13 6188
TEST_TIME 14 6119
TEST_TIME 15 6140
TEST_TIME 16 6325
TEST_TIME 17 6635
TEST_TIME 18 6886
TEST_TIME 19 7042
TEST_TIME 20 7755
TEST_TIME 21 8056
TEST_TIME 22 8156
TEST_TIME 23 8360
TEST_TIME 24 8777
TEST_TIME 25 9592
TEST_TIME 26 10479
TEST_TIME 27 10491
TEST_TIME 28 10436
TEST_TIME 29 10215
TEST_TIME 30 9909
TEST_TIME 31 9849
TEST_TIME 32 9827
TEST_TIME 33 9784
TEST_TIME 34 9757
TEST_TIME 35 9716
TEST_TIME 36 9748
TEST_TIME 37 9684
TEST_TIME 38 9675
TEST_TIME 39 9595
TEST_TIME 40 9558
TEST_TIME 41 9590
TEST_TIME 42 9623
TEST_TIME 43 9646
TEST_TIME 44 9551
TEST_TIME 45 9627
TEST_TIME 46 9766
TEST_TIME 47 9856
TEST_TIME 48 9894
TEST_TIME 49 9976
TEST_TIME 50 9928
TEST_TIME 51 9919
TEST_TIME 52 9956
TEST_TIME 53 9797
TEST_TIME 54 9660
TEST_TIME 55 9598
TEST_TIME 56 9576
TEST_TIME 57 9491
TEST_TIME 58 9455
TEST_TIME 59 9358
TEST_TIME 60 9350
TEST_TIME 61 9298
TEST_TIME 62 9351
TEST_TIME 63 9408
TEST_TIME 64 9355
TEST_TIME 65 9363
TEST_TIME 66 9328
TEST_TIME 67 9447
TEST_TIME 68 9366
TEST_TIME 69 9464
TEST_TIME 70 9471
TEST_TIME 71 9481
TEST_TIME 72 9608
TEST_TIME 73 9481
TEST_TIME 74 9383
TEST_TIME 75 9347
TEST_TIME 76 9293
TEST_TIME 77 9320
TEST_TIME 78 9301
TEST_TIME 79 9309
TEST_TIME 80 9242
TEST_TIME 81 9280
TEST_TIME 82 9282
TEST_TIME 83 9323
TEST_TIME 84 9331
TEST_TIME 85 9366
TEST_TIME 86 9435
TEST_TIME 87 9454
TEST_TIME 88 9465
TEST_TIME 89 9483
TEST_TIME 90 9391
TEST_TIME 91 9338
TEST_TIME 92 9292
TEST_TIME 93 9243
TEST_TIME 94 9202
TEST_TIME 95 9135
TEST_TIME 96 9161
TEST_TIME 97 9117
TEST_TIME 98 9092
TEST_TIME 99 9105
TEST_TIME 100 9087
TEST_TIME 101 9048
TEST_TIME 102 9179
TEST_TIME 103 9185
TEST_TIME 104 9166
TEST_TIME 105 9183
TEST_TIME 106 9197
TEST_TIME 107 9184
TEST_TIME 108 9179
TEST_TIME 109 9117
TEST_TIME 110 9082
TEST_TIME 111 9045
TEST_TIME 112 9005
TEST_TIME 113 9003
TEST_TIME 114 9012
TEST_TIME 115 9008
TEST_TIME 116 8973
TEST_TIME 117 8991
TEST_TIME 118 8961
TEST_TIME 119 8947
TEST_TIME 120 8909

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-10-2020 07:36:24 to 30-10-2020 07:36:28.

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 1484496 1484496 0 0.00 0 15136 7 0 0
doc_list_biud 1484496 1484496 0 0.00 0 34683 31 0 0
sp_add_invoice_to_stock 4987 4869 118 2.36 45 204958 18003 0 118
sp_cancel_adding_invoice 5453 5453 0 0.00 6 73942 843 0 0
sp_cancel_client_order 1750 1750 0 0.00 5 7855 302 0 0
sp_cancel_customer_reserve 536878 536878 0 0.00 1 30993 70 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 1448 57 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 2811 62 0 0
sp_cancel_supplier_invoice 6084 6076 8 0.13 2 28282 925 0 8
sp_cancel_supplier_order 387 387 0 0.00 6 1043022 122682 0 0
sp_cancel_write_off 8894 8894 0 0.00 1 11597 136 0 0
sp_client_order 19625 19625 0 0.00 4 32555 391 0 0
sp_customer_reserve 449633 449633 0 0.00 0 61028 177 0 0
sp_fill_shopping_cart 30381 30381 0 0.00 1 10305 42 0 0
sp_fill_shopping_cart_clo_res 449632 449632 0 0.00 0 16492 18 0 0
sp_get_clo_for_invoice 4868 4868 0 0.00 0 31851 365 0 0
sp_kill_cost_storno 15457 15457 0 0.00 0 5890 57 0 0
sp_kill_qstorno_ret_qs2qd 1089113 1089113 0 0.00 0 30966 37 0 0
sp_kill_qty_storno 574114 574114 0 0.00 0 34683 80 0 0
sp_lock_selected_doc 20389 20389 0 0.00 0 8458 6 0 0
sp_make_cost_storno 27090 27090 0 0.00 0 14746 48 0 0
sp_multiply_rows_for_pdistr 27090 27090 0 0.00 0 6865 25 0 0
sp_multiply_rows_for_qdistr 19625 19625 0 0.00 0 32533 219 0 0
sp_pay_from_customer 6810 6810 0 0.00 4 7432 83 0 0
sp_pay_to_supplier 5604 5604 0 0.00 4 10030 110 0 0
sp_payment_common 12414 12414 0 0.00 1 5364 17 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 7849 272 0 0
sp_qd_handle_on_invoice_upd_sts 10321 10321 0 0.00 0 34683 366 0 0
sp_qd_handle_on_reserve_upd_sts 9808 9808 0 0.00 0 19073 61 0 0
sp_reserve_write_off 9808 9808 0 0.00 6 19087 174 0 0
sp_supplier_invoice 5308 5308 0 0.00 7 96265 3467 0 0
sp_supplier_order 5456 5456 0 0.00 6 73804 1328 0 0
srv_aggregate_perf_data 115 115 0 0.00 3057 138143 12798 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1889 137344 46843 0 0
srv_find_qd_qs_mism 1022547 1022547 0 0.00 0 13823 6 0 0
srv_make_invnt_saldo 446 446 0 0.00 49 124896 3359 0 0
srv_make_money_saldo 713 713 0 0.00 1 20484 498 0 0
srv_recalc_idx_stat 4 4 0 0.00 284859 549847 415431 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4869 4869 0 0.00 0 23718 172 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 5523 103 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 3153 12 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 3247 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 3494 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 7728 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 872 19 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 22490 1474 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 4095 10 0 0
t$perf-norm:sp_client_order 19625 19625 0 0.00 0 6156 15 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 429 3 0 0
t$perf-norm:sp_pay_from_customer 6810 6810 0 0.00 0 4187 7 0 0
t$perf-norm:sp_pay_to_supplier 5604 5604 0 0.00 0 9763 9 0 0
t$perf-norm:sp_reserve_write_off 9808 9808 0 0.00 0 11706 10 0 0
t$perf-norm:sp_supplier_invoice 5308 5308 0 0.00 0 10043 19 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 4237 16 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 816 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 608 11 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 3916 21 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 4788 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9972 9736 236 2.36 0 5398 15 0 236
v_all_customers 36196 36196 0 0.00 0 26 1 0 0
v_all_suppliers 29750 29750 0 0.00 0 20 1 0 0
v_all_wares 39250 39250 0 0.00 0 122 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 1126 8 0 0
v_cancel_client_order 3500 3500 0 0.00 0 3121 5 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 1010 5 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 1517 4 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1078 6 0 0
v_cancel_supplier_invoice 834 818 16 1.91 0 2422 16 0 16
v_cancel_supplier_order 774 774 0 0.00 0 2417 9 0 0
v_cancel_write_off 2050 2050 0 0.00 0 4399 15 0 0
v_max_id_clo_ord 5456 5456 0 0.00 0 785 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 8039 17 0 0
v_max_id_ord_sup 5300 5300 0 0.00 0 3962 7 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 527 2 0 0
v_max_non_paid_realizn 3453 3453 0 0.00 0 573 2 0 0
v_min_id_clo_ord 5456 5456 0 0.00 0 6573 11 0 0
v_min_id_clo_res 957 957 0 0.00 0 11083 21 0 0
v_min_id_ord_sup 5300 5300 0 0.00 0 6174 6 0 0
v_min_non_paid_invoice 5604 5604 0 0.00 0 5362 11 0 0
v_min_non_paid_realizn 6810 6810 0 0.00 0 3663 10 0 0
v_our_firm 9768 9768 0 0.00 0 23 1 0 0
v_reserve_write_off 19616 19616 0 0.00 0 4077 4 0 0
x_lock_depdocs_on_canc_invoice 11120 11120 0 0.00 0 13241 106 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 15960 404 0 0
x_make_qstorno_1000_1200 5456 5456 0 0.00 2 73737 1242 0 0
x_make_qstorno_1200_2000 5308 5308 0 0.00 4 93228 3318 0 0
x_make_qstorno_2100_3300 449633 449633 0 0.00 0 61027 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-10-2020 07:36:29 to 30-10-2020 07:36:33.

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:05:27 2 8192000000 8798998224 8810450944 3163600 0.038 1 1 2 0 3261296 61840 35856
05:06:29 21275 8192000000 9717169104 10013655040 798431776 8.880 100 88 176 0 827385392 7668576 21285040
05:08:07 1809 8192000000 9753667568 10055602176 828582640 9.185 100 93 186 0 863511680 12669616 22259424
05:09:13 16531 8192000000 9791494704 10104369152 858794944 9.488 100 87 174 0 899735968 20549424 20391600
05:10:33 13583 8192000000 9867988960 10201206784 912808160 10.025 100 82 164 0 973850496 42606688 18435648
05:11:50 6623 8192000000 9919356880 10263834624 965468224 10.542 100 83 164 0 1028635600 45504512 17662864
05:13:01 5028 8192000000 9974078080 10329554944 1000967536 10.888 100 83 166 3 1087801008 70446096 16387376
05:14:08 2667 8192000000 10019023440 10376830976 1021466352 11.086 100 90 180 2 1125136000 86250176 17419472
05:15:13 1732 8192000000 9998594528 10373140480 1044899552 11.312 100 83 165 1 1120470416 58785184 16785680
05:16:15 958 8192000000 10047247360 10417655808 1065753968 11.512 100 96 190 1 1154021280 68636544 19630768
05:17:17 26727 8192000000 9999618768 10375688192 1071036608 11.562 100 75 151 1 1097596512 10891248 15668656
05:18:44 9218 8192000000 9984038736 10377125888 1077988672 11.628 100 38 74 2 1103565888 17207056 8370160
05:19:56 6947 8192000000 9998957232 10402136064 1079216208 11.640 100 35 70 0 1124584256 38360688 7007360
05:21:05 11228 8192000000 10004927536 10415714304 1087113536 11.715 100 21 42 1 1120967984 29368256 4486192
05:22:19 72653 8192000000 10019851888 10436464640 1094454352 11.785 100 31 61 3 1110269136 8647216 7167568
05:24:42 5865 8192000000 10005288272 10416807936 1084646896 11.692 100 31 62 2 1122391808 30970416 6774496
05:25:50 4741 8192000000 9982020048 10383536128 1057030720 11.428 98 48 97 5 1096778720 29526560 10221440
05:26:56 6778 8192000000 9931992480 10309332992 972735504 10.613 94 35 68 1 999474416 19416848 7322064
05:28:04 27723 8192000000 9942505056 10332303360 983202240 10.715 95 29 58 1 1004497808 14805920 6489648
05:29:34 3238 8192000000 9956954384 10333896704 1024540432 11.116 99 34 67 3 1050281472 19010784 6730256
05:30:39 7851 8192000000 9967493200 10356654080 1038224960 11.248 100 36 71 2 1072914928 26655184 8034784
05:31:48 88178 8192000000 9957399616 10364096512 1055934800 11.418 100 33 64 2 1087963120 23988736 8039584
05:34:16 8642 8192000000 9986257520 10382176256 1067058240 11.524 100 36 72 2 1095842032 20782336 8001456
05:35:26 14503 8192000000 10005619200 10397417472 1083094224 11.677 100 51 100 3 1110278592 15830160 11354208
05:36:45 10929 8192000000 10038977712 10430455808 1097531616 11.814 100 52 102 6 1143224784 35784992 9908176
05:37:57 62099 8192000000 10040114352 10451316736 1099869744 11.836 99 36 72 0 1163244352 55439296 7935312
05:40:06 6955 8192000000 10076756176 10472034304 1115486368 11.984 100 55 106 3 1174003152 46160320 12356464
05:41:19 13267 8192000000 10119249632 10520698880 1126838400 12.092 100 48 97 1 1219586960 81932256 10816304
05:42:42 11163 8192000000 10152726144 10551865344 1134380448 12.163 99 62 125 1 1244123760 96899632 12843680
05:43:56 25497 8192000000 10186173408 10593624064 1154150336 12.348 100 54 109 3 1292049552 125933472 11965744
05:45:30 8729 8192000000 10184462064 10610548736 1165313392 12.453 100 39 77 0 1285205936 111330400 8562144
05:46:42 6476 8192000000 10220110720 10625224704 1159811408 12.401 100 63 126 0 1324506704 150649008 14046288
05:47:56 16178 8192000000 10250226944 10643116032 1164352800 12.444 100 60 118 5 1344185232 165466512 14365920
05:49:16 1888 8192000000 10338671072 10730778624 1191497120 12.697 100 83 164 1 1439577536 229420640 18659776
05:50:21 3960 8192000000 10391074784 10789363712 1180888704 12.598 99 81 161 1 1484371440 284383552 19099184
05:51:32 1946 8192000000 10454959152 10856120320 1188832688 12.672 99 84 167 4 1547497728 338598608 20066432
05:52:40 2141 8192000000 10492828368 10896969728 1186090752 12.647 100 91 182 3 1600702688 392625632 21986304
05:53:47 2556 8192000000 10519642416 10939568128 1193299472 12.714 100 90 180 1 1625720816 410265744 22155600
05:54:53 4023 8192000000 10536371280 10949332992 1174436912 12.538 100 67 135 0 1635774560 444668128 16669520
05:56:01 13425 8192000000 10444309056 10868940800 1157397536 12.379 100 38 75 3 1554223408 386858896 9966976
05:57:19 19884 8192000000 10414835968 10849796096 1139119440 12.207 99 30 60 0 1512223824 364920416 8183968
05:58:40 22837 8192000000 10397195104 10837585920 1121342400 12.040 100 25 50 1 1509329248 380642176 7344672
06:00:12 53191 8192000000 10157828960 10697957376 1095031792 11.790 98 17 33 1 1383129184 283389664 4707728
06:02:05 18800 8192000000 10085303792 10501402624 1113221008 11.963 99 33 64 3 1169592688 49317376 7054304
06:03:24 42522 8192000000 10077593696 10521645056 1106302384 11.897 98 46 93 2 1166370848 49858336 10210128
06:05:07 84780 8192000000 10077558880 10525388800 1090222288 11.745 98 28 54 2 1186523952 90429840 5871824
06:07:34 42228 8192000000 10066465744 10520174592 1100909056 11.846 99 16 32 1 1156308352 51675680 3723616
06:09:24 40907 8192000000 10091565392 10528862208 1099060944 11.829 98 15 30 1 1177677152 75046432 3569776
06:11:19 45826 8192000000 10087123936 10524954624 1111625648 11.948 100 30 60 0 1202531632 84797408 6108576
06:13:05 45808 8192000000 10114661248 10565414912 1120539104 12.032 100 13 26 0 1202913488 79303200 3071184
06:15:12 57227 8192000000 10095608112 10555002880 1119108256 12.019 99 11 22 0 1178901840 57374112 2419472
06:17:14 47069 8192000000 10093501024 10544455680 1119570496 12.023 98 17 34 1 1162313824 39270896 3472432
06:19:02 35049 8192000000 10143616784 10578247680 1152072624 12.329 100 23 46 0 1232702864 75436400 5193840
06:20:37 56058 8192000000 10124273952 10614837248 1136938288 12.187 99 13 26 0 1257504000 117309920 3255792
06:22:54 66444 8192000000 10109255264 10575007744 1114252976 11.973 97 25 50 2 1170993280 51185136 5555168
06:25:10 84013 8192000000 10064281664 10509369344 1116992112 11.999 98 18 36 1 1174815808 53899152 3924544
06:27:44 137344 8192000000 10082840160 10522656768 1128318544 12.106 99 28 56 2 1191344832 57385680 5640608
06:31:06 65434 8192000000 10099342256 10575183872 1135456960 12.173 99 10 20 0 1184809360 47697424 1654976
06:33:17 43269 8192000000 10071398144 10534961152 1123223152 12.057 99 9 18 0 1166467824 40668928 2575744
06:35:15 51031 8192000000 10090920832 10570153984 1123663712 12.062 99 10 20 0 1199796560 73814688 2318160
06:37:17 54338 8192000000 10061624560 10497302528 1068781008 11.540 94 25 50 1 1135062016 60901088 5379920
06:39:20 39495 8192000000 10054739184 10485669888 1099275632 11.831 98 21 42 1 1133235888 29777408 4182848
06:41:07 37095 8192000000 10088897248 10511175680 1088370240 11.727 97 33 66 0 1169715520 74471168 6874112
06:42:46 48012 8192000000 10038974144 10454843392 1052060976 11.380 94 21 42 1 1085745536 28854000 4830560
06:44:36 92919 8192000000 10072596560 10548027392 1100062432 11.838 98 15 30 0 1177295536 74271856 2961248
06:47:25 96761 8192000000 10046234144 10530308096 1107537872 11.909 100 9 18 0 1164605024 55605424 1461728
06:50:03 76229 8192000000 10042494176 10510622720 1072262624 11.574 96 10 20 0 1123652128 49258256 2131248
06:52:22 122011 8192000000 10066233504 10557935616 1122972912 12.055 100 17 33 0 1199911072 73232464 3705696
06:55:24 26448 8192000000 10120623360 10545373184 1124893968 12.073 99 49 98 2 1188010928 53156768 9960192
06:57:00 48100 8192000000 10088434272 10540933120 1092386992 11.765 97 23 46 0 1171324352 74052768 4884592
06:58:50 72435 8192000000 10092752032 10556997632 1097718352 11.816 97 23 46 1 1171522352 69102160 4701840
07:01:02 133275 8192000000 10079277792 10557845504 1106335696 11.898 98 15 30 0 1151621392 42635488 2650208
07:04:16 125540 8192000000 10056172048 10502041600 1108256016 11.916 98 14 24 2 1152356544 41374464 2726064
07:07:35 59014 8192000000 10062204080 10521620480 1122142704 12.047 100 9 18 0 1173504208 49340608 2020896
07:09:59 48841 8192000000 10071770880 10515435520 1096465360 11.804 97 19 38 2 1155483280 54694592 4323328
07:13:16 23058 8192000000 10058226608 10489737216 1096963392 11.809 98 26 52 2 1134554080 32067328 5523360
07:14:41 41243 8192000000 10078358800 10519801856 1113220672 11.963 99 26 52 1 1152293616 33940640 5132304
07:16:23 86413 8192000000 10079028768 10605862912 1119171664 12.019 99 15 30 0 1213546368 91045232 3329472
07:18:49 71979 8192000000 10121458800 10566729728 1116941520 11.998 98 25 50 1 1186309200 64089632 5278048
07:21:02 58398 8192000000 10091633600 10510516224 1114346416 11.974 98 29 57 2 1168896528 48143472 6406640
07:23:02 15935 8192000000 10097200592 10507804672 1117678448 12.005 98 58 114 0 1161112880 32119344 11315088
07:24:21 121891 8192000000 10056402960 10522427392 1098523184 11.824 97 11 22 0 1148378512 47626304 2229024
07:27:23 55904 8192000000 10083709840 10553368576 1086214896 11.707 96 9 18 0 1146602384 58199744 2187744
07:29:20 64635 8192000000 10064710816 10534588416 1125673808 12.081 100 17 33 2 1167591456 38284128 3633520
07:31:26 32755 8192000000 10081996144 10511118336 1112840768 11.959 99 36 71 1 1145353968 24821936 7691264
07:33:02 31708 8192000000 10071182528 10497490944 1097134272 11.810 98 26 53 1 1129990816 27640528 5216016
07:34:45 47882 8192000000 9125673360 9369808896 237646320 2.819 22 8 16 0 283600416 44471312 1482784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-10-2020 07:36:35 to 30-10-2020 07:36:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 118 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-10-2020 07:36:51 to 30-10-2020 07:36:53.

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          235930
MON$OLDEST_ACTIVE               239122
MON$OLDEST_SNAPSHOT             239122
MON$NEXT_TRANSACTION            239122
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                       2085920
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             794
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 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.2242 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    Fri Oct 30 07:35:37 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Oct 30 07:35:44 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

30.10.20 07:36:54. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-10-30 07:36:56 No severe PSQL-related problems occured
30.10.20 07:36:57. End of report.