Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.2021 07:42
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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16859928 kB
MemAvailable 31528992 kB
Buffers 0 kB
Cached 14830524 kB
SwapCached 0 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-03-28 07:41:03
DTS_END                         2021-03-28 07:41:03.6230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57070
TRN_ID                          221216
ATT_ID                          647
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: 8917.18 25414 1070062
customer order: creation 156.68 440 18802
customer order: refuse 13.77 350 1652
order to supplier: creation 41.48 1332 4977
order to supplier: removal 3.26 133327 391
invoice (draft): creation 41.95 3835 5034
invoice (draft): removal 51.61 1101 6193
invoice accept: apply 38.76 18651 4651
invoice accept: cancel 46.38 910 5566
customer reserve: creation 3684.03 178 442084
customer reserve: removal 4560.50 75 547260
realization accept: apply 77.22 179 9267
realization accept: cancel 84.36 128 10123
payment from customer: creation 53.42 104 6410
payment from customer: removal 5.44 59 653
payment to supplier: creation 45.18 122 5422
payment to supplier: removal 3.17 53 380
service: total inventory turnovers 3.58 4158 430
service: total monetary turnovers 5.51 548 661
service: aggregate perf. data 0.84 15990 101
service: refresh index statistics 0.04 326748 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-03-2021 07:43:02 to 28-03-2021 07:43:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6607
TEST_TIME 2 5057
TEST_TIME 3 5612
TEST_TIME 4 5254
TEST_TIME 5 5284
TEST_TIME 6 5909
TEST_TIME 7 5644
TEST_TIME 8 5896
TEST_TIME 9 6060
TEST_TIME 10 6305
TEST_TIME 11 6170
TEST_TIME 12 6138
TEST_TIME 13 6159
TEST_TIME 14 6537
TEST_TIME 15 6654
TEST_TIME 16 6942
TEST_TIME 17 7903
TEST_TIME 18 7827
TEST_TIME 19 7906
TEST_TIME 20 8385
TEST_TIME 21 8491
TEST_TIME 22 8579
TEST_TIME 23 8660
TEST_TIME 24 8940
TEST_TIME 25 8887
TEST_TIME 26 8808
TEST_TIME 27 9243
TEST_TIME 28 11160
TEST_TIME 29 11240
TEST_TIME 30 11141
TEST_TIME 31 10970
TEST_TIME 32 11033
TEST_TIME 33 11073
TEST_TIME 34 10857
TEST_TIME 35 10597
TEST_TIME 36 10373
TEST_TIME 37 10287
TEST_TIME 38 10356
TEST_TIME 39 10205
TEST_TIME 40 10143
TEST_TIME 41 10011
TEST_TIME 42 10184
TEST_TIME 43 10163
TEST_TIME 44 10157
TEST_TIME 45 10108
TEST_TIME 46 9963
TEST_TIME 47 9862
TEST_TIME 48 9737
TEST_TIME 49 9700
TEST_TIME 50 9592
TEST_TIME 51 9513
TEST_TIME 52 9611
TEST_TIME 53 9604
TEST_TIME 54 9582
TEST_TIME 55 9566
TEST_TIME 56 9658
TEST_TIME 57 9613
TEST_TIME 58 9545
TEST_TIME 59 9666
TEST_TIME 60 9751
TEST_TIME 61 9876
TEST_TIME 62 9969
TEST_TIME 63 10002
TEST_TIME 64 9931
TEST_TIME 65 9839
TEST_TIME 66 9757
TEST_TIME 67 9680
TEST_TIME 68 9586
TEST_TIME 69 9590
TEST_TIME 70 9583
TEST_TIME 71 9558
TEST_TIME 72 9499
TEST_TIME 73 9502
TEST_TIME 74 9518
TEST_TIME 75 9598
TEST_TIME 76 9581
TEST_TIME 77 9525
TEST_TIME 78 9725
TEST_TIME 79 9674
TEST_TIME 80 9718
TEST_TIME 81 9668
TEST_TIME 82 9584
TEST_TIME 83 9508
TEST_TIME 84 9452
TEST_TIME 85 9401
TEST_TIME 86 9325
TEST_TIME 87 9312
TEST_TIME 88 9306
TEST_TIME 89 9319
TEST_TIME 90 9291
TEST_TIME 91 9269
TEST_TIME 92 9334
TEST_TIME 93 9283
TEST_TIME 94 9366
TEST_TIME 95 9326
TEST_TIME 96 9372
TEST_TIME 97 9397
TEST_TIME 98 9340
TEST_TIME 99 9264
TEST_TIME 100 9247
TEST_TIME 101 9198
TEST_TIME 102 9193
TEST_TIME 103 9162
TEST_TIME 104 9129
TEST_TIME 105 9143
TEST_TIME 106 9103
TEST_TIME 107 9124
TEST_TIME 108 9100
TEST_TIME 109 9091
TEST_TIME 110 9090
TEST_TIME 111 9116
TEST_TIME 112 9193
TEST_TIME 113 9179
TEST_TIME 114 9213
TEST_TIME 115 9181
TEST_TIME 116 9113
TEST_TIME 117 9083
TEST_TIME 118 9037
TEST_TIME 119 8992
TEST_TIME 120 8919

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-03-2021 07:43:06 to 28-03-2021 07:43:09.

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 1479664 1479664 0 0.00 0 11792 6 0 0
doc_list_biud 1479663 1479663 0 0.00 0 95670 35 0 0
sp_add_invoice_to_stock 4736 4651 85 1.79 32 177512 18651 0 85
sp_cancel_adding_invoice 5566 5566 0 0.00 5 97279 910 0 0
sp_cancel_client_order 1652 1652 0 0.00 4 23844 350 0 0
sp_cancel_customer_reserve 547260 547260 0 0.00 1 30953 75 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 2514 59 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 1597 53 0 0
sp_cancel_supplier_invoice 6201 6193 8 0.12 2 95681 1102 0 8
sp_cancel_supplier_order 391 391 0 0.00 15 1251705 133328 0 0
sp_cancel_write_off 10123 10123 0 0.00 1 11807 129 0 0
sp_client_order 18802 18802 0 0.00 4 32391 441 0 0
sp_customer_reserve 442084 442084 0 0.00 0 49602 179 0 0
sp_fill_shopping_cart 28792 28792 0 0.00 1 18205 43 0 0
sp_fill_shopping_cart_clo_res 442081 442081 0 0.00 0 29292 19 0 0
sp_get_clo_for_invoice 4647 4647 0 0.00 0 19626 360 0 0
sp_kill_cost_storno 16722 16722 0 0.00 0 11747 64 0 0
sp_kill_qstorno_ret_qs2qd 1111227 1111227 0 0.00 0 95669 41 0 0
sp_kill_qty_storno 585099 585099 0 0.00 0 95670 88 0 0
sp_lock_selected_doc 19334 19334 0 0.00 0 8764 6 0 0
sp_make_cost_storno 25746 25746 0 0.00 0 8634 57 0 0
sp_multiply_rows_for_pdistr 25746 25746 0 0.00 0 7023 31 0 0
sp_multiply_rows_for_qdistr 18802 18802 0 0.00 0 15106 267 0 0
sp_pay_from_customer 6410 6410 0 0.00 4 11755 105 0 0
sp_pay_to_supplier 5422 5422 0 0.00 4 9396 122 0 0
sp_payment_common 11832 11832 0 0.00 1 11748 22 0 0
sp_qd_handle_on_cancel_clo 1652 1652 0 0.00 0 23836 321 0 0
sp_qd_handle_on_invoice_upd_sts 10213 10213 0 0.00 0 15413 401 0 0
sp_qd_handle_on_reserve_upd_sts 9267 9267 0 0.00 0 14292 55 0 0
sp_reserve_write_off 9267 9267 0 0.00 7 15273 179 0 0
sp_supplier_invoice 5034 5034 0 0.00 7 139461 3836 0 0
sp_supplier_order 4977 4977 0 0.00 6 23564 1332 0 0
srv_aggregate_perf_data 102 101 1 0.98 2940 118765 15991 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1358 158909 45862 0 0
srv_find_qd_qs_mism 1025961 1025961 0 0.00 0 8278 5 0 0
srv_make_invnt_saldo 430 430 0 0.00 72 86229 4158 0 0
srv_make_money_saldo 661 661 0 0.00 1 13231 548 0 0
srv_recalc_idx_stat 5 5 0 0.00 154253 482869 326748 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4651 4651 0 0.00 0 11390 188 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 0 5662 93 0 0
t$perf-norm:sp_cancel_client_order 1652 1652 0 0.00 0 3879 12 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 3442 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 1935 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 339 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 652 14 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 22458 1795 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 2791 7 0 0
t$perf-norm:sp_client_order 18802 18802 0 0.00 0 6714 20 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 4695 9 0 0
t$perf-norm:sp_pay_from_customer 6410 6410 0 0.00 0 12800 12 0 0
t$perf-norm:sp_pay_to_supplier 5422 5422 0 0.00 0 6243 9 0 0
t$perf-norm:sp_reserve_write_off 9267 9267 0 0.00 0 5756 11 0 0
t$perf-norm:sp_supplier_invoice 5034 5034 0 0.00 0 6078 17 0 0
t$perf-norm:sp_supplier_order 4977 4977 0 0.00 0 3856 18 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1154 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 515 19 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 852 9 0 0
t$perf-norm:srv_make_money_saldo 661 661 0 0.00 0 739 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9464 9294 170 1.79 0 5682 15 0 170
v_all_customers 34528 34528 0 0.00 0 37 1 0 0
v_all_suppliers 28096 28096 0 0.00 0 24 1 0 0
v_all_wares 37604 37604 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 3042 9 0 0
v_cancel_client_order 3304 3304 0 0.00 0 1440 4 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 2504 5 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 1743 4 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1590 6 0 0
v_cancel_supplier_invoice 824 808 16 1.94 0 3158 16 0 16
v_cancel_supplier_order 782 782 0 0.00 0 563 3 0 0
v_cancel_write_off 1878 1878 0 0.00 0 3117 17 0 0
v_max_id_clo_ord 4971 4971 0 0.00 0 393 1 0 0
v_max_id_clo_res 948 948 0 0.00 0 2117 16 0 0
v_max_id_ord_sup 5019 5019 0 0.00 0 2947 3 0 0
v_max_non_paid_invoice 1364 1364 0 0.00 0 70 2 0 0
v_max_non_paid_realizn 3271 3271 0 0.00 0 1560 3 0 0
v_min_id_clo_ord 4971 4971 0 0.00 0 3551 7 0 0
v_min_id_clo_res 948 948 0 0.00 0 3084 8 0 0
v_min_id_ord_sup 5019 5019 0 0.00 0 7758 9 0 0
v_min_non_paid_invoice 5422 5422 0 0.00 0 6538 16 0 0
v_min_non_paid_realizn 6410 6410 0 0.00 0 11745 17 0 0
v_our_firm 9354 9354 0 0.00 0 20 1 0 0
v_reserve_write_off 18534 18534 0 0.00 0 3395 5 0 0
x_lock_depdocs_on_canc_invoice 11355 11355 0 0.00 0 16435 108 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3202 298 0 0
x_make_qstorno_1000_1200 4977 4977 0 0.00 2 23555 1253 0 0
x_make_qstorno_1200_2000 5034 5034 0 0.00 2 139431 3670 0 0
x_make_qstorno_2100_3300 442084 442084 0 0.00 0 49599 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-03-2021 07:43:10 to 28-03-2021 07:43:14.

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:11:07 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:12:08 29041 8192000000 9710870304 10005954560 784932096 8.743 100 81 162 1 812958816 7523248 20503472
05:13:43 26746 8192000000 9761501616 10064568320 829821520 9.197 100 92 184 0 864879728 12690464 22367744
05:15:15 17785 8192000000 9794832496 10109415424 855619760 9.456 100 85 171 0 899064432 23189136 20255536
05:16:35 14019 8192000000 9843158608 10171424768 906310912 9.961 100 82 161 2 951596032 27444928 17840192
05:17:54 6823 8192000000 9908298432 10252099584 946984160 10.362 100 80 157 1 1023019888 60074704 15961024
05:19:05 6413 8192000000 9960399344 10307239936 982108192 10.705 100 88 176 0 1075724912 75136176 18480544
05:20:14 5342 8192000000 9980284288 10342010880 1023807584 11.109 100 78 156 1 1096471904 57656992 15007328
05:21:20 1824 8192000000 10036665040 10402332672 1042929104 11.293 100 86 172 1 1149771920 89435632 17407184
05:22:24 1364 8192000000 10035236832 10403037184 1067620176 11.529 100 96 193 0 1149908240 62603920 19684144
05:23:26 17779 8192000000 9989390288 10388770816 1070330416 11.555 100 37 75 1 1103309968 25288048 7691504
05:24:45 7841 8192000000 10002360896 10402586624 1077154304 11.620 100 36 74 1 1114639440 30099200 7385936
05:25:55 6551 8192000000 9985538752 10386534400 1083739360 11.683 100 31 58 3 1118685072 29216848 5728864
05:27:06 9189 8192000000 10003404192 10412609536 1092074208 11.762 100 39 76 1 1133740336 33485952 8180176
05:28:17 18205 8192000000 10009698432 10396250112 1093614848 11.777 100 58 116 1 1117552608 10930432 13007328
05:29:36 9204 8192000000 10013370800 10414776320 1093154896 11.773 100 40 75 3 1120899424 20019680 7724848
05:30:47 5137 8192000000 10016392160 10413203456 1095840080 11.798 100 47 93 2 1131092240 24615680 10636480
05:31:54 8090 8192000000 9992280784 10392252416 1070917728 11.561 99 24 48 1 1098415312 21242464 6255120
05:33:08 63345 8192000000 9945127200 10341052416 950206976 10.393 89 21 42 2 975729600 21061888 4460736
05:35:14 7080 8192000000 9933642688 10323275776 993852944 10.819 98 36 71 2 1021415456 19550144 8012368
05:36:25 9982 8192000000 9952960656 10329047040 1021570144 11.087 100 38 76 0 1058124016 28431792 8122080
05:37:36 5784 8192000000 9938684016 10340925440 1038766480 11.253 100 36 71 0 1064909632 16065376 10077776
05:38:42 42303 8192000000 9961733392 10364366848 1047373120 11.335 100 38 76 0 1073706320 18213120 8120080
05:40:25 10280 8192000000 9984088288 10385985536 1064111888 11.496 100 32 64 2 1089548624 17897136 7539600
05:41:42 12635 8192000000 10027459184 10420867072 1093888080 11.780 100 58 115 1 1131669632 25861616 11919936
05:43:02 18777 8192000000 10062035952 10460389376 1111200208 11.944 100 43 86 2 1168390928 48160640 9030080
05:44:36 72045 8192000000 10124436272 10541154304 1133270032 12.152 100 47 90 2 1225644768 82587088 9787648
05:47:04 30378 8192000000 10130695504 10545987584 1137319504 12.190 100 39 78 2 1245712160 100436064 7956592
05:48:48 2473 8192000000 10171409312 10578853888 1157280096 12.378 100 62 123 1 1274071952 103487696 13304160
05:49:59 7542 8192000000 10186096624 10609057792 1163188240 12.433 100 54 108 1 1301592608 126100688 12303680
05:51:12 4083 8192000000 10249673504 10658291712 1169852896 12.495 100 63 126 1 1352850320 169297872 13699552
05:52:22 12565 8192000000 10265500256 10678038528 1185992384 12.646 100 61 123 1 1364894160 164701504 14200272
05:53:44 4832 8192000000 10318455280 10730266624 1191046784 12.693 100 71 142 0 1424701376 217007248 16647344
05:54:57 2403 8192000000 10377172160 10802663424 1189322528 12.677 100 82 164 0 1498567584 289963264 19281792
05:56:05 3318 8192000000 10394224064 10822295552 1205000240 12.823 100 80 159 0 1494512512 270249280 19262992
05:57:15 1854 8192000000 10431965488 10855788544 1202585728 12.800 100 84 168 0 1528463712 306067664 19810320
05:58:23 1911 8192000000 10405369152 10838343680 1195737632 12.737 100 92 183 0 1501969168 284982512 21249024
05:59:26 8074 8192000000 10395333504 10822389760 1178749792 12.579 99 45 89 1 1485444800 296137296 10557712
06:00:41 4452 8192000000 10466139200 10887512064 1187020656 12.656 99 75 148 1 1553036848 348345792 17670400
06:01:50 1355 8192000000 10401771200 10832797696 1179159744 12.582 100 63 129 1 1504404464 310093936 15150784
06:02:55 8759 8192000000 10424985680 10847444992 1174771440 12.541 100 39 78 0 1521623856 336867552 9984864
06:04:11 5428 8192000000 10464850640 10885664768 1176731872 12.560 100 60 121 0 1557737008 366642704 14362432
06:05:19 11478 8192000000 10486019600 10925039616 1158694384 12.391 99 36 72 1 1568561952 400537088 9330480
06:06:36 17573 8192000000 10497546064 10947362816 1154751488 12.354 100 29 58 0 1582135536 419515856 7868192
06:08:15 39451 8192000000 10469188512 10925867008 1130853408 12.129 99 33 66 2 1535388432 395651744 8883280
06:09:56 41033 8192000000 10097523584 10570567680 1137755152 12.194 99 37 74 1 1196263456 50316944 8191360
06:12:38 25592 8192000000 10103012144 10538094592 1102428256 11.861 98 23 45 3 1179421888 71964912 5028720
06:14:16 92753 8192000000 10074820224 10504294400 1060488256 11.461 94 23 46 2 1110816256 45138864 5189136
06:16:56 96608 8192000000 10069044352 10515107840 1086909440 11.713 98 26 52 1 1159599168 66993520 5696208
06:19:34 40433 8192000000 10104780512 10531647488 1117855872 12.007 99 29 57 1 1172633664 48769904 6007888
06:21:16 58086 8192000000 10174548352 10625327104 1139021936 12.206 100 20 40 0 1262159536 118483344 4654256
06:23:28 31415 8192000000 10134084240 10641358848 1134058144 12.160 100 7 14 0 1240912128 105357440 1496544
06:25:02 49903 8192000000 10061534960 10504380416 1093349392 11.774 97 20 40 0 1137775600 39955392 4470816
06:26:58 51214 8192000000 10053106208 10506571776 1116936640 11.998 99 9 18 1 1145592400 26699008 1956752
06:29:17 73232 8192000000 10076617984 10545004544 1136657488 12.184 100 9 18 0 1182012704 43461728 1893488
06:31:43 67970 8192000000 10096705056 10561597440 1125611744 12.080 99 16 32 2 1169983280 41104144 3267392
06:34:09 42710 8192000000 10089390032 10562588672 1144862416 12.261 100 17 34 2 1190977568 42770736 3344416
06:36:27 42086 8192000000 10121344576 10569703424 1149451344 12.304 100 29 58 2 1219010096 63785616 5773136
06:38:18 46572 8192000000 10129176784 10573361152 1155017392 12.357 99 34 68 0 1215373952 52853088 7503472
06:40:08 39797 8192000000 10191448160 10664574976 1163982576 12.441 100 24 48 1 1308142384 138471984 5687824
06:41:48 75369 8192000000 10127590656 10602131456 1139463616 12.210 98 13 26 0 1258937952 116593584 2880752
06:44:04 158909 8192000000 10079945696 10569777152 1126177440 12.085 97 12 24 0 1194974064 66254864 2541760
06:48:10 109337 8192000000 10125749264 10583302144 1135745760 12.175 98 10 20 0 1168642800 31094592 1802448
06:51:11 72001 8192000000 10071709408 10543714304 1102298144 11.859 96 18 36 2 1135933408 30120384 3514880
06:53:25 19026 8192000000 10124753136 10569261056 1139764672 12.213 100 60 119 1 1223909792 71283024 12862096
06:54:47 94623 8192000000 10097360048 10638098432 1119312848 12.020 98 14 28 0 1189368752 66680688 3375216
06:57:53 52566 8192000000 10152656080 10595209216 1123685920 12.062 99 31 62 0 1194237856 64255312 6296624
06:59:50 90044 8192000000 10082256256 10552229888 1039341280 11.258 92 10 20 0 1074200016 32849904 2008832
07:02:21 16152 8192000000 10069225776 10488922112 1116036496 11.990 100 62 123 3 1147643872 19078128 12529248
07:03:40 115314 8192000000 10033427472 10482069504 1093937104 11.780 98 29 58 2 1167346320 67703536 5705680
07:06:47 38755 8192000000 10058551792 10517540864 1120251360 12.029 100 6 12 0 1160028816 38666512 1110944
07:08:29 70340 8192000000 10090821424 10585423872 1105775424 11.892 98 13 26 0 1181010944 72396944 2838576
07:10:48 36821 8192000000 10068170912 10512273408 1104068320 11.876 98 27 54 2 1140242416 30196864 5977232
07:12:31 40061 8192000000 10116651312 10548355072 1103551152 11.871 99 29 58 0 1181136656 71406560 6178944
07:14:14 84260 8192000000 10132262864 10603237376 1098751328 11.826 97 24 48 0 1167312224 63599680 4961216
07:16:40 72587 8192000000 10054619776 10497970176 1047540272 11.337 94 14 28 1 1114563248 63780272 3242704
07:18:54 31572 8192000000 10054339872 10481496064 1092346544 11.765 98 26 51 0 1133270576 36136304 4787728
07:20:28 122416 8192000000 10075107424 10540527616 1103562464 11.871 99 22 44 0 1152668976 44632368 4474144
07:23:34 60603 8192000000 10057613856 10579111936 1110330720 11.936 99 4 8 0 1162909760 51711488 867552
07:25:34 76862 8192000000 10081350960 10565287936 1130479952 12.126 100 16 31 4 1187010368 53127888 3402528
07:27:53 37820 8192000000 10098978672 10536894464 1120854480 12.035 99 31 62 2 1186310032 58772880 6682672
07:29:34 43484 8192000000 10107499920 10551066624 1112603680 11.957 99 32 64 0 1186949984 67545952 6800352
07:31:25 67558 8192000000 10140055392 10595500032 1118161840 12.010 98 28 55 2 1223658640 99211584 6285216
07:33:40 49025 8192000000 10093649904 10572402688 1134228384 12.161 100 9 18 0 1187640128 51109360 2302384
07:35:33 17654 8192000000 10112261088 10563620864 1136818336 12.186 100 37 71 1 1199749728 55816032 7115360
07:36:58 106575 8192000000 10068744352 10524110848 1132717440 12.147 100 22 44 2 1176393936 38383376 5293120
07:39:59 58726 8192000000 10144470304 10616844288 1144741520 12.260 100 10 20 1 1225570928 78452336 2377072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-03-2021 07:43:16 to 28-03-2021 07:43:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 85 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-03-2021 07:43:32 to 28-03-2021 07:43:34.

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          220700
MON$OLDEST_ACTIVE               221460
MON$OLDEST_SNAPSHOT             221460
MON$NEXT_TRANSACTION            221460
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                       2099136
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2393 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.2393 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    Sun Mar 28 07:41:27 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

28.03.21 07:43:35. 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-03-28 07:43:37 No severe PSQL-related problems occured
28.03.21 07:43:38. End of report.