Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.11.2021 07:37
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16641604 kB
MemAvailable 31543852 kB
Buffers 0 kB
Cached 15014592 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.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-11-25 07:37:05
DTS_END                         2021-11-25 07:37:05.7170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42406
TRN_ID                          233451
ATT_ID                          601
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: 8888.86 20428 1066663
customer order: creation 173.14 421 20777
customer order: refuse 14.78 394 1773
order to supplier: creation 46.02 1546 5522
order to supplier: removal 3.33 111747 400
invoice (draft): creation 46.33 3796 5560
invoice (draft): removal 50.59 976 6071
invoice accept: apply 41.00 18255 4920
invoice accept: cancel 45.14 848 5417
customer reserve: creation 3804.73 177 456567
customer reserve: removal 4374.33 66 524920
realization accept: apply 83.89 215 10067
realization accept: cancel 79.08 131 9490
payment from customer: creation 58.93 142 7072
payment from customer: removal 5.64 190 677
payment to supplier: creation 48.25 156 5790
payment to supplier: removal 3.23 72 388
service: total inventory turnovers 3.66 3908 439
service: total monetary turnovers 5.76 569 691
service: aggregate perf. data 0.98 13916 118
service: refresh index statistics 0.03 251042 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-11-2021 07:38:15 to 25-11-2021 07:38:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3934
TEST_TIME 2 2579
TEST_TIME 3 2881
TEST_TIME 4 3398
TEST_TIME 5 3805
TEST_TIME 6 3963
TEST_TIME 7 4198
TEST_TIME 8 4453
TEST_TIME 9 4615
TEST_TIME 10 4989
TEST_TIME 11 5181
TEST_TIME 12 5749
TEST_TIME 13 6299
TEST_TIME 14 6533
TEST_TIME 15 6766
TEST_TIME 16 6714
TEST_TIME 17 7357
TEST_TIME 18 7814
TEST_TIME 19 7850
TEST_TIME 20 8143
TEST_TIME 21 8138
TEST_TIME 22 8423
TEST_TIME 23 8560
TEST_TIME 24 9064
TEST_TIME 25 9183
TEST_TIME 26 9106
TEST_TIME 27 9220
TEST_TIME 28 9122
TEST_TIME 29 9146
TEST_TIME 30 10600
TEST_TIME 31 10483
TEST_TIME 32 10495
TEST_TIME 33 10456
TEST_TIME 34 10308
TEST_TIME 35 10214
TEST_TIME 36 10203
TEST_TIME 37 10061
TEST_TIME 38 9992
TEST_TIME 39 9911
TEST_TIME 40 9917
TEST_TIME 41 9838
TEST_TIME 42 9745
TEST_TIME 43 9736
TEST_TIME 44 9756
TEST_TIME 45 9694
TEST_TIME 46 9784
TEST_TIME 47 9688
TEST_TIME 48 9590
TEST_TIME 49 9491
TEST_TIME 50 9436
TEST_TIME 51 9550
TEST_TIME 52 9516
TEST_TIME 53 9448
TEST_TIME 54 9612
TEST_TIME 55 9570
TEST_TIME 56 9508
TEST_TIME 57 9428
TEST_TIME 58 9391
TEST_TIME 59 9506
TEST_TIME 60 9686
TEST_TIME 61 9654
TEST_TIME 62 9737
TEST_TIME 63 9747
TEST_TIME 64 9771
TEST_TIME 65 9659
TEST_TIME 66 9604
TEST_TIME 67 9543
TEST_TIME 68 9497
TEST_TIME 69 9519
TEST_TIME 70 9451
TEST_TIME 71 9393
TEST_TIME 72 9360
TEST_TIME 73 9297
TEST_TIME 74 9227
TEST_TIME 75 9261
TEST_TIME 76 9267
TEST_TIME 77 9224
TEST_TIME 78 9218
TEST_TIME 79 9207
TEST_TIME 80 9236
TEST_TIME 81 9265
TEST_TIME 82 9282
TEST_TIME 83 9276
TEST_TIME 84 9308
TEST_TIME 85 9280
TEST_TIME 86 9329
TEST_TIME 87 9298
TEST_TIME 88 9222
TEST_TIME 89 9185
TEST_TIME 90 9166
TEST_TIME 91 9161
TEST_TIME 92 9112
TEST_TIME 93 9070
TEST_TIME 94 9051
TEST_TIME 95 9074
TEST_TIME 96 9061
TEST_TIME 97 9052
TEST_TIME 98 9123
TEST_TIME 99 9169
TEST_TIME 100 9165
TEST_TIME 101 9188
TEST_TIME 102 9199
TEST_TIME 103 9225
TEST_TIME 104 9209
TEST_TIME 105 9254
TEST_TIME 106 9237
TEST_TIME 107 9158
TEST_TIME 108 9132
TEST_TIME 109 9099
TEST_TIME 110 9099
TEST_TIME 111 9057
TEST_TIME 112 9035
TEST_TIME 113 9009
TEST_TIME 114 8976
TEST_TIME 115 8991
TEST_TIME 116 8990
TEST_TIME 117 8978
TEST_TIME 118 8967
TEST_TIME 119 8966
TEST_TIME 120 8892

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-11-2021 07:38:18 to 25-11-2021 07:38:22.

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 1489303 1489303 0 0.00 0 53453 7 0 0
doc_list_biud 1489303 1489303 0 0.00 0 55159 30 0 0
sp_add_invoice_to_stock 4997 4920 77 1.54 42 155072 18255 0 77
sp_cancel_adding_invoice 5417 5417 0 0.00 5 63127 848 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 16601 394 0 0
sp_cancel_customer_reserve 524920 524920 0 0.00 1 55160 67 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 39524 191 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 4141 73 0 0
sp_cancel_supplier_invoice 6081 6071 10 0.16 3 47894 977 0 10
sp_cancel_supplier_order 400 400 0 0.00 6 1103124 111747 0 0
sp_cancel_write_off 9490 9490 0 0.00 1 36665 131 0 0
sp_client_order 20777 20777 0 0.00 4 29522 422 0 0
sp_customer_reserve 456567 456567 0 0.00 0 71927 177 0 0
sp_fill_shopping_cart 31859 31859 0 0.00 1 28054 51 0 0
sp_fill_shopping_cart_clo_res 456560 456560 0 0.00 0 19693 17 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 32008 393 0 0
sp_kill_cost_storno 15972 15972 0 0.00 0 39517 72 0 0
sp_kill_qstorno_ret_qs2qd 1065801 1065801 0 0.00 0 55157 37 0 0
sp_kill_qty_storno 563051 563051 0 0.00 0 55159 79 0 0
sp_lock_selected_doc 20751 20751 0 0.00 0 8752 6 0 0
sp_make_cost_storno 27842 27842 0 0.00 0 53232 69 0 0
sp_multiply_rows_for_pdistr 27842 27842 0 0.00 0 39533 36 0 0
sp_multiply_rows_for_qdistr 20777 20777 0 0.00 0 24855 220 0 0
sp_pay_from_customer 7072 7072 0 0.00 4 44527 142 0 0
sp_pay_to_supplier 5790 5790 0 0.00 5 47300 156 0 0
sp_payment_common 12862 12862 0 0.00 1 44637 41 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 16594 359 0 0
sp_qd_handle_on_invoice_upd_sts 10330 10330 0 0.00 0 26622 347 0 0
sp_qd_handle_on_reserve_upd_sts 10067 10067 0 0.00 0 22050 67 0 0
sp_reserve_write_off 10067 10067 0 0.00 7 53514 216 0 0
sp_supplier_invoice 5560 5560 0 0.00 6 99119 3797 0 0
sp_supplier_order 5522 5522 0 0.00 6 38044 1547 0 0
srv_aggregate_perf_data 119 118 1 0.84 2772 72867 13917 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1664 156454 43532 0 0
srv_find_qd_qs_mism 1019217 1019217 0 0.00 0 24993 6 0 0
srv_make_invnt_saldo 439 439 0 0.00 69 123305 3909 0 0
srv_make_money_saldo 691 691 0 0.00 1 15329 570 0 0
srv_recalc_idx_stat 4 4 0 0.00 215984 315428 251042 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4921 4921 0 0.00 0 11516 178 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 4687 77 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 3998 10 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 1943 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 935 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 784 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 1226 23 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 21725 1723 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 3996 7 0 0
t$perf-norm:sp_client_order 20777 20777 0 0.00 0 9508 22 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 1048 4 0 0
t$perf-norm:sp_pay_from_customer 7072 7072 0 0.00 0 5058 8 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 6336 13 0 0
t$perf-norm:sp_reserve_write_off 10067 10067 0 0.00 0 8425 13 0 0
t$perf-norm:sp_supplier_invoice 5560 5560 0 0.00 0 9391 28 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 7261 19 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 334 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6184 118 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 2258 13 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 482 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9980 9826 154 1.54 0 5038 17 0 154
v_all_customers 38180 38180 0 0.00 0 21 1 0 0
v_all_suppliers 30600 30600 0 0.00 0 107 1 0 0
v_all_wares 41554 41554 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2612 10 0 0
v_cancel_client_order 3546 3546 0 0.00 0 2454 6 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1742 7 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 1888 7 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 4130 11 0 0
v_cancel_supplier_invoice 824 804 20 2.42 0 1533 16 0 20
v_cancel_supplier_order 800 800 0 0.00 0 1600 7 0 0
v_cancel_write_off 2020 2020 0 0.00 0 2329 14 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 2596 2 0 0
v_max_id_clo_res 947 947 0 0.00 0 1761 14 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 7764 8 0 0
v_max_non_paid_invoice 1572 1572 0 0.00 0 5525 6 0 0
v_max_non_paid_realizn 3668 3668 0 0.00 0 38986 13 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 24765 15 0 0
v_min_id_clo_res 947 947 0 0.00 0 8328 22 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 5781 8 0 0
v_min_non_paid_invoice 5790 5790 0 0.00 0 39108 32 0 0
v_min_non_paid_realizn 7072 7072 0 0.00 0 19171 25 0 0
v_our_firm 10182 10182 0 0.00 0 14 1 0 0
v_reserve_write_off 20134 20134 0 0.00 0 2454 5 0 0
x_lock_depdocs_on_canc_invoice 11086 11086 0 0.00 0 26008 115 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 21719 438 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 2 35091 1444 0 0
x_make_qstorno_1200_2000 5560 5560 0 0.00 3 95656 3607 0 0
x_make_qstorno_2100_3300 456567 456567 0 0.00 0 71925 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-11-2021 07:38:23 to 25-11-2021 07:38:27.

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:07:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:10 25271 8192000000 9717587344 10014527488 793519120 8.831 100 88 176 0 824559504 8025376 23015008
05:09:38 22500 8192000000 9769861536 10077261824 842810224 9.328 100 85 170 1 874412816 11658576 19944016
05:11:09 14345 8192000000 9805167808 10125303808 877596608 9.676 100 78 156 0 912704912 17298496 17809808
05:12:40 9071 8192000000 9871465776 10203906048 924143568 10.137 100 80 158 1 982754784 41294224 17316992
05:13:51 8503 8192000000 9904540752 10247786496 957714816 10.467 100 83 165 2 1031271904 56561840 16995248
05:15:01 4622 8192000000 9971926352 10325778432 995744064 10.837 100 80 161 2 1082837360 70786016 16307280
05:16:09 4211 8192000000 9989887728 10355445760 1020571264 11.078 100 83 166 2 1107334032 70632400 16130368
05:17:15 3257 8192000000 10019237472 10385309696 1043224640 11.296 100 94 186 2 1133277232 71422720 18629872
05:18:21 1997 8192000000 10041864400 10412007424 1061016016 11.466 100 95 191 1 1147252080 66566608 19669456
05:19:25 12788 8192000000 10023001744 10402082816 1065623712 11.510 100 74 147 2 1136520848 56236864 14660272
05:20:40 4573 8192000000 10032246752 10417946624 1079875776 11.646 100 65 131 1 1142179312 48691024 13612512
05:21:46 1579 8192000000 10032757472 10408030208 1078804320 11.636 100 93 184 0 1129532544 30794640 19933584
05:22:48 5389 8192000000 10004139280 10392293376 1085249056 11.697 100 51 95 2 1105689392 10070496 10369840
05:23:56 21734 8192000000 9994184320 10408992768 1085366592 11.699 100 36 73 1 1120251904 27252848 7632464
05:25:33 6158 8192000000 10000549776 10400395264 1084704224 11.692 100 28 53 1 1110444352 20244752 5495376
05:26:41 5058 8192000000 9989606704 10384486400 1059080416 11.448 97 41 79 1 1084392192 17049424 8262352
05:27:50 8250 8192000000 9999969328 10399035392 1062653952 11.482 98 39 76 1 1092367984 21599024 8115008
05:29:03 15426 8192000000 9985155248 10394099712 1071360000 11.565 99 22 44 1 1113799744 37751232 4688512
05:30:31 7548 8192000000 9966549232 10358616064 1034606528 11.213 98 27 54 0 1066752720 26417952 5728240
05:31:40 8559 8192000000 9938708192 10315554816 989234176 10.774 96 49 97 4 1016286768 15606176 11446416
05:32:48 30244 8192000000 9925966368 10293067776 992180128 10.803 97 55 110 0 1014634784 10095776 12358880
05:34:19 7867 8192000000 9960753328 10357698560 1041560080 11.280 100 26 52 0 1062815392 15913376 5341936
05:35:30 4753 8192000000 9974721440 10365403136 1054227536 11.401 100 48 102 1 1094114480 29578944 10308000
05:36:38 12690 8192000000 9987705616 10397118464 1066268496 11.516 100 42 82 1 1094207104 19059536 8879072
05:38:01 156453 8192000000 10038892496 10451677184 1094643280 11.787 100 33 65 1 1138937520 35524816 8769424
05:41:48 10860 8192000000 10080091216 10475782144 1122873312 12.054 100 49 97 4 1188320912 55059440 10388160
05:43:01 5941 8192000000 10138069776 10540421120 1136580928 12.183 100 65 129 0 1247281024 96803184 13896912
05:44:21 20073 8192000000 10158886832 10571612160 1143580192 12.249 100 37 74 0 1285477104 132847248 9049664
05:45:52 6977 8192000000 10193706144 10600386560 1156806400 12.373 100 63 123 1 1305888336 134950000 14131936
05:47:03 13688 8192000000 10231216208 10647334912 1160694096 12.410 100 52 104 1 1340023040 167737168 11591776
05:48:36 4298 8192000000 10272190144 10682081280 1174947296 12.543 100 69 138 0 1378816464 188284864 15584304
05:49:44 1661 8192000000 10271107360 10677866496 1176130128 12.554 100 83 166 1 1375546800 180666720 18749952
05:50:48 2080 8192000000 10321848480 10725830656 1182410480 12.613 100 72 142 0 1421016560 222708608 15897472
05:51:56 3286 8192000000 10307667936 10729259008 1168016528 12.478 100 63 125 2 1412964224 230422416 14525280
05:53:05 2690 8192000000 10321100672 10731663360 1178450224 12.576 100 74 148 1 1423009360 226295072 18264064
05:54:13 1705 8192000000 10334591024 10738647040 1165607328 12.456 99 78 157 0 1417478784 234346912 17524544
05:55:18 2255 8192000000 10342956048 10747498496 1167570000 12.474 100 86 173 3 1435509600 247373696 20565904
05:56:23 6762 8192000000 10306805792 10736996352 1162828208 12.430 100 40 80 0 1417199664 244539264 9832192
05:57:34 10654 8192000000 10291360512 10722545664 1150122336 12.311 100 46 92 0 1402041664 240529984 11389344
05:58:49 1756 8192000000 10293691184 10701418496 1135710768 12.175 99 86 174 0 1381579232 225552992 20315472
05:59:54 20064 8192000000 10246501312 10665934848 1098845504 11.827 98 30 60 1 1343106176 236316592 7944080
06:01:16 27840 8192000000 10285657472 10719502336 1106657248 11.901 99 31 63 1 1356849680 241782912 8409520
06:02:47 14841 8192000000 10273674720 10691006464 1116858336 11.997 99 44 88 1 1359212560 230784448 11569776
06:04:04 27474 8192000000 10313884352 10751774720 1132371856 12.144 100 24 48 0 1415655632 277478032 5805744
06:05:34 24211 8192000000 10352405552 10814111744 1129845584 12.120 100 17 34 0 1435975056 301445184 4684288
06:07:48 41193 8192000000 10058259744 10488930304 1109920288 11.932 99 40 76 1 1131584192 14142784 7521120
06:09:32 85904 8192000000 10063075856 10495234048 1097814208 11.817 97 15 27 4 1139054608 38744640 2495760
06:12:16 49128 8192000000 10124396944 10574757888 1129587712 12.117 100 36 73 1 1202855552 65803936 7463904
06:14:06 59727 8192000000 10076782624 10512556032 1105798816 11.893 98 43 85 2 1182536928 67044640 9693472
06:16:12 78955 8192000000 10096380480 10559361024 1117586368 12.004 99 31 60 1 1184201968 60366544 6249056
06:18:59 48588 8192000000 10134652128 10578014208 1145614640 12.268 100 17 34 0 1226133088 76535936 3982512
06:20:48 25636 8192000000 10134710880 10584002560 1140362544 12.219 100 51 98 1 1229505136 78196192 10946400
06:22:17 53673 8192000000 10118446576 10589147136 1126027904 12.084 99 10 20 0 1207388544 79234160 2126480
06:25:20 54520 8192000000 10151673536 10598137856 1154241184 12.349 100 29 58 0 1245006752 84492288 6273280
06:27:23 59928 8192000000 10132706320 10606100480 1139104736 12.207 99 29 58 0 1293907072 147777616 7024720
06:29:28 43679 8192000000 10092070304 10542800896 1148236144 12.293 100 16 32 0 1203327536 51863360 3228032
06:31:22 51096 8192000000 10092992176 10633359360 1154176608 12.349 100 9 18 0 1213772512 57800192 1795712
06:33:13 43806 8192000000 10096454832 10546118656 1120452848 12.031 97 29 56 0 1155044032 28722704 5868480
06:35:07 29968 8192000000 10191710720 10630303744 1157921936 12.384 100 26 52 0 1271048384 107063408 6063040
06:37:01 63763 8192000000 10074468944 10582745088 1107926048 11.913 97 9 18 1 1188785520 79281728 1577744
06:39:10 69524 8192000000 10083856592 10524151808 1103620656 11.872 97 20 40 0 1162330000 54675824 4033520
06:41:20 66038 8192000000 10089396992 10553929728 1113811904 11.968 98 20 40 0 1149988576 32473456 3703216
06:44:11 83651 8192000000 10070742160 10543038464 1107180064 11.906 97 14 28 0 1142306608 31965024 3161520
06:46:36 75352 8192000000 10069375264 10541088768 1129245888 12.114 99 12 25 1 1158236960 26423824 2567248
06:49:06 49337 8192000000 10091078336 10571517952 1132922336 12.149 99 13 26 0 1210406800 74621104 2863360
06:51:13 84911 8192000000 10096711024 10564218880 1071623440 11.568 94 19 38 0 1149109328 73340976 4144912
06:53:49 28356 8192000000 10019694672 10452013056 1050585936 11.366 94 25 50 2 1089155296 33441536 5127824
06:55:37 41902 8192000000 10053185968 10492710912 1100617312 11.843 99 29 58 0 1141311296 34612800 6081184
06:57:24 51239 8192000000 10077355440 10519556096 1081840032 11.665 97 21 42 1 1140862864 54145216 4877616
06:59:34 49364 8192000000 10066589392 10487279616 1066726368 11.521 96 34 68 2 1099511360 25534224 7250768
07:01:25 74359 8192000000 10072241328 10507993088 1109669264 11.929 99 27 54 0 1160249440 44743280 5836896
07:03:43 133525 8192000000 10091398976 10528808960 1093967600 11.780 97 34 68 1 1152581648 51453568 7160480
07:07:09 19082 8192000000 10050081504 10476597248 1115305376 11.983 99 45 90 0 1151015856 26420384 9290096
07:08:33 48589 8192000000 10066390160 10512613376 1133916848 12.158 100 7 14 0 1167134624 32045072 1172704
07:10:37 59823 8192000000 10104169280 10576556032 1146316448 12.275 100 14 28 0 1215540448 66264896 2959104
07:12:56 47481 8192000000 10112133632 10572886016 1152430544 12.332 100 26 53 0 1224859808 66347872 6081392
07:14:51 36458 8192000000 10137415776 10630352896 1138942752 12.206 98 21 42 0 1235277920 91362416 4972752
07:17:03 54303 8192000000 10115556704 10564231168 1127502832 12.098 98 27 54 1 1190340800 57193408 5644560
07:19:03 67786 8192000000 10109486752 10547953664 1119658816 12.024 97 23 44 0 1162456176 37889200 4908160
07:21:25 24698 8192000000 10090119824 10519511040 1136925536 12.187 99 31 62 1 1166943632 24105168 5912928
07:23:05 78658 8192000000 10047851504 10483298304 1078728560 11.635 94 12 24 0 1108134240 26875840 2529840
07:25:29 47705 8192000000 10056309952 10507747328 1114138272 11.972 98 41 82 2 1155801360 33526864 8136224
07:27:20 79123 8192000000 10047553856 10511040512 1112195584 11.953 98 9 18 0 1153101104 38889312 2016208
07:30:07 46146 8192000000 10062183328 10507665408 1121604496 12.042 100 27 54 0 1186162224 58253808 6303920
07:31:55 85712 8192000000 10080390848 10563067904 1122895632 12.054 99 8 16 0 1186216112 61316544 2003936
07:34:30 24822 8192000000 10086083824 10524819456 1132351760 12.144 100 26 50 1 1166228832 28818448 5058624
07:35:56 59376 8192000000 10114806416 10569691136 1074156368 11.592 95 13 26 0 1162123760 84977440 2989952

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-11-2021 07:38:28 to 25-11-2021 07:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 77 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-11-2021 07:38:44 to 25-11-2021 07:38:46.

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          229744
MON$OLDEST_ACTIVE               233674
MON$OLDEST_SNAPSHOT             233674
MON$NEXT_TRANSACTION            233674
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                       2046752
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
--- end of diff output ---

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

25.11.21 07:38:47. 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-11-25 07:38:49 No severe PSQL-related problems occured
25.11.21 07:38:50. End of report.