Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.03.2021 07: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 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 16584220 kB
MemAvailable 31476460 kB
Buffers 0 kB
Cached 15040684 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-25 07:26:57
DTS_END                         2021-03-25 07:26:57.1730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40294
TRN_ID                          226909
ATT_ID                          588
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: 8818.77 23908 1058252
customer order: creation 166.01 409 19921
customer order: refuse 13.66 412 1639
order to supplier: creation 44.30 1447 5316
order to supplier: removal 3.36 114424 403
invoice (draft): creation 45.63 4160 5476
invoice (draft): removal 49.63 947 5956
invoice accept: apply 41.33 20317 4959
invoice accept: cancel 44.51 1005 5341
customer reserve: creation 3777.33 201 453280
customer reserve: removal 4357.41 70 522889
realization accept: apply 81.93 199 9831
realization accept: cancel 70.23 122 8428
payment from customer: creation 56.76 117 6811
payment from customer: removal 5.73 103 687
payment to supplier: creation 46.53 139 5583
payment to supplier: removal 3.41 68 409
service: total inventory turnovers 3.55 3964 426
service: total monetary turnovers 6.60 644 792
service: aggregate perf. data 0.84 14820 101
service: refresh index statistics 0.03 314586 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-03-2021 07:28:05 to 25-03-2021 07:28:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1283
TEST_TIME 2 2113
TEST_TIME 3 2849
TEST_TIME 4 3623
TEST_TIME 5 4535
TEST_TIME 6 4772
TEST_TIME 7 4724
TEST_TIME 8 5515
TEST_TIME 9 5594
TEST_TIME 10 5476
TEST_TIME 11 5722
TEST_TIME 12 6267
TEST_TIME 13 6433
TEST_TIME 14 6610
TEST_TIME 15 6593
TEST_TIME 16 6808
TEST_TIME 17 6916
TEST_TIME 18 7084
TEST_TIME 19 7234
TEST_TIME 20 7381
TEST_TIME 21 7601
TEST_TIME 22 7814
TEST_TIME 23 7914
TEST_TIME 24 8592
TEST_TIME 25 8584
TEST_TIME 26 8706
TEST_TIME 27 8578
TEST_TIME 28 8828
TEST_TIME 29 9150
TEST_TIME 30 9146
TEST_TIME 31 9439
TEST_TIME 32 9603
TEST_TIME 33 9701
TEST_TIME 34 9789
TEST_TIME 35 10221
TEST_TIME 36 10393
TEST_TIME 37 10439
TEST_TIME 38 10471
TEST_TIME 39 10571
TEST_TIME 40 10600
TEST_TIME 41 10541
TEST_TIME 42 10502
TEST_TIME 43 10321
TEST_TIME 44 10097
TEST_TIME 45 9928
TEST_TIME 46 9850
TEST_TIME 47 9821
TEST_TIME 48 9706
TEST_TIME 49 9620
TEST_TIME 50 9577
TEST_TIME 51 9644
TEST_TIME 52 9610
TEST_TIME 53 9543
TEST_TIME 54 9493
TEST_TIME 55 9499
TEST_TIME 56 9415
TEST_TIME 57 9318
TEST_TIME 58 9336
TEST_TIME 59 9254
TEST_TIME 60 9266
TEST_TIME 61 9365
TEST_TIME 62 9317
TEST_TIME 63 9283
TEST_TIME 64 9340
TEST_TIME 65 9479
TEST_TIME 66 9495
TEST_TIME 67 9476
TEST_TIME 68 9487
TEST_TIME 69 9471
TEST_TIME 70 9503
TEST_TIME 71 9572
TEST_TIME 72 9513
TEST_TIME 73 9467
TEST_TIME 74 9347
TEST_TIME 75 9378
TEST_TIME 76 9312
TEST_TIME 77 9265
TEST_TIME 78 9208
TEST_TIME 79 9165
TEST_TIME 80 9090
TEST_TIME 81 9026
TEST_TIME 82 9051
TEST_TIME 83 9004
TEST_TIME 84 9019
TEST_TIME 85 9014
TEST_TIME 86 9035
TEST_TIME 87 9252
TEST_TIME 88 9269
TEST_TIME 89 9202
TEST_TIME 90 9234
TEST_TIME 91 9262
TEST_TIME 92 9261
TEST_TIME 93 9226
TEST_TIME 94 9166
TEST_TIME 95 9105
TEST_TIME 96 9075
TEST_TIME 97 9029
TEST_TIME 98 9028
TEST_TIME 99 8981
TEST_TIME 100 8967
TEST_TIME 101 8993
TEST_TIME 102 8948
TEST_TIME 103 9002
TEST_TIME 104 9000
TEST_TIME 105 9023
TEST_TIME 106 9012
TEST_TIME 107 8988
TEST_TIME 108 9008
TEST_TIME 109 9019
TEST_TIME 110 9070
TEST_TIME 111 9000
TEST_TIME 112 8941
TEST_TIME 113 8916
TEST_TIME 114 8878
TEST_TIME 115 8836
TEST_TIME 116 8814
TEST_TIME 117 8828
TEST_TIME 118 8832
TEST_TIME 119 8820
TEST_TIME 120 8820

