Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.2021 13: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 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 16154132 kB
MemAvailable 31028888 kB
Buffers 0 kB
Cached 15485404 kB
SwapCached 2572 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-03 13:37:04
DTS_END                         2021-08-03 13:37:04.5120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43370
TRN_ID                          241917
ATT_ID                          707
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: 9170.21 21534 1100425
customer order: creation 176.36 406 21163
customer order: refuse 14.85 332 1782
order to supplier: creation 47.03 1377 5643
order to supplier: removal 3.52 95790 422
invoice (draft): creation 47.73 3600 5728
invoice (draft): removal 51.42 886 6170
invoice accept: apply 42.53 18998 5103
invoice accept: cancel 45.55 820 5466
customer reserve: creation 3956.29 186 474755
customer reserve: removal 4490.85 58 538902
realization accept: apply 86.16 184 10339
realization accept: cancel 76.95 110 9234
payment from customer: creation 60.01 134 7201
payment from customer: removal 5.88 70 705
payment to supplier: creation 49.97 139 5996
payment to supplier: removal 3.62 92 434
service: total inventory turnovers 3.93 3189 471
service: total monetary turnovers 6.68 374 801
service: aggregate perf. data 0.88 14275 105
service: refresh index statistics 0.04 289658 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2021 13:38:03 to 03-08-2021 13:38:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1947
TEST_TIME 2 3993
TEST_TIME 3 4156
TEST_TIME 4 4511
TEST_TIME 5 5375
TEST_TIME 6 5428
TEST_TIME 7 5419
TEST_TIME 8 5479
TEST_TIME 9 5933
TEST_TIME 10 6171
TEST_TIME 11 6078
TEST_TIME 12 6376
TEST_TIME 13 6733
TEST_TIME 14 7100
TEST_TIME 15 7402
TEST_TIME 16 7556
TEST_TIME 17 8349
TEST_TIME 18 8636
TEST_TIME 19 9011
TEST_TIME 20 8764
TEST_TIME 21 9178
TEST_TIME 22 9401
TEST_TIME 23 9841
TEST_TIME 24 10335
TEST_TIME 25 10763
TEST_TIME 26 10499
TEST_TIME 27 10546
TEST_TIME 28 10631
TEST_TIME 29 10674
TEST_TIME 30 10488
TEST_TIME 31 10538
TEST_TIME 32 10468
TEST_TIME 33 10433
TEST_TIME 34 10369
TEST_TIME 35 10322
TEST_TIME 36 10204
TEST_TIME 37 10197
TEST_TIME 38 10313
TEST_TIME 39 10227
TEST_TIME 40 10286
TEST_TIME 41 10189
TEST_TIME 42 10134
TEST_TIME 43 9941
TEST_TIME 44 9824
TEST_TIME 45 9883
TEST_TIME 46 9765
TEST_TIME 47 9753
TEST_TIME 48 9675
TEST_TIME 49 9634
TEST_TIME 50 9504
TEST_TIME 51 9627
TEST_TIME 52 9524
TEST_TIME 53 9532
TEST_TIME 54 9598
TEST_TIME 55 9586
TEST_TIME 56 9502
TEST_TIME 57 9530
TEST_TIME 58 9821
TEST_TIME 59 9818
TEST_TIME 60 9831
TEST_TIME 61 9737
TEST_TIME 62 9865
TEST_TIME 63 9864
TEST_TIME 64 9845
TEST_TIME 65 9757
TEST_TIME 66 9706
TEST_TIME 67 9674
TEST_TIME 68 9641
TEST_TIME 69 9562
TEST_TIME 70 9506
TEST_TIME 71 9446
TEST_TIME 72 9401
TEST_TIME 73 9497
TEST_TIME 74 9491
TEST_TIME 75 9464
TEST_TIME 76 9462
TEST_TIME 77 9457
TEST_TIME 78 9477
TEST_TIME 79 9548
TEST_TIME 80 9510
TEST_TIME 81 9604
TEST_TIME 82 9608
TEST_TIME 83 9595
TEST_TIME 84 9650
TEST_TIME 85 9648
TEST_TIME 86 9644
TEST_TIME 87 9554
TEST_TIME 88 9546
TEST_TIME 89 9494
TEST_TIME 90 9447
TEST_TIME 91 9401
TEST_TIME 92 9388
TEST_TIME 93 9355
TEST_TIME 94 9334
TEST_TIME 95 9332
TEST_TIME 96 9364
TEST_TIME 97 9348
TEST_TIME 98 9315
TEST_TIME 99 9286
TEST_TIME 100 9333
TEST_TIME 101 9452
TEST_TIME 102 9421
TEST_TIME 103 9412
TEST_TIME 104 9438
TEST_TIME 105 9468
TEST_TIME 106 9399
TEST_TIME 107 9359
TEST_TIME 108 9317
TEST_TIME 109 9276
TEST_TIME 110 9252
TEST_TIME 111 9242
TEST_TIME 112 9252
TEST_TIME 113 9239
TEST_TIME 114 9233
TEST_TIME 115 9248
TEST_TIME 116 9259
TEST_TIME 117 9235
TEST_TIME 118 9287
TEST_TIME 119 9243
TEST_TIME 120 9172

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-08-2021 13:38:06 to 03-08-2021 13:38:10.

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 1540392 1540392 0 0.00 0 20702 7 0 0
doc_list_biud 1540392 1540392 0 0.00 0 61396 27 0 0
sp_add_invoice_to_stock 5247 5103 144 2.74 37 207995 18999 0 144
sp_cancel_adding_invoice 5466 5466 0 0.00 5 94209 820 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 12934 333 0 0
sp_cancel_customer_reserve 538902 538902 0 0.00 1 35268 59 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 3570 70 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 7015 93 0 0
sp_cancel_supplier_invoice 6179 6170 9 0.14 2 25410 886 0 9
sp_cancel_supplier_order 422 422 0 0.00 9 962547 95791 0 0
sp_cancel_write_off 9234 9234 0 0.00 1 6537 110 0 0
sp_client_order 21163 21163 0 0.00 4 21404 406 0 0
sp_customer_reserve 474755 474755 0 0.00 0 49204 187 0 0
sp_fill_shopping_cart 32526 32526 0 0.00 1 19649 48 0 0
sp_fill_shopping_cart_clo_res 474748 474748 0 0.00 0 30647 15 0 0
sp_get_clo_for_invoice 5096 5096 0 0.00 0 30591 358 0 0
sp_kill_cost_storno 15839 15839 0 0.00 0 14902 56 0 0
sp_kill_qstorno_ret_qs2qd 1093630 1093630 0 0.00 0 34012 32 0 0
sp_kill_qty_storno 577411 577411 0 0.00 0 61396 70 0 0
sp_lock_selected_doc 21349 21349 0 0.00 0 9391 7 0 0
sp_make_cost_storno 28632 28632 0 0.00 0 13808 54 0 0
sp_multiply_rows_for_pdistr 28632 28632 0 0.00 0 7816 32 0 0
sp_multiply_rows_for_qdistr 21163 21163 0 0.00 0 17025 213 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 14011 135 0 0
sp_pay_to_supplier 5996 5996 0 0.00 5 12687 139 0 0
sp_payment_common 13197 13197 0 0.00 1 7940 30 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 12911 302 0 0
sp_qd_handle_on_invoice_upd_sts 10562 10562 0 0.00 0 61395 333 0 0
sp_qd_handle_on_reserve_upd_sts 10339 10339 0 0.00 0 20241 64 0 0
sp_reserve_write_off 10339 10339 0 0.00 7 20284 184 0 0
sp_supplier_invoice 5728 5728 0 0.00 6 81185 3601 0 0
sp_supplier_order 5643 5643 0 0.00 5 88936 1378 0 0
srv_aggregate_perf_data 105 105 0 0.00 2357 94767 14276 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3256 124975 46692 0 0
srv_find_qd_qs_mism 1051740 1051740 0 0.00 0 20700 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 56 83844 3190 0 0
srv_make_money_saldo 801 801 0 0.00 1 12062 375 0 0
srv_recalc_idx_stat 5 5 0 0.00 196742 445726 289659 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5103 5103 0 0.00 0 9231 138 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 19455 141 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 5066 16 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 922 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 959 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 2823 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 1986 17 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 17813 1443 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 5176 9 0 0
t$perf-norm:sp_client_order 21163 21163 0 0.00 0 13866 14 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 5916 9 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 6218 10 0 0
t$perf-norm:sp_pay_to_supplier 5996 5996 0 0.00 0 2932 6 0 0
t$perf-norm:sp_reserve_write_off 10339 10339 0 0.00 0 3644 8 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 5913 20 0 0
t$perf-norm:sp_supplier_order 5643 5643 0 0.00 0 4739 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 135 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 93 2 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1996 11 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 368 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 55 23 0 0
v_add_invoice_to_stock 10480 10192 288 2.74 0 5567 16 0 288
v_all_customers 38000 38000 0 0.00 0 17 1 0 0
v_all_suppliers 31728 31728 0 0.00 0 17 1 0 0
v_all_wares 42324 42324 0 0.00 0 216 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1747 12 0 0
v_cancel_client_order 3564 3564 0 0.00 0 1807 5 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 1061 5 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 1457 4 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 2219 7 0 0
v_cancel_supplier_invoice 892 874 18 2.01 0 7670 21 0 18
v_cancel_supplier_order 844 844 0 0.00 0 5363 19 0 0
v_cancel_write_off 2022 2022 0 0.00 0 5094 19 0 0
v_max_id_clo_ord 5642 5642 0 0.00 0 2670 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 4947 21 0 0
v_max_id_ord_sup 5721 5721 0 0.00 0 5706 6 0 0
v_max_non_paid_invoice 1495 1495 0 0.00 0 328 2 0 0
v_max_non_paid_realizn 4122 4122 0 0.00 0 3699 4 0 0
v_min_id_clo_ord 5642 5642 0 0.00 0 11852 7 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 2204 8 0 0
v_min_id_ord_sup 5721 5721 0 0.00 0 5665 10 0 0
v_min_non_paid_invoice 5996 5996 0 0.00 0 7933 19 0 0
v_min_non_paid_realizn 7201 7201 0 0.00 0 7830 27 0 0
v_our_firm 10482 10482 0 0.00 0 20 1 0 0
v_reserve_write_off 20672 20672 0 0.00 0 5087 5 0 0
x_lock_depdocs_on_canc_invoice 11199 11199 0 0.00 0 13295 105 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 4400 301 0 0
x_make_qstorno_1000_1200 5643 5643 0 0.00 2 88917 1302 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 3 79787 3406 0 0
x_make_qstorno_2100_3300 474755 474755 0 0.00 0 49201 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-08-2021 13:38:11 to 03-08-2021 13:38:15.

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
11:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:09 37137 8192000000 9735769216 10036109312 812943440 9.027 100 85 170 0 840739264 6984512 20811312
11:09:49 18600 8192000000 9776723312 10083266560 848056656 9.381 100 89 178 0 885545584 16720928 20768000
11:11:28 13675 8192000000 9812281104 10132516864 879440928 9.694 100 87 174 1 924940288 25919408 19579952
11:12:48 12156 8192000000 9895918688 10231308288 930885776 10.203 100 84 168 0 1010681296 62378864 17416656
11:14:02 6506 8192000000 9936969824 10283585536 973960512 10.625 100 80 160 0 1044288048 53660080 16667456
11:15:10 4829 8192000000 9969819360 10327179264 1002864720 10.906 100 86 172 0 1088456464 67940720 17651024
11:16:16 2283 8192000000 10010636048 10372567040 1031222288 11.180 100 91 183 2 1124174784 74922224 18030272
11:17:21 1874 8192000000 10032729520 10403405824 1053650192 11.396 100 89 177 1 1146455152 75354080 17450880
11:18:24 20201 8192000000 10019885216 10394763264 1061915648 11.475 100 74 147 2 1122355056 45210912 15228496
11:19:47 9416 8192000000 9973437232 10358165504 1068269840 11.536 100 33 67 0 1109883008 34678128 6935040
11:20:58 6793 8192000000 9988857808 10388324352 1080144592 11.649 100 24 48 3 1107581408 22619136 4817680
11:22:07 5062 8192000000 9991598160 10389364736 1087665968 11.720 100 48 95 3 1112006544 13637952 10702624
11:23:13 64984 8192000000 10010261824 10405003264 1089188000 11.735 100 59 118 2 1118740832 16502752 13050080
11:25:19 5722 8192000000 10023172944 10423615488 1097948640 11.818 100 42 84 0 1132908416 25921840 9037936
11:26:26 5689 8192000000 10004848784 10406428672 1093646896 11.777 100 29 58 0 1117091248 17676304 5768048
11:27:33 67279 8192000000 9950732400 10345689088 962628416 10.515 89 19 35 3 975778640 9560896 3589328
11:29:42 7117 8192000000 9921829328 10312482816 981239632 10.696 95 38 73 2 1008291408 18899328 8152448
11:30:51 7064 8192000000 9951654048 10338820096 1010403648 10.979 98 45 89 2 1032442704 12234080 9804976
11:32:02 20072 8192000000 9928971760 10323914752 1007921568 10.955 98 39 78 0 1038067328 21886832 8258928
11:33:48 6181 8192000000 9953845456 10344071168 1034625536 11.213 100 28 56 0 1061745744 21100832 6019376
11:34:54 6344 8192000000 9966153744 10351702016 1049627440 11.357 100 47 95 2 1076985008 17158176 10199392
11:36:01 7450 8192000000 9981770352 10380443648 1061777520 11.473 100 46 90 2 1079222496 5733360 11711616
11:37:11 124973 8192000000 10014884848 10422427648 1076797808 11.617 100 36 72 0 1116961248 32791248 7372192
11:40:33 50431 8192000000 10071589568 10484711424 1095014192 11.790 100 35 70 2 1161637680 59112224 7511264
11:42:35 3700 8192000000 10085413072 10486583296 1110137504 11.934 100 64 125 2 1188075120 64139184 13798432
11:43:43 7651 8192000000 10121292592 10529472512 1124631584 12.071 100 50 100 1 1229461136 93340464 11489088
11:45:01 8318 8192000000 10157327936 10572394496 1137641872 12.193 100 57 113 1 1265147024 114282496 13222656
11:46:15 12077 8192000000 10210354480 10626740224 1152522816 12.333 100 53 104 1 1308951984 145479312 10949856
11:47:43 9386 8192000000 10234001168 10660229120 1171226512 12.508 100 57 114 1 1335123056 152009552 11886992
11:48:54 3815 8192000000 10268126960 10680696832 1173418752 12.529 100 77 154 1 1373675968 182772976 17484240
11:50:02 4486 8192000000 10286299680 10706661376 1169848080 12.495 100 50 100 1 1385427872 204743824 10835968
11:51:11 3460 8192000000 10310626096 10725064704 1168474048 12.483 100 73 146 4 1408240928 223555376 16211504
11:52:20 3254 8192000000 10307217264 10720591872 1158645440 12.391 100 84 165 0 1413992880 235587152 19760288
11:53:29 4025 8192000000 10282205232 10693644288 1142847776 12.242 100 73 143 0 1386755808 226251056 17656976
11:54:37 9958 8192000000 10271621952 10716094464 1124714160 12.072 99 37 74 1 1373248944 239678256 8856528
11:55:51 5966 8192000000 10277079536 10688921600 1137558000 12.193 100 56 112 0 1371415392 221235456 12621936
11:56:58 12677 8192000000 10257772432 10668376064 1117384528 12.002 99 46 91 0 1347802816 219724768 10693520
11:58:19 13936 8192000000 10318301600 10786656256 1129300640 12.115 99 46 92 1 1437845984 297062496 11482848
11:59:42 25009 8192000000 10285822416 10748731392 1121558272 12.042 99 29 58 0 1394409536 264656256 8195008
12:01:08 13785 8192000000 10206353088 10652205056 1106788752 11.902 99 25 50 2 1290792832 178157360 5846720
12:02:25 89411 8192000000 10115183984 10543030272 1061490000 11.471 95 41 81 1 1174410000 103648320 9271680
12:05:00 25168 8192000000 10116979168 10559598592 1104762112 11.883 100 12 24 0 1214946496 107659344 2525040
12:07:50 70951 8192000000 10046570912 10477932544 1093387264 11.775 99 26 52 2 1141087936 42303936 5396736
12:10:06 88329 8192000000 10060001072 10496262144 1079010928 11.638 97 17 33 1 1113824128 30808144 4005056
12:12:41 71032 8192000000 10073195648 10523287552 1077514752 11.624 96 16 33 2 1125439520 44462544 3462224
12:14:54 43584 8192000000 10104517792 10572517376 1108365872 11.917 99 19 38 0 1185552976 73126848 4060256
12:17:14 72554 8192000000 10047963664 10503925760 1105840016 11.893 98 10 20 2 1132615088 24943936 1831136
12:19:32 99093 8192000000 10070226736 10503970816 1121853984 12.045 99 41 83 1 1156305840 25450848 9001008
12:22:15 16756 8192000000 10073386336 10506715136 1138455952 12.201 100 59 115 2 1177516848 25642976 13417920
12:23:36 24516 8192000000 10095222176 10536771584 1151987856 12.328 100 26 52 1 1174513200 17436208 5089136
12:25:02 16517 8192000000 10125765216 10551595008 1151429632 12.323 100 55 109 1 1215519088 51965552 12123904
12:26:19 38738 8192000000 10152391904 10618671104 1150704304 12.316 99 12 24 0 1244971888 91344784 2922800
12:28:04 56525 8192000000 10109228816 10613211136 1119562416 12.023 98 19 38 0 1242027680 117463600 5001664
12:30:01 55420 8192000000 10099859632 10585665536 1133989920 12.159 99 19 38 0 1214757024 76212736 4554368
12:31:59 42747 8192000000 10113319456 10557575168 1131070624 12.131 98 16 32 0 1194304016 59624016 3609376
12:33:53 63846 8192000000 10170535264 10642501632 1124309360 12.068 97 32 64 0 1287365760 156349504 6706896
12:36:00 60542 8192000000 10069577568 10529796096 1114786256 11.978 98 8 16 0 1159737184 43453024 1497904
12:38:01 54353 8192000000 10109197488 10541858816 1081466176 11.661 96 25 50 0 1157705072 71096224 5142672
12:41:19 22878 8192000000 10103198976 10524184576 1100581904 11.843 98 23 46 0 1151385216 46324400 4478912
12:42:42 104025 8192000000 10062172384 10507034624 1089451824 11.737 98 22 44 0 1135388736 40716016 5220896
12:45:28 48050 8192000000 10059849520 10517381120 1096903024 11.808 99 13 26 1 1159539616 59967232 2669360
12:47:20 52149 8192000000 10091562480 10536312832 1122301424 12.049 100 23 46 1 1189872512 62557440 5013648
12:49:21 61934 8192000000 10119818912 10608156672 1115087760 11.981 99 14 28 1 1237815984 119682560 3045664
12:52:12 55148 8192000000 10036270640 10484174848 1054690272 11.406 95 19 38 1 1089764656 31085248 3989136
12:54:07 29790 8192000000 10056365856 10476281856 1074040640 11.591 97 33 66 0 1117079248 36742976 6295632
12:55:38 85159 8192000000 10069757504 10546601984 1111513024 11.947 100 15 28 2 1190181808 76279088 2389696
12:58:08 72297 8192000000 10089463872 10591191040 1120891840 12.035 100 16 32 0 1229790080 105535488 3362752
13:00:25 42967 8192000000 10101715984 10530783232 1097396416 11.813 97 33 66 0 1169941200 65387328 7157456
13:02:13 45702 8192000000 10068813984 10509688832 1108603072 11.919 99 20 40 1 1137410768 24976848 3830848
13:04:42 99560 8192000000 10079704416 10537095168 1120672720 12.033 99 35 70 2 1167259664 39534960 7051984
13:07:23 59759 8192000000 10102102160 10552913920 1123639408 12.061 98 20 40 2 1186055440 58002432 4413600
13:09:35 92237 8192000000 10116333952 10588553216 1148586768 12.296 100 27 54 2 1225362480 70075968 6699744
13:12:09 48582 8192000000 10101843744 10588704768 1139391712 12.210 100 15 30 0 1204749712 62197168 3160832
13:14:16 47114 8192000000 10088992352 10535346176 1136858512 12.186 100 46 92 2 1190776928 43897456 10020960
13:16:06 43647 8192000000 10145699312 10585624576 1131754192 12.138 99 36 71 2 1214342240 74979728 7608320
13:17:54 82829 8192000000 10056111792 10536128512 1095814160 11.798 97 17 32 2 1189671088 90042880 3814048
13:20:22 35716 8192000000 10091351968 10561662976 1086656416 11.711 96 42 84 1 1157509200 62498608 8354176
13:22:06 32912 8192000000 10099847968 10552328192 1100420336 11.842 98 34 68 1 1199080000 91654912 7004752
13:23:41 83037 8192000000 10053065120 10514796544 1105417008 11.889 98 36 72 0 1159774176 47319344 7037824
13:26:05 47736 8192000000 10045512512 10508570624 1118461376 12.012 99 4 8 0 1149029472 30027536 540560
13:27:56 57076 8192000000 10083508816 10553053184 1088667600 11.730 96 9 18 1 1144408224 54019584 1721040
13:29:55 81789 8192000000 10062717936 10528272384 1066184720 11.516 94 25 50 2 1137203952 64745088 6274144
13:32:29 74137 8192000000 10120212768 10580729856 1125984224 12.083 99 19 38 0 1212505008 82389248 4131536
13:34:43 81636 8192000000 10059853424 10536022016 1096089056 11.801 97 19 38 1 1137754592 37341680 4323856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-08-2021 13:38:17 to 03-08-2021 13:38:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-08-2021 13:38:32 to 03-08-2021 13:38:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240064
MON$OLDEST_ACTIVE               242583
MON$OLDEST_SNAPSHOT             242583
MON$NEXT_TRANSACTION            242583
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                       2053728
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2556 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2556 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2556 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2556 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Aug  3 13:37:06 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Aug  3 13:37:12 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

03.08.21 13:38:35. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-03 13:38:38 No severe PSQL-related problems occured
03.08.21 13:38:39. End of report.