Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2021 13: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 16571816 kB
MemAvailable 31307128 kB
Buffers 0 kB
Cached 15033556 kB
SwapCached 4864 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-01-10 13:26:55
DTS_END                         2021-01-10 13:26:55.8920
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/51632
TRN_ID                          230931
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: 8957.54 20414 1074905
customer order: creation 172.31 391 20677
customer order: refuse 15.00 374 1800
order to supplier: creation 45.77 1544 5492
order to supplier: removal 3.18 135577 382
invoice (draft): creation 45.84 3845 5501
invoice (draft): removal 51.33 1134 6160
invoice accept: apply 40.93 18224 4912
invoice accept: cancel 46.14 965 5537
customer reserve: creation 3767.02 177 452042
customer reserve: removal 4481.17 73 537740
realization accept: apply 82.75 191 9930
realization accept: cancel 78.70 117 9444
payment from customer: creation 58.93 113 7072
payment from customer: removal 5.82 96 698
payment to supplier: creation 49.10 117 5892
payment to supplier: removal 3.05 79 366
service: total inventory turnovers 3.61 4328 433
service: total monetary turnovers 6.14 649 737
service: aggregate perf. data 0.71 26457 85
service: refresh index statistics 0.04 213820 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-01-2021 13:28:16 to 10-01-2021 13:28:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3267
TEST_TIME 2 3324
TEST_TIME 3 3897
TEST_TIME 4 4672
TEST_TIME 5 4973
TEST_TIME 6 4888
TEST_TIME 7 5768
TEST_TIME 8 5603
TEST_TIME 9 5773
TEST_TIME 10 6262
TEST_TIME 11 6396
TEST_TIME 12 6386
TEST_TIME 13 6584
TEST_TIME 14 6656
TEST_TIME 15 6732
TEST_TIME 16 6834
TEST_TIME 17 6753
TEST_TIME 18 6920
TEST_TIME 19 7074
TEST_TIME 20 7185
TEST_TIME 21 7449
TEST_TIME 22 7444
TEST_TIME 23 7932
TEST_TIME 24 8025
TEST_TIME 25 8054
TEST_TIME 26 7870
TEST_TIME 27 7988
TEST_TIME 28 8497
TEST_TIME 29 9480
TEST_TIME 30 9875
TEST_TIME 31 10003
TEST_TIME 32 10602
TEST_TIME 33 10569
TEST_TIME 34 10429
TEST_TIME 35 10338
TEST_TIME 36 10330
TEST_TIME 37 10410
TEST_TIME 38 10384
TEST_TIME 39 10195
TEST_TIME 40 10165
TEST_TIME 41 10334
TEST_TIME 42 10188
TEST_TIME 43 10068
TEST_TIME 44 9892
TEST_TIME 45 9814
TEST_TIME 46 9750
TEST_TIME 47 9679
TEST_TIME 48 9586
TEST_TIME 49 9586
TEST_TIME 50 9732
TEST_TIME 51 9713
TEST_TIME 52 9733
TEST_TIME 53 9869
TEST_TIME 54 9756
TEST_TIME 55 9693
TEST_TIME 56 9635
TEST_TIME 57 9582
TEST_TIME 58 9623
TEST_TIME 59 9590
TEST_TIME 60 9556
TEST_TIME 61 9520
TEST_TIME 62 9491
TEST_TIME 63 9671
TEST_TIME 64 9622
TEST_TIME 65 9612
TEST_TIME 66 9567
TEST_TIME 67 9741
TEST_TIME 68 9685
TEST_TIME 69 9673
TEST_TIME 70 9666
TEST_TIME 71 9801
TEST_TIME 72 9862
TEST_TIME 73 9818
TEST_TIME 74 9794
TEST_TIME 75 9699
TEST_TIME 76 9666
TEST_TIME 77 9592
TEST_TIME 78 9514
TEST_TIME 79 9445
TEST_TIME 80 9441
TEST_TIME 81 9452
TEST_TIME 82 9440
TEST_TIME 83 9369
TEST_TIME 84 9309
TEST_TIME 85 9288
TEST_TIME 86 9278
TEST_TIME 87 9226
TEST_TIME 88 9265
TEST_TIME 89 9249
TEST_TIME 90 9327
TEST_TIME 91 9289
TEST_TIME 92 9326
TEST_TIME 93 9319
TEST_TIME 94 9335
TEST_TIME 95 9366
TEST_TIME 96 9393
TEST_TIME 97 9405
TEST_TIME 98 9365
TEST_TIME 99 9288
TEST_TIME 100 9242
TEST_TIME 101 9196
TEST_TIME 102 9154
TEST_TIME 103 9110
TEST_TIME 104 9112
TEST_TIME 105 9096
TEST_TIME 106 9091
TEST_TIME 107 9055
TEST_TIME 108 9060
TEST_TIME 109 9055
TEST_TIME 110 9072
TEST_TIME 111 9105
TEST_TIME 112 9115
TEST_TIME 113 9082
TEST_TIME 114 9168
TEST_TIME 115 9142
TEST_TIME 116 9079
TEST_TIME 117 9054
TEST_TIME 118 9000
TEST_TIME 119 8985
TEST_TIME 120 8964

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2021 13:28:20 to 10-01-2021 13:28:23.

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 1492856 1492856 0 0.00 0 22220 7 0 0
doc_list_biud 1492853 1492853 0 0.00 0 36945 34 0 0
sp_add_invoice_to_stock 5059 4912 147 2.90 45 190025 18224 0 147
sp_cancel_adding_invoice 5537 5537 0 0.00 5 58324 966 0 0
sp_cancel_client_order 1800 1800 0 0.00 4 9313 375 0 0
sp_cancel_customer_reserve 537740 537740 0 0.00 1 23675 74 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 5608 96 0 0
sp_cancel_pay_to_supplier 366 366 0 0.00 3 1789 79 0 0
sp_cancel_supplier_invoice 6170 6160 10 0.16 3 36948 1135 0 10
sp_cancel_supplier_order 382 382 0 0.00 11 1294369 135578 0 0
sp_cancel_write_off 9444 9444 0 0.00 1 18945 118 0 0
sp_client_order 20677 20677 0 0.00 4 25618 392 0 0
sp_customer_reserve 452042 452042 0 0.00 0 41896 177 0 0
sp_fill_shopping_cart 31653 31653 0 0.00 1 28741 44 0 0
sp_fill_shopping_cart_clo_res 452030 452030 0 0.00 0 28103 17 0 0
sp_get_clo_for_invoice 4894 4894 0 0.00 0 75898 450 0 0
sp_kill_cost_storno 16045 16045 0 0.00 0 6750 66 0 0
sp_kill_qstorno_ret_qs2qd 1091466 1091466 0 0.00 0 36944 41 0 0
sp_kill_qty_storno 575886 575886 0 0.00 0 36945 88 0 0
sp_lock_selected_doc 20518 20518 0 0.00 0 8668 6 0 0
sp_make_cost_storno 27787 27787 0 0.00 0 21568 58 0 0
sp_multiply_rows_for_pdistr 27787 27787 0 0.00 0 8938 33 0 0
sp_multiply_rows_for_qdistr 20677 20677 0 0.00 0 15251 214 0 0
sp_pay_from_customer 7072 7072 0 0.00 4 12986 113 0 0
sp_pay_to_supplier 5892 5892 0 0.00 5 12455 118 0 0
sp_payment_common 12964 12964 0 0.00 1 8310 23 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 9241 346 0 0
sp_qd_handle_on_invoice_upd_sts 10430 10430 0 0.00 0 19029 438 0 0
sp_qd_handle_on_reserve_upd_sts 9930 9930 0 0.00 0 23746 63 0 0
sp_reserve_write_off 9930 9930 0 0.00 7 23916 192 0 0
sp_supplier_invoice 5501 5501 0 0.00 9 137954 3846 0 0
sp_supplier_order 5492 5492 0 0.00 6 92126 1545 0 0
srv_aggregate_perf_data 85 85 0 0.00 2604 635985 26457 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1420 144874 42255 0 0
srv_find_qd_qs_mism 1027367 1027367 0 0.00 0 22220 5 0 0
srv_make_invnt_saldo 433 433 0 0.00 35 95335 4329 0 0
srv_make_money_saldo 737 737 0 0.00 1 21412 649 0 0
srv_recalc_idx_stat 5 5 0 0.00 89096 319211 213821 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4913 4913 0 0.00 0 16856 215 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 1 3012 94 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 4271 15 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 722 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 3439 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 366 366 0 0.00 0 1136 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 897 27 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 26132 1944 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 7864 20 0 0
t$perf-norm:sp_client_order 20677 20677 0 0.00 0 12432 24 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 4659 13 0 0
t$perf-norm:sp_pay_from_customer 7072 7072 0 0.00 0 8005 13 0 0
t$perf-norm:sp_pay_to_supplier 5892 5892 0 0.00 0 4247 11 0 0
t$perf-norm:sp_reserve_write_off 9930 9930 0 0.00 0 9208 13 0 0
t$perf-norm:sp_supplier_invoice 5501 5501 0 0.00 0 10689 26 0 0
t$perf-norm:sp_supplier_order 5492 5492 0 0.00 0 16041 30 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 109 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 198 8 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 2906 20 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 1992 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10080 9786 294 2.91 0 6717 18 0 294
v_all_customers 37564 37564 0 0.00 0 181 1 0 0
v_all_suppliers 30792 30792 0 0.00 0 18 1 0 0
v_all_wares 41354 41354 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 572 8 0 0
v_cancel_client_order 3600 3600 0 0.00 0 3712 5 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 4051 11 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 432 4 0 0
v_cancel_payment_to_supplier 732 732 0 0.00 0 516 4 0 0
v_cancel_supplier_invoice 804 784 20 2.48 0 4893 29 0 20
v_cancel_supplier_order 764 764 0 0.00 0 5438 14 0 0
v_cancel_write_off 1996 1996 0 0.00 0 1877 14 0 0
v_max_id_clo_ord 5484 5484 0 0.00 0 688 2 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 4855 17 0 0
v_max_id_ord_sup 5492 5492 0 0.00 0 3456 4 0 0
v_max_non_paid_invoice 1472 1472 0 0.00 0 2679 6 0 0
v_max_non_paid_realizn 3690 3690 0 0.00 0 1751 3 0 0
v_min_id_clo_ord 5484 5484 0 0.00 0 2922 7 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 5650 15 0 0
v_min_id_ord_sup 5492 5492 0 0.00 0 5609 9 0 0
v_min_non_paid_invoice 5892 5892 0 0.00 0 7807 13 0 0
v_min_non_paid_realizn 7072 7072 0 0.00 0 5239 16 0 0
v_our_firm 10554 10554 0 0.00 0 182 1 0 0
v_reserve_write_off 19860 19860 0 0.00 0 4613 5 0 0
x_lock_depdocs_on_canc_invoice 11305 11305 0 0.00 0 15712 123 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 7168 392 0 0
x_make_qstorno_1000_1200 5492 5492 0 0.00 2 92057 1454 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 5 137935 3678 0 0
x_make_qstorno_2100_3300 452042 452042 0 0.00 0 41894 157 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-01-2021 13:28:24 to 10-01-2021 13:28:29.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:59 6 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:00 22967 8192000000 9705640112 9996402688 786552160 8.760 100 90 180 0 816827328 7771328 22503840
10:59:27 34217 8192000000 9754197760 10058293248 828213552 9.181 100 84 169 0 859424720 11269136 19942032
11:01:06 8704 8192000000 9793201904 10102431744 857128992 9.471 100 91 182 0 901219568 22800656 21289920
11:02:21 14865 8192000000 9840149216 10170961920 902186560 9.920 100 77 154 0 954627264 35547424 16893280
11:03:38 9451 8192000000 9925485536 10264477696 949076496 10.382 100 81 161 0 1027927888 62085056 16766336
11:04:51 7900 8192000000 9968048032 10318721024 985411072 10.737 100 87 173 2 1076477600 73845136 17221392
11:06:01 3584 8192000000 9973478944 10335309824 1012519552 11.000 100 78 155 3 1099386288 70191056 16675680
11:07:08 3566 8192000000 10002982320 10367709184 1027618000 11.145 100 82 160 0 1123247424 79804528 15824896
11:08:13 2153 8192000000 10002617824 10370863104 1050674208 11.367 100 87 171 1 1124689232 57115776 16899248
11:09:18 1387 8192000000 10051515920 10422308864 1060431824 11.461 100 95 189 0 1161061376 81249840 19379712
11:10:21 33592 8192000000 9971715808 10360631296 1066786656 11.521 100 36 73 0 1105302912 30062400 8453856
11:11:56 3838 8192000000 10004178544 10385784832 1079371712 11.641 100 57 111 2 1118006304 27308688 11325904
11:13:01 8848 8192000000 9995687760 10386763776 1081062400 11.658 100 29 56 0 1117886320 30898640 5925280
11:14:12 5308 8192000000 10016337520 10421358592 1091003056 11.752 100 22 44 1 1143018192 47435024 4580112
11:15:18 36685 8192000000 10017057536 10402181120 1093846512 11.779 100 61 123 0 1128889984 21622912 13420560
11:16:55 5718 8192000000 10021248480 10423091200 1102509536 11.861 100 41 82 2 1131905440 20681744 8714160
11:18:04 6031 8192000000 10007544096 10400940032 1084990640 11.695 99 33 66 2 1124858048 32264560 7602848
11:19:13 71828 8192000000 9940019904 10328256512 941618640 10.309 88 25 50 0 969244688 21678944 5947104
11:21:27 10281 8192000000 9952485888 10343833600 999628528 10.875 97 40 81 1 1034180320 25417296 9134496
11:22:37 3284 8192000000 9955303824 10331635712 1027240528 11.142 98 51 101 0 1049493840 10147456 12105856
11:23:44 5240 8192000000 9946698464 10329640960 1034041648 11.207 100 51 102 0 1064649168 18709008 11898512
11:24:53 23974 8192000000 9949397168 10349604864 1043184336 11.295 100 27 55 1 1070551152 20617872 6748944
11:26:18 12879 8192000000 9982007280 10384142336 1065450432 11.509 100 29 58 0 1101125920 29477856 6197632
11:27:38 20937 8192000000 10003226512 10402308096 1090879328 11.751 100 26 50 1 1116064336 19809616 5375392
11:29:06 14881 8192000000 10057540640 10455384064 1110481984 11.937 100 52 104 2 1171411680 50252304 10677392
11:30:25 4786 8192000000 10101952480 10509819904 1123015376 12.055 100 48 94 0 1218048048 84765344 10267328
11:31:33 25512 8192000000 10104088352 10533822464 1127811200 12.101 100 29 58 1 1221310032 87153056 6345776
11:33:02 11359 8192000000 10146331408 10563395584 1134708288 12.166 100 68 134 0 1263340848 114006544 14626016
11:34:17 4929 8192000000 10144529600 10554109952 1147121632 12.282 100 57 114 0 1251583792 92177888 12284272
11:35:30 5085 8192000000 10175989920 10584621056 1157327440 12.378 100 57 113 2 1277385392 107397504 12660448
11:36:41 1671 8192000000 10185990576 10588364800 1162724016 12.429 100 81 160 2 1290253680 111204208 16325456
11:37:48 2848 8192000000 10223467504 10635190272 1172326032 12.519 100 74 148 1 1320887632 132250848 16310752
11:38:54 3674 8192000000 10288783744 10694688768 1188210672 12.667 100 76 151 2 1386166160 180664848 17290640
11:40:05 15881 8192000000 10302793376 10722471936 1170082768 12.498 99 57 112 0 1399084864 216275888 12726208
11:41:24 4707 8192000000 10309171792 10729631744 1183915920 12.627 100 49 97 1 1411887424 216836272 11135232
11:42:36 3843 8192000000 10333493392 10758012928 1176589120 12.558 100 61 122 1 1433241824 243062720 13589984
11:43:45 3463 8192000000 10345824048 10756132864 1166763744 12.467 100 72 142 0 1446025568 263297776 15964048
11:44:56 6695 8192000000 10364252400 10784751616 1165866736 12.458 100 67 133 1 1464949888 283746704 15336448
11:46:10 1419 8192000000 10409931024 10815344640 1154076160 12.348 99 90 180 0 1497709936 323008864 20624912
11:47:17 2759 8192000000 10425643280 10825900032 1167188816 12.471 100 94 188 2 1527487248 338887600 21410832
11:48:24 3058 8192000000 10428135088 10826780672 1140667760 12.222 99 75 149 2 1509765520 351473744 17624016
11:49:34 10079 8192000000 10420504576 10890469376 1132283760 12.143 98 41 82 1 1533190736 389867360 11039616
11:50:55 6796 8192000000 10483312448 10910781440 1157596304 12.381 100 46 92 0 1583861120 414540832 11723984
11:52:15 6640 8192000000 10478745440 10898755584 1127037024 12.093 100 54 108 1 1575990880 435302112 13651744
11:53:31 5866 8192000000 10532238240 10977574912 1136217312 12.180 100 47 93 1 1632684144 484317904 12148928
11:54:56 9842 8192000000 10427447440 10911129600 1136042800 12.178 100 34 67 2 1542343824 397537296 8763728
11:56:12 31923 8192000000 10244470736 10749222912 1078799280 11.636 95 21 42 1 1325420672 241486688 5134704
11:57:50 27165 8192000000 10146453152 10585374720 1106815200 11.902 99 22 44 1 1238538016 126898384 4824432
11:59:22 13987 8192000000 10054044624 10486341632 1107980912 11.913 99 36 67 2 1124847776 9569280 7297584
12:00:37 47918 8192000000 10120766240 10544521216 1132198720 12.142 100 26 50 1 1199220496 61100224 5921552
12:02:27 82461 8192000000 10071545088 10537046016 1111253120 11.944 98 22 44 0 1151546496 35656560 4636816
12:04:53 120013 8192000000 10156192800 10586558464 1135287088 12.171 99 21 42 1 1246399936 106926128 4186720
12:07:59 69438 8192000000 10105664064 10561179648 1148768640 12.298 100 19 38 0 1189931072 36881616 4280816
12:10:35 71526 8192000000 10101412928 10590490624 1158800176 12.392 100 19 38 1 1212650976 49431184 4419616
12:12:48 37495 8192000000 10199471264 10656169984 1167414608 12.473 100 31 62 2 1296771040 122714528 6641904
12:15:04 46887 8192000000 10262858672 10745028608 1171899056 12.515 100 36 72 0 1367938720 187215344 8824320
12:16:55 41641 8192000000 10195173520 10712215552 1155633472 12.362 99 17 33 0 1331507344 171877616 3996256
12:18:41 55552 8192000000 10105279760 10614108160 1103982256 11.875 95 11 22 1 1207078384 100141056 2955072
12:21:07 69960 8192000000 10089924464 10576519168 1131123648 12.132 99 12 24 0 1199595376 66008384 2463344
12:23:22 69353 8192000000 10093617344 10582794240 1130924240 12.130 99 10 20 1 1218343664 85244352 2175072
12:25:34 65916 8192000000 10084022128 10533195776 1137908784 12.196 99 9 18 0 1170622032 31006576 1706672
12:27:41 37437 8192000000 10132385248 10587639808 1139439072 12.210 99 17 34 1 1208461504 65412016 3610416
12:29:29 42497 8192000000 10076003520 10574643200 1120204592 12.029 98 10 20 0 1215385360 93100080 2080688
12:31:12 68704 8192000000 10113760912 10558922752 1123557136 12.061 99 14 28 0 1182782912 56109616 3116160
12:33:21 58493 8192000000 10076956368 10549260288 1129469088 12.116 99 12 24 0 1217201376 85509264 2223024
12:35:25 59865 8192000000 10178470480 10657218560 1110725440 11.939 97 18 36 1 1219210256 104588528 3896288
12:37:48 38671 8192000000 10075747104 10587967488 1113332352 11.964 99 15 30 1 1225053440 108579568 3141520
12:39:28 144873 8192000000 10061528768 10517676032 1099651952 11.834 98 30 59 1 1177673680 71416384 6605344
12:42:55 52716 8192000000 10063390688 10533801984 1099588800 11.834 98 13 26 1 1141610960 39168464 2853696
12:44:52 68919 8192000000 10079734224 10525474816 1059356464 11.450 95 11 22 1 1123920112 62226192 2337456
12:47:02 56756 8192000000 10070346768 10546286592 1104006544 11.876 99 21 42 0 1182967968 74086672 4874752
12:48:59 69726 8192000000 10036184352 10469998592 1101103136 11.848 100 20 40 1 1131070400 25292400 4674864
12:51:11 62990 8192000000 10035994608 10492911616 1076800368 11.617 97 7 13 2 1111704448 32974048 1930032
12:53:24 92116 8192000000 10081751328 10571251712 1083784736 11.684 97 14 28 0 1142574032 55954784 2834512
12:56:03 48297 8192000000 10033589504 10502508544 1069521200 11.548 97 19 38 1 1132885424 59072176 4292048
12:57:53 35690 8192000000 10097670528 10560786432 1118235920 12.010 100 40 80 1 1200838256 74515568 8086768
12:59:29 22099 8192000000 10081017120 10500501504 1112819360 11.959 99 42 84 2 1158648592 37426736 8402496
13:00:57 18504 8192000000 10112089984 10539606016 1125804288 12.082 99 33 66 3 1191538320 59535616 6198416
13:02:22 113763 8192000000 10072142640 10525388800 1121666448 12.043 98 14 27 0 1172042000 45918208 4457344
13:05:16 98013 8192000000 10067237408 10517934080 1135730464 12.175 99 26 52 0 1178875536 37832608 5312464
13:08:05 42248 8192000000 10063015712 10534137856 1131654048 12.137 99 8 15 0 1162004656 28951872 1398736
13:09:47 34955 8192000000 10074519696 10520969216 1145713808 12.269 100 26 51 1 1187463792 35738048 6011936
13:11:28 37912 8192000000 10080870656 10525466624 1131347616 12.134 98 29 58 0 1169408736 32117600 5943520
13:13:06 47858 8192000000 10113654336 10555670528 1135106352 12.169 99 20 40 0 1188270720 48846256 4318112
13:15:04 85928 8192000000 10122860336 10558070784 1088763280 11.731 95 21 42 0 1181363264 87715856 4884128
13:17:37 70108 8192000000 10062138912 10496847872 1082943984 11.676 95 18 36 2 1126964624 39654240 4366400
13:19:52 61928 8192000000 10071005312 10548011008 1119778144 12.025 99 13 26 0 1191174160 68924912 2471104
13:22:02 109334 8192000000 10069456368 10535608320 1123711152 12.062 99 26 52 0 1177200592 48171008 5318432
13:25:06 80372 8192000000 10056824160 10535219200 1112920592 11.960 98 8 16 1 1146999728 32311968 1767168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-01-2021 13:28:30 to 10-01-2021 13:28:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2021 13:28:47 to 10-01-2021 13:28:49.

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          230807
MON$OLDEST_ACTIVE               231204
MON$OLDEST_SNAPSHOT             231204
MON$NEXT_TRANSACTION            231204
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                       2108096
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             786
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2324 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.2324 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 ---
:64: 
:65: mscs-test-161.budzdorov.ru    Sun Jan 10 13:26:58 2021
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Sun Jan 10 13:26:59 2021
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Sun Jan 10 13:26:59 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Sun Jan 10 13:26:59 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
--- end of diff output ---

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

10.01.21 13:28:50. 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-01-10 13:28:52 No severe PSQL-related problems occured
10.01.21 13:28:53. End of report.