Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.09.2021 16:43
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 16111420 kB
MemAvailable 30853200 kB
Buffers 0 kB
Cached 15573588 kB
SwapCached 388 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-11 16:42:48
DTS_END                         2021-09-11 16:42:48.5530
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(57:17)
IP                              ::1/34470
TRN_ID                          229502
ATT_ID                          691
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: 9061.37 24522 1087364
customer order: creation 170.53 368 20464
customer order: refuse 14.77 364 1772
order to supplier: creation 45.19 1382 5423
order to supplier: removal 3.43 105746 411
invoice (draft): creation 46.03 3687 5524
invoice (draft): removal 50.43 848 6052
invoice accept: apply 41.60 18438 4992
invoice accept: cancel 45.48 823 5457
customer reserve: creation 3933.68 179 472042
customer reserve: removal 4415.02 64 529803
realization accept: apply 82.05 184 9846
realization accept: cancel 83.06 118 9967
payment from customer: creation 58.18 124 6982
payment from customer: removal 6.02 73 722
payment to supplier: creation 50.13 131 6016
payment to supplier: removal 3.44 77 413
service: total inventory turnovers 4.97 1188 596
service: total monetary turnovers 6.46 24 775
service: aggregate perf. data 0.86 17748 103
service: refresh index statistics 0.03 338878 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-09-2021 16:44:10 to 11-09-2021 16:44:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2001
TEST_TIME 2 2454
TEST_TIME 3 3708
TEST_TIME 4 4118
TEST_TIME 5 4057
TEST_TIME 6 4036
TEST_TIME 7 4535
TEST_TIME 8 4286
TEST_TIME 9 4305
TEST_TIME 10 4392
TEST_TIME 11 4289
TEST_TIME 12 4539
TEST_TIME 13 4819
TEST_TIME 14 4879
TEST_TIME 15 5133
TEST_TIME 16 5297
TEST_TIME 17 5721
TEST_TIME 18 5797
TEST_TIME 19 6105
TEST_TIME 20 6644
TEST_TIME 21 6976
TEST_TIME 22 7211
TEST_TIME 23 7777
TEST_TIME 24 7873
TEST_TIME 25 7814
TEST_TIME 26 8281
TEST_TIME 27 8318
TEST_TIME 28 8775
TEST_TIME 29 9617
TEST_TIME 30 9911
TEST_TIME 31 9774
TEST_TIME 32 9713
TEST_TIME 33 9737
TEST_TIME 34 9854
TEST_TIME 35 9714
TEST_TIME 36 9693
TEST_TIME 37 9562
TEST_TIME 38 9413
TEST_TIME 39 9540
TEST_TIME 40 9522
TEST_TIME 41 9514
TEST_TIME 42 9549
TEST_TIME 43 9530
TEST_TIME 44 9458
TEST_TIME 45 9534
TEST_TIME 46 9509
TEST_TIME 47 9506
TEST_TIME 48 9431
TEST_TIME 49 9442
TEST_TIME 50 9306
TEST_TIME 51 9225
TEST_TIME 52 9207
TEST_TIME 53 9180
TEST_TIME 54 9216
TEST_TIME 55 9112
TEST_TIME 56 9062
TEST_TIME 57 9029
TEST_TIME 58 8970
TEST_TIME 59 9067
TEST_TIME 60 9077
TEST_TIME 61 9046
TEST_TIME 62 9077
TEST_TIME 63 8971
TEST_TIME 64 8895
TEST_TIME 65 9063
TEST_TIME 66 9022
TEST_TIME 67 9179
TEST_TIME 68 9233
TEST_TIME 69 9186
TEST_TIME 70 9203
TEST_TIME 71 9291
TEST_TIME 72 9268
TEST_TIME 73 9378
TEST_TIME 74 9378
TEST_TIME 75 9364
TEST_TIME 76 9335
TEST_TIME 77 9382
TEST_TIME 78 9372
TEST_TIME 79 9411
TEST_TIME 80 9296
TEST_TIME 81 9350
TEST_TIME 82 9277
TEST_TIME 83 9236
TEST_TIME 84 9255
TEST_TIME 85 9247
TEST_TIME 86 9199
TEST_TIME 87 9221
TEST_TIME 88 9237
TEST_TIME 89 9169
TEST_TIME 90 9140
TEST_TIME 91 9119
TEST_TIME 92 9072
TEST_TIME 93 9066
TEST_TIME 94 9074
TEST_TIME 95 9060
TEST_TIME 96 9071
TEST_TIME 97 9134
TEST_TIME 98 9130
TEST_TIME 99 9175
TEST_TIME 100 9204
TEST_TIME 101 9228
TEST_TIME 102 9191
TEST_TIME 103 9255
TEST_TIME 104 9280
TEST_TIME 105 9308
TEST_TIME 106 9278
TEST_TIME 107 9297
TEST_TIME 108 9284
TEST_TIME 109 9211
TEST_TIME 110 9195
TEST_TIME 111 9182
TEST_TIME 112 9154
TEST_TIME 113 9146
TEST_TIME 114 9113
TEST_TIME 115 9080
TEST_TIME 116 9098
TEST_TIME 117 9104
TEST_TIME 118 9086
TEST_TIME 119 9080
TEST_TIME 120 9062

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-09-2021 16:44:13 to 11-09-2021 16:44:17.

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 1521412 1521412 0 0.00 0 29570 7 0 0
doc_list_biud 1521412 1521412 0 0.00 0 81362 28 0 0
sp_add_invoice_to_stock 5154 4992 162 3.14 36 181398 18439 0 162
sp_cancel_adding_invoice 5457 5457 0 0.00 4 69298 824 0 0
sp_cancel_client_order 1772 1772 0 0.00 4 10624 365 0 0
sp_cancel_customer_reserve 529803 529803 0 0.00 1 33901 65 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 2468 74 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 2 1955 78 0 0
sp_cancel_supplier_invoice 6060 6052 8 0.13 1 81374 848 0 8
sp_cancel_supplier_order 411 411 0 0.00 6 1306758 105746 0 0
sp_cancel_write_off 9967 9967 0 0.00 1 30771 118 0 0
sp_client_order 20464 20464 0 0.00 4 20713 369 0 0
sp_customer_reserve 472042 472042 0 0.00 0 79558 179 0 0
sp_fill_shopping_cart 31408 31408 0 0.00 1 19199 38 0 0
sp_fill_shopping_cart_clo_res 472040 472040 0 0.00 0 11462 13 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 24091 305 0 0
sp_kill_cost_storno 16559 16559 0 0.00 0 9303 67 0 0
sp_kill_qstorno_ret_qs2qd 1076036 1076036 0 0.00 0 81356 35 0 0
sp_kill_qty_storno 568298 568298 0 0.00 0 81360 75 0 0
sp_lock_selected_doc 20615 20615 0 0.00 0 9354 3 0 0
sp_make_cost_storno 27834 27834 0 0.00 0 21527 57 0 0
sp_multiply_rows_for_pdistr 27834 27834 0 0.00 0 6593 32 0 0
sp_multiply_rows_for_qdistr 20464 20464 0 0.00 0 19410 162 0 0
sp_pay_from_customer 6982 6982 0 0.00 4 21533 124 0 0
sp_pay_to_supplier 6016 6016 0 0.00 5 15514 132 0 0
sp_payment_common 12998 12998 0 0.00 1 11520 31 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 10616 343 0 0
sp_qd_handle_on_invoice_upd_sts 10447 10447 0 0.00 0 14949 350 0 0
sp_qd_handle_on_reserve_upd_sts 9846 9846 0 0.00 0 19832 57 0 0
sp_reserve_write_off 9846 9846 0 0.00 7 19863 184 0 0
sp_supplier_invoice 5524 5524 0 0.00 7 87180 3688 0 0
sp_supplier_order 5423 5423 0 0.00 5 36533 1382 0 0
srv_aggregate_perf_data 103 103 0 0.00 2380 131133 17748 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1986 208854 47125 0 0
srv_find_qd_qs_mism 1037791 1037791 0 0.00 0 29567 6 0 0
srv_make_invnt_saldo 596 596 0 0.00 22 51014 1189 0 0
srv_make_money_saldo 775 775 0 0.00 0 699 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 144169 465675 338878 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4992 4992 0 0.00 0 24254 194 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 10474 114 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 5774 15 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 4882 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1737 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1968 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1867 24 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 21852 1634 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 1872 7 0 0
t$perf-norm:sp_client_order 20464 20464 0 0.00 0 14178 19 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 1946 6 0 0
t$perf-norm:sp_pay_from_customer 6982 6982 0 0.00 0 9600 10 0 0
t$perf-norm:sp_pay_to_supplier 6016 6016 0 0.00 0 21030 13 0 0
t$perf-norm:sp_reserve_write_off 9846 9846 0 0.00 0 22982 13 0 0
t$perf-norm:sp_supplier_invoice 5524 5524 0 0.00 0 4095 17 0 0
t$perf-norm:sp_supplier_order 5423 5423 0 0.00 0 3654 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 449 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 1142 27 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 2802 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 131 35 0 0
v_add_invoice_to_stock 10304 9980 324 3.14 0 16490 22 0 324
v_all_customers 37478 37478 0 0.00 0 71 1 0 0
v_all_suppliers 30788 30788 0 0.00 0 48 1 0 0
v_all_wares 40928 40928 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1750 9 0 0
v_cancel_client_order 3544 3544 0 0.00 0 4245 5 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 899 6 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 9282 12 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 1939 7 0 0
v_cancel_supplier_invoice 872 856 16 1.83 0 2374 17 0 16
v_cancel_supplier_order 822 822 0 0.00 0 1413 5 0 0
v_cancel_write_off 1906 1906 0 0.00 0 3134 18 0 0
v_max_id_clo_ord 5423 5423 0 0.00 0 148 1 0 0
v_max_id_clo_res 973 973 0 0.00 0 2638 21 0 0
v_max_id_ord_sup 5521 5521 0 0.00 0 1815 2 0 0
v_max_non_paid_invoice 1566 1566 0 0.00 0 892 3 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 3145 6 0 0
v_min_id_clo_ord 5423 5423 0 0.00 0 3431 6 0 0
v_min_id_clo_res 973 973 0 0.00 0 5002 12 0 0
v_min_id_ord_sup 5521 5521 0 0.00 0 3308 6 0 0
v_min_non_paid_invoice 6016 6016 0 0.00 0 11515 20 0 0
v_min_non_paid_realizn 6982 6982 0 0.00 0 9012 21 0 0
v_our_firm 10206 10206 0 0.00 0 48 1 0 0
v_reserve_write_off 19692 19692 0 0.00 0 19426 9 0 0
x_lock_depdocs_on_canc_invoice 11081 11081 0 0.00 0 14105 117 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 8894 349 0 0
x_make_qstorno_1000_1200 5423 5423 0 0.00 2 36510 1304 0 0
x_make_qstorno_1200_2000 5524 5524 0 0.00 4 87169 3552 0 0
x_make_qstorno_2100_3300 472042 472042 0 0.00 0 79557 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-09-2021 16:44:18 to 11-09-2021 16:44:22.

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
14:12:52 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:13:54 22268 8192000000 10326593440 10643091456 1383462656 14.447 100 84 168 1 1416028864 5142048 27424160
14:15:23 20578 8192000000 10428110992 10753880064 1483365312 15.331 100 93 186 1 1520730576 7600512 29764752
14:16:56 18787 8192000000 10490143872 10829778944 1533397008 15.766 100 78 156 0 1566331728 8926016 24008704
14:18:16 18240 8192000000 10589832320 10944770048 1631192448 16.605 100 81 162 1 1665553728 10325360 24035920
14:19:48 8570 8192000000 10632878752 11007438848 1701171392 17.195 100 72 144 0 1733046144 11250336 20624416
14:21:03 4998 8192000000 10698267472 11077648384 1760110928 17.685 100 76 151 2 1795201616 13913456 21177232
14:22:10 6410 8192000000 10720910192 11115167744 1795894080 17.980 100 57 113 0 1821475552 9707136 15874336
14:23:20 1357 8192000000 10791753872 11176562688 1846673488 18.395 100 91 183 2 1887931952 16483280 24775184
14:24:24 1410 8192000000 10812910160 11201912832 1867090112 18.561 100 93 183 2 1907715792 14299808 26325872
14:25:29 1753 8192000000 10809887888 11212726272 1877495856 18.645 100 66 132 1 1910197696 14144544 18557296
14:26:31 8579 8192000000 10808904336 11220963328 1886334848 18.716 100 30 60 2 1901220208 6458000 8427360
14:27:41 24472 8192000000 10828613408 11239215104 1895515936 18.790 100 47 95 1 1915874768 6348912 14009920
14:29:06 5848 8192000000 10812604304 11245441024 1903432544 18.854 100 41 81 3 1922052960 6643504 11976912
14:30:13 8097 8192000000 10809005232 11249377280 1904233056 18.860 100 20 40 0 1916116400 5834336 6049008
14:31:23 5507 8192000000 10825586496 11258884096 1912142160 18.924 100 23 46 0 1925146704 5911632 7092912
14:32:29 27138 8192000000 10838766480 11266588672 1917506544 18.967 100 44 88 0 1937629808 8235152 11888112
14:33:58 9974 8192000000 10809470352 11231121408 1875367136 18.628 99 22 44 0 1891386224 9759872 6259216
14:35:08 10603 8192000000 10767420400 11191644160 1795767040 17.979 96 23 46 2 1808328800 6104688 6457072
14:36:20 12650 8192000000 10735215584 11154116608 1742301312 17.538 95 14 28 1 1751488496 5015648 4171536
14:37:36 14293 8192000000 10707888992 11134357504 1759533872 17.681 97 24 48 1 1772859168 6554560 6770736
14:38:59 5617 8192000000 10717032512 11140038656 1805035456 18.055 100 38 76 1 1822128240 5783488 11309296
14:40:06 6387 8192000000 10750634128 11167076352 1817170608 18.155 99 28 56 1 1831584768 5923968 8490192
14:41:13 48970 8192000000 10783346272 11202084864 1856884432 18.478 100 20 41 0 1867954176 5602080 5467664
14:43:06 53454 8192000000 10813874560 11243393024 1900597760 18.831 100 42 82 4 1919237552 6740448 11899344
14:45:08 8019 8192000000 10871391120 11289313280 1932541008 19.087 100 62 123 0 1964482160 14514736 17426416
14:46:33 36421 8192000000 10902229744 11318386688 1952797216 19.249 100 46 92 1 1982963760 17192656 12973888
14:48:14 25185 8192000000 10908557712 11342532608 1968941168 19.377 100 36 72 2 1997818288 18081728 10795392
14:49:47 26886 8192000000 10919007040 11351576576 1974657984 19.422 100 44 88 1 2010918736 22460560 13800192
14:51:26 11474 8192000000 10938731840 11370573824 1990675280 19.549 100 53 106 0 2028086656 21768944 15642432
14:52:42 12230 8192000000 10953634016 11391131648 2004469376 19.658 100 47 94 0 2043760128 24716848 14573904
14:53:59 8765 8192000000 10975799952 11408134144 2022361312 19.799 100 58 114 2 2067495504 27502656 17631536
14:55:17 3328 8192000000 10999799408 11430440960 2036257616 19.908 100 74 146 2 2092493312 33130272 23105424
14:56:25 3304 8192000000 10986364144 11417108480 2019061536 19.773 100 67 133 0 2077363392 37736720 20565136
14:57:34 2999 8192000000 10991561776 11416428544 2015987312 19.749 100 64 128 1 2074276288 38417856 19871120
14:58:46 6645 8192000000 11015231792 11438690304 2029538176 19.855 100 69 137 2 2098468512 46111744 22818592
15:00:01 7874 8192000000 11023132912 11455377408 2035972624 19.905 100 67 133 0 2106126848 49683232 20470992
15:01:14 3142 8192000000 11029493088 11457400832 2034227008 19.892 100 75 150 3 2112055008 53439664 24388336
15:02:22 1982 8192000000 11017415856 11438047232 2024668832 19.817 100 80 159 1 2094563536 44725680 25169024
15:03:31 2163 8192000000 11002198576 11417796608 1994205152 19.577 99 93 186 1 2070240512 47369376 28665984
15:04:38 2305 8192000000 10980596048 11399614464 1983636688 19.493 100 86 172 2 2066400416 55826128 26937600
15:05:45 24810 8192000000 10933273280 11379580928 1922320112 19.005 97 32 64 3 1975978464 42572272 11086080
15:07:20 10053 8192000000 10933530752 11380977664 1961030144 19.314 100 36 72 0 2024525984 51437008 12058832
15:08:41 26412 8192000000 10890515376 11360923648 1930493568 19.071 100 24 48 1 1988833104 49868704 8470832
15:10:18 9979 8192000000 10909095088 11359379456 1927735520 19.049 100 27 54 0 1991186624 53326048 10125056
15:11:32 77013 8192000000 10886324512 11339661312 1858756960 18.493 95 13 26 1 1895915504 33378992 3779552
15:13:54 41114 8192000000 10906037808 11347787776 1953569200 19.255 100 36 71 3 1973507248 9267264 10670784
15:15:37 36403 8192000000 10891714112 11358982144 1961247760 19.316 100 22 42 1 1982148288 14368384 6532144
15:17:52 125758 8192000000 10883232960 11345936384 1881042416 18.674 96 9 18 0 1895349184 12029312 2277456
15:21:03 93843 8192000000 10877260416 11338530816 1881819232 18.680 96 13 26 0 1895116576 9420528 3876816
15:23:37 76726 8192000000 10863304624 11325091840 1878985056 18.657 97 17 34 1 1902031040 18225488 4820496
15:26:04 64946 8192000000 10859406032 11326517248 1901896464 18.842 98 12 25 1 1915984624 10599184 3488976
15:28:12 114804 8192000000 10863180464 11326148608 1886344416 18.716 97 11 22 0 1899517792 10096800 3076576
15:31:10 208854 8192000000 10916161728 11362385920 1926919376 19.042 98 27 54 0 1948598784 14549792 7129616
15:35:40 42968 8192000000 10887066368 11366768640 1960137984 19.307 99 49 98 1 1987372784 12754896 14479904
15:37:42 5548 8192000000 10918866208 11382341632 1978902064 19.456 100 27 54 0 2001667104 15337856 7427184
15:38:50 88547 8192000000 10951739232 11402260480 1978914144 19.456 99 23 46 1 1996894224 11729312 6250768
15:41:27 68383 8192000000 10935023440 11416653824 1997976816 19.607 100 15 30 0 2020079872 17569920 4533136
15:43:36 28752 8192000000 10927950704 11401474048 2000014288 19.623 100 9 18 0 2015533648 13157104 2362256
15:45:22 135329 8192000000 10933940240 11426443264 1989351904 19.539 99 15 30 1 2010434528 17255440 3827184
15:48:38 66590 8192000000 10915539728 11399897088 1948745712 19.216 97 15 30 0 1971716480 18752960 4217808
15:50:52 57281 8192000000 10917630912 11373178880 1946700224 19.200 98 34 68 0 1966054976 8839792 10514960
15:52:54 85721 8192000000 10924445456 11363971072 1881699280 18.679 95 25 50 3 1898208016 9524640 6984096
15:55:22 41539 8192000000 10879662384 11355529216 1942575216 19.167 100 22 44 0 1959971200 11459776 5936208
15:57:04 66373 8192000000 10888479392 11335823360 1845872688 18.389 94 22 44 0 1860209360 8496688 5839984
15:59:11 30943 8192000000 10908939744 11359481856 1922091120 19.004 98 28 56 1 1943804736 13939600 7774016
16:00:43 151651 8192000000 10885315872 11348140032 1874181072 18.618 95 19 36 1 1891597088 12006384 5409632
16:04:16 62454 8192000000 10874402976 11339313152 1931238672 19.077 99 34 68 2 1949634400 8382448 10013280
16:06:36 54214 8192000000 10870705264 11351273472 1909049712 18.899 98 16 32 1 1924747216 11289920 4407584
16:08:30 69099 8192000000 10858975184 11327787008 1901295056 18.837 98 13 26 0 1915139104 10125712 3718336
16:10:42 41030 8192000000 10835099440 11313692672 1916181840 18.956 99 4 8 0 1926457024 9531632 743552
16:12:29 43309 8192000000 10834072432 11300093952 1875527408 18.629 97 22 43 0 1888335664 6670112 6138144
16:14:15 69001 8192000000 10844868528 11331215360 1908299568 18.893 99 9 18 0 1919946704 9214256 2432880
16:16:40 40584 8192000000 10879600336 11350732800 1933928528 19.098 99 11 22 1 1945022640 8205488 2888624
16:18:24 22463 8192000000 10898479024 11351744512 1941530784 19.159 99 53 106 1 1965449168 8655312 15263072
16:19:50 33037 8192000000 10888495280 11349929984 1921632208 19.000 98 21 42 0 1937204288 9832416 5739664
16:21:24 45992 8192000000 10897966848 11354193920 1940807056 19.153 99 28 56 0 1959453536 10910240 7736240
16:23:11 27078 8192000000 10882950720 11348439040 1934996512 19.107 99 29 58 2 1953864608 10566064 8302032
16:24:43 25523 8192000000 10929265136 11378487296 1958775920 19.296 99 50 100 1 1983544784 10624752 14144112
16:26:12 100598 8192000000 10899947744 11383623680 1922423808 19.006 97 10 20 0 1938766288 13778016 2564464
16:28:54 15818 8192000000 10926299440 11392950272 1989143328 19.537 100 29 58 3 2006655440 8834672 8677440
16:30:10 67632 8192000000 10881278480 11365412864 1916828816 18.961 97 9 18 1 1931820944 12731296 2260832
16:32:32 61204 8192000000 10910251520 11384709120 1975889744 19.432 100 10 20 0 1990528384 11971504 2667136
16:34:33 52107 8192000000 10889917600 11375464448 1959065296 19.299 99 9 16 1 1971099280 9680592 2353392
16:36:30 67092 8192000000 10897304256 11379261440 1943914672 19.178 98 15 29 3 1962182576 13929728 4338176
16:38:41 63874 8192000000 10866551520 11354824704 1899986080 18.826 97 13 26 0 1914539760 10806288 3747392
16:40:50 47745 8192000000 10881309728 11359084544 1929050464 19.059 98 27 54 1 1950927376 14324448 7552464
16:42:47 9559 8192000000 9092734624 9304612864 253352784 2.999 14 3 6 0 265731904 11473440 905680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-09-2021 16:44:24 to 11-09-2021 16:44:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 162 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-09-2021 16:44:39 to 11-09-2021 16:44:41.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232027
MON$OLDEST_ACTIVE               232028
MON$OLDEST_SNAPSHOT             232028
MON$NEXT_TRANSACTION            232028
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-03 16:41:55.3470
MON$PAGES                       2032032
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Sep 11 16:43:18 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

11.09.21 16:44:42. There are 3 logs in /var/tmp/logs-oltp30 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-09-11 16:44:45 No severe PSQL-related problems occured
11.09.21 16:44:46. End of report.