Performance per minute, chart


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

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 1477644 1477644 0 0.00 0 22946 8 0 0
doc_list_biud 1477643 1477643 0 0.00 0 27875 32 0 0
sp_add_invoice_to_stock 5054 4959 95 1.87 25 294489 20317 0 95
sp_cancel_adding_invoice 5341 5341 0 0.00 5 101533 1005 0 0
sp_cancel_client_order 1639 1639 0 0.00 4 23715 412 0 0
sp_cancel_customer_reserve 522889 522889 0 0.00 1 15841 70 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 6612 103 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 1891 69 0 0
sp_cancel_supplier_invoice 5966 5956 10 0.16 1 27884 948 0 10
sp_cancel_supplier_order 403 403 0 0.00 5 1112643 114425 0 0
sp_cancel_write_off 8428 8428 0 0.00 1 14589 123 0 0
sp_client_order 19921 19921 0 0.00 4 48933 409 0 0
sp_customer_reserve 453280 453280 0 0.00 0 158736 201 0 0
sp_fill_shopping_cart 30709 30709 0 0.00 1 14996 46 0 0
sp_fill_shopping_cart_clo_res 453277 453277 0 0.00 0 21156 16 0 0
sp_get_clo_for_invoice 4956 4956 0 0.00 0 53916 410 0 0
sp_kill_cost_storno 14865 14865 0 0.00 0 14356 69 0 0
sp_kill_qstorno_ret_qs2qd 1060565 1060565 0 0.00 0 27872 38 0 0
sp_kill_qty_storno 559443 559443 0 0.00 0 27874 83 0 0
sp_lock_selected_doc 20367 20367 0 0.00 0 7374 6 0 0
sp_make_cost_storno 27181 27181 0 0.00 0 18922 63 0 0
sp_multiply_rows_for_pdistr 27181 27181 0 0.00 0 8723 33 0 0
sp_multiply_rows_for_qdistr 19921 19921 0 0.00 0 45861 224 0 0
sp_pay_from_customer 6811 6811 0 0.00 4 13177 118 0 0
sp_pay_to_supplier 5583 5583 0 0.00 5 15735 139 0 0
sp_payment_common 12394 12394 0 0.00 1 9664 19 0 0
sp_qd_handle_on_cancel_clo 1639 1639 0 0.00 0 23705 376 0 0
sp_qd_handle_on_invoice_upd_sts 10297 10297 0 0.00 0 20061 434 0 0
sp_qd_handle_on_reserve_upd_sts 9831 9831 0 0.00 0 16337 54 0 0
sp_reserve_write_off 9831 9831 0 0.00 7 22146 199 0 0
sp_supplier_invoice 5476 5476 0 0.00 6 94457 4160 0 0
sp_supplier_order 5316 5316 0 0.00 6 52126 1448 0 0
srv_aggregate_perf_data 101 101 0 0.00 2646 176703 14820 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1387 152293 43956 0 0
srv_find_qd_qs_mism 1011801 1011801 0 0.00 0 22946 7 0 0
srv_make_invnt_saldo 426 426 0 0.00 23 91589 3964 0 0
srv_make_money_saldo 792 792 0 0.00 1 14683 644 0 0
srv_recalc_idx_stat 4 4 0 0.00 149255 619590 314587 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 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 4960 4960 0 0.00 0 18399 218 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 8494 129 0 0
t$perf-norm:sp_cancel_client_order 1639 1639 0 0.00 0 3047 10 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 2204 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 3411 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1158 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 894 14 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 25947 1492 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 2019 8 0 0
t$perf-norm:sp_client_order 19921 19921 0 0.00 0 11983 26 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 947 7 0 0
t$perf-norm:sp_pay_from_customer 6811 6811 0 0.00 0 11050 12 0 0
t$perf-norm:sp_pay_to_supplier 5583 5583 0 0.00 0 10017 12 0 0
t$perf-norm:sp_reserve_write_off 9831 9831 0 0.00 0 5734 12 0 0
t$perf-norm:sp_supplier_invoice 5476 5476 0 0.00 0 5442 21 0 0
t$perf-norm:sp_supplier_order 5316 5316 0 0.00 0 5542 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 327 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 190 5 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 809 10 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 1697 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10102 9912 190 1.88 0 6149 19 0 190
v_all_customers 35934 35934 0 0.00 0 77 1 0 0
v_all_suppliers 29526 29526 0 0.00 0 78 1 0 0
v_all_wares 39840 39840 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2422 10 0 0
v_cancel_client_order 3278 3278 0 0.00 0 12318 9 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 6498 14 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 3304 6 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 1870 7 0 0
v_cancel_supplier_invoice 796 776 20 2.51 0 689 14 0 20
v_cancel_supplier_order 806 806 0 0.00 0 1945 11 0 0
v_cancel_write_off 1978 1978 0 0.00 0 4648 17 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 5716 2 0 0
v_max_id_clo_res 983 983 0 0.00 0 4102 13 0 0
v_max_id_ord_sup 5474 5474 0 0.00 0 3967 3 0 0
v_max_non_paid_invoice 1609 1609 0 0.00 0 161 2 0 0
v_max_non_paid_realizn 3754 3754 0 0.00 0 7113 7 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 7443 14 0 0
v_min_id_clo_res 983 983 0 0.00 0 4719 12 0 0
v_min_id_ord_sup 5474 5474 0 0.00 0 5987 6 0 0
v_min_non_paid_invoice 5583 5583 0 0.00 0 4030 10 0 0
v_min_non_paid_realizn 6811 6811 0 0.00 0 9657 12 0 0
v_our_firm 10018 10018 0 0.00 0 14 1 0 0
v_reserve_write_off 19662 19662 0 0.00 0 9430 8 0 0
x_lock_depdocs_on_canc_invoice 10909 10909 0 0.00 0 19431 130 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 3267 348 0 0
x_make_qstorno_1000_1200 5316 5316 0 0.00 2 52055 1346 0 0
x_make_qstorno_1200_2000 5476 5476 0 0.00 3 94447 4005 0 0
x_make_qstorno_2100_3300 453280 453280 0 0.00 0 158734 182 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-03-2021 07:28:13 to 25-03-2021 07:28:18.

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
04:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:02 6839 8192000000 9709382400 10006052864 802983632 8.927 100 84 166 1 829974704 6982016 20009056
04:59:17 22241 8192000000 9765315968 10069639168 839622624 9.296 100 89 178 0 872288256 11128928 21536704
05:00:54 3472 8192000000 9793705024 10107346944 862888256 9.529 100 86 172 0 903793392 20733696 20171440
05:02:02 13314 8192000000 9828361104 10155331584 887666608 9.776 100 77 154 1 944847680 40020320 17160752
05:03:21 7585 8192000000 9879425888 10218647552 934333872 10.237 100 69 138 0 995248544 46508192 14406480
05:04:33 5929 8192000000 9936581216 10282205184 975283616 10.638 100 81 163 2 1050814432 59469056 16061760
05:05:40 5899 8192000000 9998800384 10357182464 1012419328 10.999 100 86 171 1 1117969664 88686928 16863408
05:06:48 4949 8192000000 10013522704 10378567680 1037145568 11.237 100 87 173 2 1129176096 74095328 17935200
05:07:54 2078 8192000000 10025182640 10390343680 1044300208 11.306 100 83 166 0 1131571040 70414880 16855952
05:09:02 2730 8192000000 10019460112 10396024832 1054239920 11.401 100 77 152 3 1146823152 76585536 15997696
05:10:06 7379 8192000000 9987547408 10376036352 1067259728 11.526 100 52 102 1 1107927184 29881648 10785808
05:11:15 4721 8192000000 10010559488 10399731712 1079358656 11.641 100 37 72 1 1124688848 37652768 7677424
05:12:21 4360 8192000000 10010506336 10397376512 1087977136 11.723 100 53 107 2 1122307216 22513376 11816704
05:13:29 25351 8192000000 10006863088 10417455104 1086016896 11.705 100 41 83 0 1119719056 25261392 8440768
05:14:59 7316 8192000000 10024150608 10426736640 1091252960 11.755 100 40 78 0 1129657472 29277024 9127488
05:16:09 8475 8192000000 10001292864 10410926080 1094601552 11.786 100 26 52 0 1131336272 30905040 5829680
05:17:20 5343 8192000000 9999631328 10395447296 1080640464 11.654 99 46 93 0 1101940752 10831376 10468912
05:18:31 22511 8192000000 9976865792 10379448320 1026662736 11.136 94 31 63 2 1067046272 33421104 6962432
05:19:54 5445 8192000000 9960776496 10342359040 1015863984 11.032 96 49 98 4 1044395984 18015376 10516624
05:21:02 2837 8192000000 9979042640 10359095296 1045488320 11.317 100 58 116 3 1086549936 29173568 11888048
05:22:06 50655 8192000000 9943460752 10339205120 1029860992 11.167 99 23 45 0 1062397584 27660928 4875664
05:24:06 10089 8192000000 9970743920 10372370432 1034023232 11.207 99 38 76 3 1058500000 16354624 8122144
05:25:17 9608 8192000000 9980814448 10385264640 1047491248 11.337 99 31 61 2 1074795856 20270864 7033744
05:26:27 7737 8192000000 9977668320 10384523264 1061650016 11.472 100 33 62 4 1092684992 24309072 6725904
05:27:47 95909 8192000000 10030221616 10438148096 1087023792 11.714 100 41 81 1 1122242704 25571088 9647824
05:30:37 14755 8192000000 10095968496 10494214144 1111815408 11.950 100 64 127 0 1208657584 84452176 12390000
05:31:57 14278 8192000000 10081218208 10499915776 1123270688 12.058 100 51 102 0 1199279696 65811088 10197920
05:33:17 15992 8192000000 10137776176 10537889792 1136221744 12.180 100 67 133 1 1234716736 84765392 13729600
05:34:39 12716 8192000000 10145100576 10552168448 1145773520 12.270 100 47 94 2 1244042512 87365824 10903168
05:36:00 8331 8192000000 10170735520 10582761472 1152152096 12.330 100 65 130 0 1283110768 116393024 14565648
05:37:13 21676 8192000000 10213152320 10643951616 1159658352 12.400 100 49 98 1 1331356768 160350128 11348288
05:38:39 8997 8192000000 10204023376 10628456448 1170942608 12.506 100 65 128 0 1316663920 131315392 14405920
05:39:54 4271 8192000000 10208286752 10628505600 1168684736 12.485 100 69 138 1 1311889744 128157792 15047216
05:41:07 7842 8192000000 10232679744 10647252992 1170948704 12.506 100 64 123 1 1333197376 148685776 13562896
05:42:19 6654 8192000000 10261515472 10684153856 1168487312 12.483 100 73 146 0 1366045648 181028336 16530000
05:43:35 11521 8192000000 10234214928 10654355456 1153708544 12.344 99 45 90 1 1335161776 171247296 10205936
05:44:52 5080 8192000000 10285253440 10691620864 1166503376 12.464 100 87 171 2 1383431200 197313504 19614320
05:46:01 1385 8192000000 10275628928 10683617280 1161270416 12.415 100 92 185 0 1375549440 193160768 21118256
05:47:09 1990 8192000000 10305643184 10704027648 1138138736 12.198 99 84 166 0 1394230352 237215296 18876320
05:48:14 18657 8192000000 10310486752 10738692096 1149470928 12.305 100 38 76 1 1422022016 263366592 9184496
05:49:43 5581 8192000000 10336102368 10739040256 1128246080 12.105 100 85 169 1 1435659552 287500240 19913232
05:50:53 4840 8192000000 10271638912 10720878592 1133409856 12.153 100 56 109 3 1364798448 217755776 13632816
05:52:04 9217 8192000000 10307205472 10729582592 1147690480 12.288 100 40 81 0 1404845616 246793424 10361712
05:53:16 6585 8192000000 10331443456 10745819136 1125046064 12.075 98 55 110 3 1400670032 262403424 13220544
05:54:25 11748 8192000000 10318485776 10758418432 1124526016 12.070 99 29 58 0 1418953600 286702272 7725312
05:55:48 10243 8192000000 10308412736 10776633344 1136658032 12.184 100 28 56 1 1409608144 265631168 7318944
05:57:02 30019 8192000000 10265364800 10796179456 1120411136 12.031 99 27 54 0 1413095184 285376816 7307232
05:58:52 22889 8192000000 10263390544 10721550336 1135526160 12.173 100 20 40 0 1352316544 211703824 5086560
06:00:17 36601 8192000000 10210344128 10701742080 1115793632 11.987 100 12 24 1 1296427248 177721616 2912000
06:02:02 52582 8192000000 10105564848 10600640512 1105410896 11.889 98 7 14 0 1185894592 79012016 1471680
06:03:55 148853 8192000000 10094173648 10589237248 1093085424 11.772 96 3 6 0 1149763536 56330848 347264
06:07:24 116143 8192000000 10092495456 10578157568 1134990256 12.168 99 13 26 0 1168801024 31448848 2361920
06:11:20 15674 8192000000 10091084576 10521378816 1145369216 12.266 100 76 152 2 1186352432 25235312 15747904
06:12:37 27711 8192000000 10121698992 10562379776 1155712576 12.363 100 46 90 2 1212060816 46240096 10108144
06:14:06 25571 8192000000 10109030624 10572410880 1144265088 12.256 99 19 39 0 1205494352 57109152 4120112
06:15:38 152293 8192000000 10090073968 10576654336 1108628880 11.919 96 26 51 1 1209658160 94883824 6145456
06:19:12 50867 8192000000 10094872368 10578370560 1149008112 12.300 100 13 26 0 1201271568 49284608 2978848
06:21:05 77906 8192000000 10099281232 10618834944 1128959728 12.112 98 14 28 1 1192182576 60253616 2969232
06:23:43 46447 8192000000 10059063024 10552954880 1142613152 12.240 100 11 22 0 1211866480 66335712 2917616
06:25:38 57101 8192000000 10107682048 10587545600 1133015616 12.150 98 14 28 0 1197786832 61441648 3329568
06:27:36 45170 8192000000 10052004816 10521587712 1109998368 11.932 97 11 22 0 1159864000 47592000 2273632
06:29:24 47893 8192000000 10147616112 10599673856 1136402128 12.182 98 30 60 1 1219984432 77116592 6465712
06:31:15 78773 8192000000 10095051056 10559410176 1109361520 11.926 97 14 27 0 1182774048 70560672 2851856
06:33:34 35211 8192000000 10094622000 10526056448 1120848000 12.035 98 34 69 0 1158358544 29629664 7880880
06:35:28 39512 8192000000 10135537040 10611941376 1137011632 12.187 99 27 54 1 1232778096 90029104 5737360
06:37:24 65644 8192000000 10058347712 10501050368 1086085760 11.705 95 25 50 3 1111733904 20658976 4989168
06:39:33 128080 8192000000 10083942224 10582720512 1125543904 12.079 100 41 82 0 1212888240 78936256 8408080
06:43:56 68120 8192000000 10064494208 10514841600 1114801632 11.978 99 16 33 0 1152537712 34079968 3656112
06:46:04 57379 8192000000 10127227264 10580021248 1120278352 12.030 99 15 28 0 1190481648 66813696 3389600
06:49:04 32880 8192000000 10117954192 10561515520 1102658688 11.863 98 16 33 1 1204527296 98428928 3439680
06:50:37 38636 8192000000 10116531120 10552156160 1062371472 11.479 95 18 36 0 1165597424 98726656 4499296
06:52:42 76402 8192000000 10088385360 10668109824 1108250928 11.916 99 16 32 0 1210549616 98899584 3399104
06:54:59 24772 8192000000 10055039728 10484277248 1104695632 11.882 98 52 101 2 1137104544 22101264 10307648
06:56:25 42101 8192000000 10112134400 10552537088 1121705392 12.043 99 21 42 0 1171324784 45559568 4059824
06:58:14 57763 8192000000 10094924928 10536181760 1097502512 11.814 96 39 78 1 1149932880 45106048 7324320
07:00:14 94404 8192000000 10072369648 10528690176 1136906864 12.186 100 20 40 1 1171107456 30172496 4028096
07:04:31 42233 8192000000 10058611232 10530705408 1131219344 12.133 100 14 27 1 1176436256 42051312 3165600
07:06:15 88261 8192000000 10131582576 10620506112 1130516240 12.126 98 29 58 2 1199065744 62327200 6222304
07:08:44 62242 8192000000 10112074240 10608689152 1123297584 12.058 98 24 48 1 1234319200 105821744 5199872
07:10:47 55105 8192000000 10067110320 10539573248 1127963376 12.102 99 14 28 1 1163988400 33194240 2830784
07:13:04 30458 8192000000 10095953520 10534895616 1137330240 12.190 100 42 84 2 1185177616 39054688 8792688
07:14:36 92629 8192000000 10117011552 10601533440 1117177216 12.000 98 32 64 2 1180329632 55703504 7448912
07:17:11 104458 8192000000 10044469552 10496745472 1120179840 12.029 99 10 20 0 1149914288 27987776 1746672
07:19:58 66563 8192000000 10071207456 10547183616 1136880816 12.186 100 13 26 1 1182838080 43297072 2660192
07:22:04 63530 8192000000 10063932336 10540711936 1117620416 12.005 98 15 29 3 1177619600 56826544 3172640
07:24:10 44576 8192000000 10072267392 10522320896 1115481920 11.984 98 13 26 1 1156584784 38380688 2722176
07:25:57 27417 8192000000 10131631264 10576498688 1151324112 12.322 100 36 71 0 1218132128 58617088 8190928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-03-2021 07:28:19 to 25-03-2021 07:28:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 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 25-03-2021 07:28:35 to 25-03-2021 07:28:37.

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          226010
MON$OLDEST_ACTIVE               228698
MON$OLDEST_SNAPSHOT             228698
MON$NEXT_TRANSACTION            228698
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                       2131392
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:310: 
:311: mscs-test-161.budzdorov.ru    Thu Mar 25 07:27:04 2021
:312:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:313: 
--- end of diff output ---

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

25.03.21 07:28:38. 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-25 07:28:41 No severe PSQL-related problems occured
25.03.21 07:28:42. End of report.