Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2021 04:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15902192 kB
MemAvailable 30941620 kB
Buffers 20 kB
Cached 15713280 kB
SwapCached 2584 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.1
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-08-19 04:37:07
DTS_END                         2021-08-19 04:37:07.0210
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/35622
TRN_ID                          231142
ATT_ID                          621
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: 9069.56 24199 1088347
customer order: creation 165.51 396 19861
customer order: refuse 14.94 398 1793
order to supplier: creation 44.39 1469 5327
order to supplier: removal 3.54 114893 425
invoice (draft): creation 44.06 3891 5287
invoice (draft): removal 51.49 945 6179
invoice accept: apply 41.42 20944 4970
invoice accept: cancel 46.79 956 5615
customer reserve: creation 3846.33 206 461560
customer reserve: removal 4527.42 71 543290
realization accept: apply 80.97 191 9717
realization accept: cancel 77.77 127 9332
payment from customer: creation 58.43 139 7012
payment from customer: removal 5.43 98 651
payment to supplier: creation 46.86 134 5623
payment to supplier: removal 3.40 112 408
service: total inventory turnovers 3.70 3949 444
service: total monetary turnovers 6.28 611 754
service: aggregate perf. data 0.79 18570 95
service: refresh index statistics 0.03 315875 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-08-2021 04:38:31 to 19-08-2021 04:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3215
TEST_TIME 2 3672
TEST_TIME 3 3836
TEST_TIME 4 3793
TEST_TIME 5 4395
TEST_TIME 6 5342
TEST_TIME 7 5133
TEST_TIME 8 5716
TEST_TIME 9 6521
TEST_TIME 10 6998
TEST_TIME 11 7195
TEST_TIME 12 7088
TEST_TIME 13 7239
TEST_TIME 14 7179
TEST_TIME 15 7507
TEST_TIME 16 7478
TEST_TIME 17 7540
TEST_TIME 18 7658
TEST_TIME 19 7795
TEST_TIME 20 8072
TEST_TIME 21 8370
TEST_TIME 22 8539
TEST_TIME 23 8519
TEST_TIME 24 8947
TEST_TIME 25 9160
TEST_TIME 26 9105
TEST_TIME 27 9517
TEST_TIME 28 9688
TEST_TIME 29 10295
TEST_TIME 30 10537
TEST_TIME 31 10396
TEST_TIME 32 10435
TEST_TIME 33 10454
TEST_TIME 34 10437
TEST_TIME 35 10592
TEST_TIME 36 10522
TEST_TIME 37 10457
TEST_TIME 38 10432
TEST_TIME 39 10356
TEST_TIME 40 10336
TEST_TIME 41 10182
TEST_TIME 42 10213
TEST_TIME 43 10024
TEST_TIME 44 9907
TEST_TIME 45 9855
TEST_TIME 46 9708
TEST_TIME 47 9824
TEST_TIME 48 9939
TEST_TIME 49 9952
TEST_TIME 50 9912
TEST_TIME 51 9850
TEST_TIME 52 9889
TEST_TIME 53 9884
TEST_TIME 54 9970
TEST_TIME 55 9932
TEST_TIME 56 9969
TEST_TIME 57 9856
TEST_TIME 58 9917
TEST_TIME 59 9949
TEST_TIME 60 10083
TEST_TIME 61 10026
TEST_TIME 62 9986
TEST_TIME 63 10028
TEST_TIME 64 10141
TEST_TIME 65 10140
TEST_TIME 66 10073
TEST_TIME 67 10072
TEST_TIME 68 9947
TEST_TIME 69 9856
TEST_TIME 70 9823
TEST_TIME 71 9770
TEST_TIME 72 9691
TEST_TIME 73 9650
TEST_TIME 74 9626
TEST_TIME 75 9577
TEST_TIME 76 9532
TEST_TIME 77 9480
TEST_TIME 78 9438
TEST_TIME 79 9365
TEST_TIME 80 9333
TEST_TIME 81 9330
TEST_TIME 82 9306
TEST_TIME 83 9252
TEST_TIME 84 9259
TEST_TIME 85 9231
TEST_TIME 86 9325
TEST_TIME 87 9287
TEST_TIME 88 9295
TEST_TIME 89 9354
TEST_TIME 90 9356
TEST_TIME 91 9395
TEST_TIME 92 9428
TEST_TIME 93 9411
TEST_TIME 94 9454
TEST_TIME 95 9431
TEST_TIME 96 9360
TEST_TIME 97 9325
TEST_TIME 98 9298
TEST_TIME 99 9282
TEST_TIME 100 9242
TEST_TIME 101 9212
TEST_TIME 102 9167
TEST_TIME 103 9182
TEST_TIME 104 9237
TEST_TIME 105 9214
TEST_TIME 106 9186
TEST_TIME 107 9221
TEST_TIME 108 9238
TEST_TIME 109 9297
TEST_TIME 110 9263
TEST_TIME 111 9308
TEST_TIME 112 9302
TEST_TIME 113 9355
TEST_TIME 114 9281
TEST_TIME 115 9215
TEST_TIME 116 9191
TEST_TIME 117 9187
TEST_TIME 118 9180
TEST_TIME 119 9132
TEST_TIME 120 9074

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-08-2021 04:38:35 to 19-08-2021 04:38:38.

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 1514066 1514066 0 0.00 0 21111 8 0 0
doc_list_biud 1514065 1514065 0 0.00 0 44647 32 0 0
sp_add_invoice_to_stock 5070 4970 100 1.97 28 230109 20944 0 100
sp_cancel_adding_invoice 5615 5615 0 0.00 3 73087 957 0 0
sp_cancel_client_order 1793 1793 0 0.00 4 13259 399 0 0
sp_cancel_customer_reserve 543290 543290 0 0.00 1 34329 71 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 5267 99 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 3322 112 0 0
sp_cancel_supplier_invoice 6184 6179 5 0.08 1 17318 946 0 5
sp_cancel_supplier_order 425 425 0 0.00 5 1090038 114893 0 0
sp_cancel_write_off 9332 9332 0 0.00 1 16719 128 0 0
sp_client_order 19861 19861 0 0.00 4 25023 396 0 0
sp_customer_reserve 461560 461560 0 0.00 0 113128 206 0 0
sp_fill_shopping_cart 30464 30464 0 0.00 1 20984 44 0 0
sp_fill_shopping_cart_clo_res 461548 461548 0 0.00 0 17532 16 0 0
sp_get_clo_for_invoice 4958 4958 0 0.00 0 27105 363 0 0
sp_kill_cost_storno 16006 16006 0 0.00 0 6859 66 0 0
sp_kill_qstorno_ret_qs2qd 1102516 1102516 0 0.00 0 44645 38 0 0
sp_kill_qty_storno 581309 581309 0 0.00 0 44646 83 0 0
sp_lock_selected_doc 20403 20403 0 0.00 0 7668 4 0 0
sp_make_cost_storno 27310 27310 0 0.00 0 14680 66 0 0
sp_multiply_rows_for_pdistr 27310 27310 0 0.00 0 10431 36 0 0
sp_multiply_rows_for_qdistr 19861 19861 0 0.00 0 25007 181 0 0
sp_pay_from_customer 7012 7012 0 0.00 4 16893 140 0 0
sp_pay_to_supplier 5623 5623 0 0.00 5 16485 134 0 0
sp_payment_common 12635 12635 0 0.00 1 14678 33 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 13095 364 0 0
sp_qd_handle_on_invoice_upd_sts 10573 10573 0 0.00 0 19210 394 0 0
sp_qd_handle_on_reserve_upd_sts 9717 9717 0 0.00 0 9179 55 0 0
sp_reserve_write_off 9717 9717 0 0.00 7 16525 191 0 0
sp_supplier_invoice 5287 5287 0 0.00 8 114235 3892 0 0
sp_supplier_order 5327 5327 0 0.00 5 41233 1470 0 0
srv_aggregate_perf_data 95 95 0 0.00 2857 210312 18571 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 715 121774 48068 0 0
srv_find_qd_qs_mism 1040848 1040848 0 0.00 0 21110 7 0 0
srv_make_invnt_saldo 444 444 0 0.00 50 115037 3950 0 0
srv_make_money_saldo 754 754 0 0.00 1 23597 611 0 0
srv_recalc_idx_stat 4 4 0 0.00 168056 398630 315876 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4970 4970 0 0.00 0 14425 209 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 6169 122 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 7911 18 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 459 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 3360 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 21459 63 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1215 19 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 20734 1768 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 2356 9 0 0
t$perf-norm:sp_client_order 19861 19861 0 0.00 0 11337 24 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 22296 32 0 0
t$perf-norm:sp_pay_from_customer 7012 7012 0 0.00 0 6754 12 0 0
t$perf-norm:sp_pay_to_supplier 5623 5623 0 0.00 0 14083 13 0 0
t$perf-norm:sp_reserve_write_off 9717 9717 0 0.00 0 7761 14 0 0
t$perf-norm:sp_supplier_invoice 5287 5287 0 0.00 0 11298 30 0 0
t$perf-norm:sp_supplier_order 5327 5327 0 0.00 0 10712 20 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 2242 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2139 34 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 2016 11 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 3178 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10112 9912 200 1.97 0 6611 18 0 200
v_all_customers 36616 36616 0 0.00 0 21 1 0 0
v_all_suppliers 29448 29448 0 0.00 0 24 1 0 0
v_all_wares 39720 39720 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1752 8 0 0
v_cancel_client_order 3586 3586 0 0.00 0 5237 7 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 5193 13 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 1518 5 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 3137 14 0 0
v_cancel_supplier_invoice 762 752 10 1.31 0 256 12 0 10
v_cancel_supplier_order 850 850 0 0.00 0 2066 10 0 0
v_cancel_write_off 1982 1982 0 0.00 0 1586 15 0 0
v_max_id_clo_ord 5324 5324 0 0.00 0 1210 2 0 0
v_max_id_clo_res 961 961 0 0.00 0 2084 14 0 0
v_max_id_ord_sup 5279 5279 0 0.00 0 9073 7 0 0
v_max_non_paid_invoice 1502 1502 0 0.00 0 115 2 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 1200 3 0 0
v_min_id_clo_ord 5324 5324 0 0.00 0 5345 7 0 0
v_min_id_clo_res 961 961 0 0.00 0 2982 9 0 0
v_min_id_ord_sup 5279 5279 0 0.00 0 8831 12 0 0
v_min_non_paid_invoice 5623 5623 0 0.00 0 10262 23 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 6496 26 0 0
v_our_firm 9784 9784 0 0.00 0 22 1 0 0
v_reserve_write_off 19434 19434 0 0.00 0 4893 5 0 0
x_lock_depdocs_on_canc_invoice 11418 11418 0 0.00 0 14095 125 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 7095 355 0 0
x_make_qstorno_1000_1200 5327 5327 0 0.00 2 41215 1377 0 0
x_make_qstorno_1200_2000 5287 5287 0 0.00 4 113768 3731 0 0
x_make_qstorno_2100_3300 461560 461560 0 0.00 0 113124 187 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-08-2021 04:38:39 to 19-08-2021 04:38:44.

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
02:07:10 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 31622 8192000000 9723907264 10023010304 801753584 8.914 100 86 173 0 830116976 7582768 20780624
02:09:50 24025 8192000000 9768325760 10074198016 841521280 9.315 100 89 178 0 875197424 12377504 21298640
02:11:23 19019 8192000000 9815114688 10136039424 879176032 9.691 100 87 174 1 924821760 25178688 20467040
02:12:44 14429 8192000000 9872604304 10207596544 917761024 10.074 100 78 156 0 987109040 52015776 17332240
02:14:01 10964 8192000000 9929579520 10272415744 958337408 10.473 100 83 166 0 1040019168 64328608 17353152
02:15:15 5119 8192000000 9954817360 10308055040 990236160 10.784 100 80 160 0 1068870944 62393584 16241200
02:16:22 2723 8192000000 10003160320 10364665856 1017891040 11.052 100 92 184 0 1118225360 81365104 18969216
02:17:29 4641 8192000000 9995280528 10361864192 1035889184 11.225 100 71 140 1 1112974016 63019056 14065776
02:18:37 1589 8192000000 10052585440 10423136256 1058831712 11.445 100 96 192 0 1167339776 89587696 18920368
02:19:41 15399 8192000000 10009236992 10388705280 1067084336 11.524 100 62 122 1 1121955616 42872832 11998448
02:20:58 3635 8192000000 10021139760 10404171776 1075488432 11.604 100 65 131 2 1126824016 38151440 13184144
02:22:03 7113 8192000000 9998708528 10396057600 1084235632 11.688 100 49 98 0 1118496208 23872304 10388272
02:23:14 6525 8192000000 10004417264 10407923712 1093366256 11.775 100 40 81 1 1126142480 24285328 8490896
02:24:28 54937 8192000000 10014951056 10408595456 1092894992 11.770 100 58 114 1 1115296064 10223200 12177872
02:26:23 5839 8192000000 10016745808 10418831360 1097105792 11.810 100 58 115 1 1132976000 23102240 12767968
02:27:32 5967 8192000000 10000693824 10408890368 1093787680 11.779 100 39 78 1 1127055584 24373216 8894688
02:28:40 3639 8192000000 9969429376 10363170816 1058230016 11.440 99 53 104 1 1090281072 20435344 11615712
02:29:44 41496 8192000000 9930841712 10324475904 975306032 10.638 92 20 40 1 1008230656 28187680 4736944
02:31:26 2982 8192000000 9951693488 10325397504 1016712896 11.040 99 50 98 1 1046954048 19195616 11045536
02:32:30 7375 8192000000 9936753728 10323300352 998531312 10.864 97 41 82 0 1029556112 21079568 9945232
02:33:38 14553 8192000000 9950665488 10340937728 1043355088 11.297 100 35 70 1 1070083696 19223632 7504976
02:34:58 26611 8192000000 9948112368 10354487296 1034080432 11.208 98 14 26 1 1049058736 11943888 3034416
02:36:27 6629 8192000000 9990338416 10386157568 1063921024 11.494 100 47 93 2 1087628512 13763120 9944368
02:37:39 34188 8192000000 10017016336 10410713088 1087702336 11.721 100 50 100 2 1120087664 22054752 10330576
02:39:16 48462 8192000000 10057098080 10461323264 1102988592 11.866 100 35 70 0 1172585520 61908256 7688672
02:41:08 39308 8192000000 10101904128 10510262272 1115162832 11.981 100 34 68 0 1202772672 80446560 7163280
02:42:54 11772 8192000000 10154451200 10565828608 1131988704 12.140 100 57 114 0 1259223888 115396368 11838816
02:44:12 18243 8192000000 10154799056 10567176192 1140559088 12.221 100 48 94 1 1262394784 111959552 9876144
02:45:34 10409 8192000000 10179871088 10603368448 1147806160 12.289 100 66 130 1 1283315952 121118480 14391312
02:46:49 4108 8192000000 10196790528 10605936640 1155818848 12.364 100 71 142 1 1306828304 136155504 14853952
02:48:00 5676 8192000000 10217285792 10629791744 1176584032 12.558 100 64 124 0 1318829120 129049744 13195344
02:49:09 2328 8192000000 10233078320 10640977920 1176967696 12.562 100 73 147 0 1334105648 141128784 16009168
02:50:18 13481 8192000000 10250379808 10662940672 1158326752 12.388 99 61 122 0 1331847616 159573216 13947648
02:51:36 5746 8192000000 10291736032 10695446528 1143854224 12.252 97 74 149 1 1370015392 208724176 17436992
02:52:52 2337 8192000000 10321973072 10731814912 1181675184 12.606 100 78 157 0 1421481440 222049632 17756624
02:53:58 2629 8192000000 10366764320 10770624512 1163581392 12.437 99 83 166 1 1452658896 269919120 19158384
02:55:05 4034 8192000000 10358946240 10767679488 1150113008 12.311 99 64 127 1 1444291712 278933152 15245552
02:56:15 2714 8192000000 10376532496 10786365440 1161382432 12.416 100 77 153 2 1474612080 294324944 18904704
02:57:23 1575 8192000000 10397655072 10803879936 1160176272 12.405 99 90 181 2 1488144560 306440256 21528032
02:58:29 11655 8192000000 10367386416 10795560960 1164487728 12.445 100 44 88 0 1465868656 290519584 10861344
02:59:53 13140 8192000000 10386296624 10815950848 1139334768 12.209 99 39 76 1 1484706496 334677408 10694320
03:01:15 2652 8192000000 10398291248 10796449792 1127868720 12.101 99 87 173 1 1486482880 337449488 21164672
03:02:22 3225 8192000000 10346564720 10790723584 1137669056 12.194 100 46 92 0 1439315104 290078096 11567952
03:03:29 25820 8192000000 10305339472 10738311168 1109863664 11.931 99 32 63 1 1401023936 282637472 8522800
03:04:57 33003 8192000000 10267062656 10757468160 1122634656 12.052 100 23 46 2 1386722048 257972688 6114704
03:06:37 20325 8192000000 10103434480 10615201792 1081622496 11.663 98 15 28 1 1263606768 177880016 4104256
03:07:58 53386 8192000000 10047125344 10482507776 1088469520 11.728 97 14 28 0 1131598416 40090384 3038512
03:09:53 83958 8192000000 10087762176 10540630016 1113974240 11.970 99 9 19 1 1155873376 40354096 1545040
03:12:18 76011 8192000000 10086653680 10547470336 1100585200 11.843 97 9 18 1 1147759024 45475440 1698384
03:14:44 43503 8192000000 10094872704 10537775104 1111949184 11.951 98 42 81 2 1165376320 43918016 9509120
03:16:32 102790 8192000000 10094998816 10597593088 1124563344 12.070 99 40 80 2 1191487248 58159344 8764560
03:19:34 118686 8192000000 10092724048 10529091584 1134811792 12.167 99 23 46 0 1192317888 52788384 4717712
03:22:38 54643 8192000000 10202170672 10645520384 1142517056 12.239 99 23 46 0 1276895920 128526240 5852624
03:24:42 61171 8192000000 10242074160 10734104576 1140480672 12.220 98 49 98 1 1340297664 189063552 10753440
03:26:53 57007 8192000000 10215911072 10718437376 1143757424 12.251 99 14 28 1 1287920032 140812784 3349824
03:28:53 95090 8192000000 10147462112 10613055488 1144420048 12.257 100 13 26 1 1269094160 121655408 3018704
03:31:30 59845 8192000000 10106223328 10561724416 1142912832 12.243 100 19 38 2 1186656224 39671248 4072144
03:33:33 55061 8192000000 10140110688 10585624576 1131470192 12.135 98 22 43 1 1198625920 61847040 5308688
03:35:40 67446 8192000000 10159187056 10665459712 1141067936 12.226 99 17 34 0 1261432608 116418192 3946480
03:37:56 102691 8192000000 10093277664 10546921472 1081492592 11.662 94 22 44 0 1122212192 35545936 5173664
03:40:39 93112 8192000000 10079690080 10573926400 1128914048 12.111 99 6 12 0 1184714464 54878576 921840
03:43:14 50620 8192000000 10086317664 10510487552 1077333040 11.622 94 28 56 0 1134746384 52256000 5157344
03:45:07 103111 8192000000 10069432832 10501447680 1083553120 11.681 96 35 70 0 1145852848 55163344 7136384
03:47:56 54286 8192000000 10053997248 10517733376 1106226256 11.897 98 13 26 0 1149218992 40441456 2551280
03:49:55 77898 8192000000 10081341872 10558193664 1096226480 11.802 97 8 15 1 1133164416 35614848 1323088
03:52:16 57128 8192000000 10058837280 10534596608 1096010880 11.800 97 16 32 1 1153677632 54083136 3583616
03:54:14 86128 8192000000 10057636624 10502438912 1093048352 11.772 97 22 45 1 1113560144 15811872 4699920
03:56:58 713 8192000000 10079291888 10495651840 1117528144 12.004 100 85 168 2 1174056032 37763072 18764816
03:58:00 79240 8192000000 10044787744 10548617216 1109153584 11.924 99 9 17 1 1179253232 68338992 1760656
04:00:29 64114 8192000000 10036006864 10498269184 1054939488 11.408 94 21 42 0 1109542592 49990112 4612992
04:02:40 49274 8192000000 10091235168 10543693824 1114849344 11.978 100 38 76 2 1211034864 88194688 7990832
04:04:31 50029 8192000000 10099273216 10570305536 1129557280 12.117 100 32 64 2 1197835856 61779248 6499328
04:06:24 77813 8192000000 10108720720 10582773760 1084662368 11.692 96 14 26 0 1148565376 61436672 2466336
04:08:42 15826 8192000000 10075438016 10501705728 1109571728 11.928 99 40 80 0 1147953088 30619824 7761536
04:10:04 45023 8192000000 10062635728 10498101248 1086244960 11.707 96 20 38 1 1118413248 28242672 3925616
04:11:49 57234 8192000000 10093401600 10532106240 1121411872 12.040 99 49 94 2 1176582608 45094448 10076288
04:13:47 121773 8192000000 10086565040 10545455104 1113527632 11.966 98 26 52 0 1154629936 35032848 6069456
04:16:50 49535 8192000000 10060978128 10520240128 1126659776 12.090 100 10 20 1 1178670576 50050016 1960784
04:18:40 85642 8192000000 10095235552 10601222144 1141476976 12.229 100 5 10 0 1191982240 49516576 988688
04:21:06 101067 8192000000 10076565392 10532880384 1103493408 11.871 96 15 30 1 1144456144 37847024 3115712
04:23:48 37614 8192000000 10121536784 10586828800 1128541488 12.108 98 30 60 0 1208707488 74063280 6102720
04:25:26 52531 8192000000 10082840608 10596855808 1120742368 12.034 98 17 33 2 1214253936 89770192 3741376
04:27:39 59398 8192000000 10056030720 10549399552 1104342048 11.879 98 20 40 1 1185275744 76614128 4319568
04:29:43 20049 8192000000 10095329584 10538217472 1119863760 12.026 100 39 78 2 1170214400 42664896 7685744
04:31:06 110973 8192000000 10050914944 10522202112 1105573520 11.890 98 14 27 0 1146970880 38364256 3033104
04:33:58 59001 8192000000 10072534720 10559926272 1124149168 12.066 99 21 42 0 1186359856 57414912 4795776
04:36:04 69008 8192000000 9790459840 10261381120 874169424 9.642 78 6 12 0 925460096 50336240 954432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-08-2021 04:38:45 to 19-08-2021 04:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-08-2021 04:39:01 to 19-08-2021 04:39:03.

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          231437
MON$OLDEST_ACTIVE               231438
MON$OLDEST_SNAPSHOT             231438
MON$NEXT_TRANSACTION            231438
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                       2066784
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:245: 
:246: mscs-test-161.budzdorov.ru    Thu Aug 19 04:37:18 2021
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:248: 
--- end of diff output ---

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

19.08.21 04:39:04. 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-08-19 04:39:07 No severe PSQL-related problems occured
19.08.21 04:39:08. End of report.