Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.08.2021 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15837896 kB
MemAvailable 30880988 kB
Buffers 0 kB
Cached 15803888 kB
SwapCached 324 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-08-31 01:37:47
DTS_END                         2021-08-31 01:37:47.6000
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(57:17)
IP                              ::1/47894
TRN_ID                          246419
ATT_ID                          621
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9569.70 24075 1148364
customer order: creation 174.77 367 20972
customer order: refuse 15.58 345 1869
order to supplier: creation 47.94 1266 5753
order to supplier: removal 3.81 100568 457
invoice (draft): creation 47.87 3293 5744
invoice (draft): removal 56.13 788 6736
invoice accept: apply 42.39 17922 5087
invoice accept: cancel 49.98 784 5998
customer reserve: creation 3899.76 177 467971
customer reserve: removal 4932.26 60 591871
realization accept: apply 86.26 171 10351
realization accept: cancel 79.78 107 9574
payment from customer: creation 61.07 125 7328
payment from customer: removal 5.98 86 717
payment to supplier: creation 49.95 125 5994
payment to supplier: removal 3.86 88 463
service: total inventory turnovers 4.76 1556 571
service: total monetary turnovers 6.68 61 801
service: aggregate perf. data 0.86 23151 103
service: refresh index statistics 0.03 330451 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-08-2021 01:38:35 to 31-08-2021 01:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2327
TEST_TIME 2 2427
TEST_TIME 3 3413
TEST_TIME 4 4029
TEST_TIME 5 4277
TEST_TIME 6 4459
TEST_TIME 7 4569
TEST_TIME 8 4591
TEST_TIME 9 5264
TEST_TIME 10 6007
TEST_TIME 11 6336
TEST_TIME 12 6600
TEST_TIME 13 6886
TEST_TIME 14 6919
TEST_TIME 15 7211
TEST_TIME 16 7345
TEST_TIME 17 7261
TEST_TIME 18 7820
TEST_TIME 19 7665
TEST_TIME 20 7865
TEST_TIME 21 8070
TEST_TIME 22 8253
TEST_TIME 23 8571
TEST_TIME 24 8980
TEST_TIME 25 8961
TEST_TIME 26 8965
TEST_TIME 27 9421
TEST_TIME 28 9400
TEST_TIME 29 9677
TEST_TIME 30 10113
TEST_TIME 31 10706
TEST_TIME 32 10830
TEST_TIME 33 10920
TEST_TIME 34 10756
TEST_TIME 35 10771
TEST_TIME 36 10782
TEST_TIME 37 10680
TEST_TIME 38 10562
TEST_TIME 39 10581
TEST_TIME 40 10428
TEST_TIME 41 10444
TEST_TIME 42 10265
TEST_TIME 43 10071
TEST_TIME 44 10153
TEST_TIME 45 10105
TEST_TIME 46 10074
TEST_TIME 47 10078
TEST_TIME 48 9903
TEST_TIME 49 9938
TEST_TIME 50 9923
TEST_TIME 51 9825
TEST_TIME 52 10049
TEST_TIME 53 9921
TEST_TIME 54 9862
TEST_TIME 55 9806
TEST_TIME 56 9786
TEST_TIME 57 9769
TEST_TIME 58 9730
TEST_TIME 59 9709
TEST_TIME 60 9648
TEST_TIME 61 9587
TEST_TIME 62 9534
TEST_TIME 63 9506
TEST_TIME 64 9485
TEST_TIME 65 9464
TEST_TIME 66 9567
TEST_TIME 67 9554
TEST_TIME 68 9538
TEST_TIME 69 9583
TEST_TIME 70 9626
TEST_TIME 71 9661
TEST_TIME 72 9634
TEST_TIME 73 9709
TEST_TIME 74 9754
TEST_TIME 75 9778
TEST_TIME 76 9887
TEST_TIME 77 9925
TEST_TIME 78 9844
TEST_TIME 79 9778
TEST_TIME 80 9773
TEST_TIME 81 9703
TEST_TIME 82 9726
TEST_TIME 83 9769
TEST_TIME 84 9727
TEST_TIME 85 9679
TEST_TIME 86 9654
TEST_TIME 87 9616
TEST_TIME 88 9549
TEST_TIME 89 9553
TEST_TIME 90 9538
TEST_TIME 91 9532
TEST_TIME 92 9569
TEST_TIME 93 9572
TEST_TIME 94 9549
TEST_TIME 95 9674
TEST_TIME 96 9692
TEST_TIME 97 9721
TEST_TIME 98 9696
TEST_TIME 99 9778
TEST_TIME 100 9768
TEST_TIME 101 9759
TEST_TIME 102 9787
TEST_TIME 103 9771
TEST_TIME 104 9742
TEST_TIME 105 9678
TEST_TIME 106 9657
TEST_TIME 107 9618
TEST_TIME 108 9602
TEST_TIME 109 9591
TEST_TIME 110 9597
TEST_TIME 111 9573
TEST_TIME 112 9525
TEST_TIME 113 9507
TEST_TIME 114 9554
TEST_TIME 115 9587
TEST_TIME 116 9589
TEST_TIME 117 9579
TEST_TIME 118 9583
TEST_TIME 119 9619
TEST_TIME 120 9573

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-08-2021 01:38:39 to 31-08-2021 01:38:42.

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 1584397 1584397 0 0.00 0 26284 6 0 0
doc_list_biud 1584397 1584397 0 0.00 0 24431 29 0 0
sp_add_invoice_to_stock 5208 5087 121 2.32 44 217736 17922 0 121
sp_cancel_adding_invoice 5998 5998 0 0.00 6 52426 785 0 0
sp_cancel_client_order 1869 1869 0 0.00 4 15945 346 0 0
sp_cancel_customer_reserve 591871 591871 0 0.00 1 22223 61 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 3 4965 86 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 3 3714 89 0 0
sp_cancel_supplier_invoice 6747 6736 11 0.16 2 16311 788 0 11
sp_cancel_supplier_order 457 457 0 0.00 9 993192 100569 0 0
sp_cancel_write_off 9574 9574 0 0.00 1 24706 108 0 0
sp_client_order 20972 20972 0 0.00 3 16929 367 0 0
sp_customer_reserve 467971 467971 0 0.00 0 77401 178 0 0
sp_fill_shopping_cart 32465 32465 0 0.00 1 11505 37 0 0
sp_fill_shopping_cart_clo_res 467963 467963 0 0.00 0 18653 14 0 0
sp_get_clo_for_invoice 5078 5078 0 0.00 0 38729 290 0 0
sp_kill_cost_storno 16752 16752 0 0.00 0 6641 58 0 0
sp_kill_qstorno_ret_qs2qd 1200509 1200509 0 0.00 0 24428 33 0 0
sp_kill_qty_storno 631933 631933 0 0.00 0 24429 71 0 0
sp_lock_selected_doc 21439 21439 0 0.00 0 8831 6 0 0
sp_make_cost_storno 28750 28750 0 0.00 0 11588 48 0 0
sp_multiply_rows_for_pdistr 28750 28750 0 0.00 0 6474 26 0 0
sp_multiply_rows_for_qdistr 20972 20972 0 0.00 0 11705 157 0 0
sp_pay_from_customer 7328 7328 0 0.00 4 28918 126 0 0
sp_pay_to_supplier 5994 5994 0 0.00 5 14510 125 0 0
sp_payment_common 13322 13322 0 0.00 2 28107 30 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 15926 311 0 0
sp_qd_handle_on_invoice_upd_sts 11075 11075 0 0.00 0 18236 366 0 0
sp_qd_handle_on_reserve_upd_sts 10351 10351 0 0.00 0 15444 59 0 0
sp_reserve_write_off 10351 10351 0 0.00 6 16232 171 0 0
sp_supplier_invoice 5744 5744 0 0.00 6 65535 3294 0 0
sp_supplier_order 5753 5753 0 0.00 5 33764 1267 0 0
srv_aggregate_perf_data 103 103 0 0.00 3397 555975 23152 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1136 128893 43713 0 0
srv_find_qd_qs_mism 1100920 1100920 0 0.00 0 26283 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 29 152689 1557 0 0
srv_make_money_saldo 801 801 0 0.00 1 20777 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 128160 469852 330452 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5087 5087 0 0.00 0 8066 168 0 0
t$perf-norm:sp_cancel_adding_invoice 338 338 0 0.00 0 6214 129 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 972 10 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 691 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 5133 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 665 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 9784 41 0 0
t$perf-norm:sp_cancel_supplier_order 457 457 0 0.00 0 28316 1867 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 1713 8 0 0
t$perf-norm:sp_client_order 20972 20972 0 0.00 0 10527 21 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 6586 14 0 0
t$perf-norm:sp_pay_from_customer 7328 7328 0 0.00 0 12671 12 0 0
t$perf-norm:sp_pay_to_supplier 5994 5994 0 0.00 0 3907 10 0 0
t$perf-norm:sp_reserve_write_off 10351 10351 0 0.00 0 9914 15 0 0
t$perf-norm:sp_supplier_invoice 5744 5744 0 0.00 0 4572 21 0 0
t$perf-norm:sp_supplier_order 5753 5753 0 0.00 0 4573 13 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 229 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 3467 25 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 1106 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10396 10154 242 2.32 0 5369 22 0 242
v_all_customers 38300 38300 0 0.00 0 25 1 0 0
v_all_suppliers 31802 31802 0 0.00 0 17 1 0 0
v_all_wares 41944 41944 0 0.00 0 86 1 0 0
v_cancel_adding_invoice 676 676 0 0.00 0 792 7 0 0
v_cancel_client_order 3738 3738 0 0.00 0 4927 6 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 2869 8 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 1284 5 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 3615 7 0 0
v_cancel_supplier_invoice 914 892 22 2.40 0 6328 23 0 22
v_cancel_supplier_order 914 914 0 0.00 0 4828 16 0 0
v_cancel_write_off 1950 1950 0 0.00 0 2780 14 0 0
v_max_id_clo_ord 5751 5751 0 0.00 0 153 1 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 3048 21 0 0
v_max_id_ord_sup 5741 5741 0 0.00 0 5949 4 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 2753 5 0 0
v_max_non_paid_realizn 3924 3924 0 0.00 0 4949 4 0 0
v_min_id_clo_ord 5751 5751 0 0.00 0 2747 5 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 3412 15 0 0
v_min_id_ord_sup 5741 5741 0 0.00 0 10751 11 0 0
v_min_non_paid_invoice 5994 5994 0 0.00 0 11539 17 0 0
v_min_non_paid_realizn 7328 7328 0 0.00 0 28093 21 0 0
v_our_firm 10452 10452 0 0.00 0 14 1 0 0
v_reserve_write_off 20702 20702 0 0.00 0 7769 8 0 0
x_lock_depdocs_on_canc_invoice 12288 12288 0 0.00 0 18609 124 0 0
x_lock_depdocs_on_canc_sup_ord 457 457 0 0.00 0 13420 335 0 0
x_make_qstorno_1000_1200 5753 5753 0 0.00 2 33751 1186 0 0
x_make_qstorno_1200_2000 5744 5744 0 0.00 3 65475 3169 0 0
x_make_qstorno_2100_3300 467971 467971 0 0.00 0 77400 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-08-2021 01:38:43 to 31-08-2021 01:38:47.

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
23:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:51 22449 8192000000 10391580816 10710626304 1439220288 14.943 100 88 176 0 1473240880 5666368 28354224
23:10:19 14692 8192000000 10447573120 10778079232 1504370688 15.514 100 90 180 0 1541321728 8117984 28833056
23:11:40 14417 8192000000 10493957296 10835357696 1548820176 15.900 100 87 174 0 1585765984 9512544 27433264
23:13:00 9841 8192000000 10551102528 10907701248 1610708176 16.431 100 75 150 0 1642978224 9796208 22473840
23:14:14 11751 8192000000 10630697072 11001790464 1692742272 17.124 100 81 162 1 1728269264 12026736 23500256
23:15:31 5466 8192000000 10703957824 11079589888 1763625312 17.714 100 87 174 1 1801603088 13317472 24660304
23:16:38 4795 8192000000 10754584128 11133186048 1810295552 18.098 100 85 170 2 1847477088 13292592 23888944
23:17:45 3348 8192000000 10778772704 11162054656 1839381456 18.336 100 80 161 2 1874849760 13265584 22202720
23:18:51 1975 8192000000 10820749808 11208568832 1876414944 18.636 100 93 184 0 1915525216 12593904 26516368
23:19:53 60605 8192000000 10818940560 11231096832 1887232640 18.723 100 35 71 2 1903246304 5937072 10076592
23:21:57 6309 8192000000 10822567632 11244613632 1896594512 18.799 100 20 40 1 1908479664 6022544 5862608
23:23:04 6019 8192000000 10828635504 11253788672 1907305152 18.885 100 25 50 1 1920057488 5427568 7324768
23:24:11 21406 8192000000 10833011424 11266514944 1913488464 18.935 100 24 47 1 1925189664 4832240 6868960
23:25:35 31167 8192000000 10835477760 11274096640 1924654688 19.024 100 25 50 2 1937451568 6294096 6502784
23:27:06 6625 8192000000 10836580368 11271168000 1920646512 18.992 100 29 58 0 1935561408 6508256 8406640
23:28:14 9113 8192000000 10777407808 11204493312 1843887360 18.372 99 17 34 0 1854204464 5437648 4879456
23:29:27 74384 8192000000 10669750336 11088764928 1657921936 16.831 92 31 62 0 1673060384 5808272 9330176
23:31:42 7430 8192000000 10722937968 11139264512 1775920576 17.816 98 27 54 4 1789305488 5393728 7991184
23:32:50 6888 8192000000 10756222448 11179646976 1845340624 18.384 100 35 71 2 1861108352 5477888 10289840
23:33:58 13275 8192000000 10780198576 11203710976 1857049152 18.479 100 17 34 0 1867747312 5976560 4721600
23:35:14 27274 8192000000 10830742848 11225849856 1878502160 18.653 100 62 121 1 1901402656 4941808 17958688
23:36:41 4747 8192000000 10796188464 11229003776 1884156304 18.699 100 31 59 3 1899709408 6975184 8577920
23:37:48 4273 8192000000 10834989104 11248488448 1909787632 18.905 100 37 70 0 1925715968 5349584 10578752
23:38:59 32918 8192000000 10862042480 11291770880 1935184848 19.108 100 44 88 2 1958049088 10448480 12415760
23:40:39 59332 8192000000 10876279136 11318558720 1952470736 19.246 100 43 85 2 1982045104 14944736 14629632
23:42:43 44093 8192000000 10894854720 11336519680 1960734688 19.312 100 19 37 0 1978811824 12459104 5618032
23:44:43 6096 8192000000 10939766384 11365314560 1991217248 19.553 100 62 124 3 2025153344 16077904 17858192
23:46:07 5198 8192000000 10955967968 11391754240 2007033520 19.678 100 59 119 0 2046174736 21138256 18002960
23:47:16 2999 8192000000 10969295296 11402178560 2012679728 19.723 100 67 134 2 2055576816 22889424 20007664
23:48:24 4800 8192000000 10941287248 11362938880 1962713984 19.328 99 80 159 0 2013461776 27194928 23552864
23:49:36 9943 8192000000 10941832368 11367153664 1961729792 19.320 99 70 141 1 2014259792 31409936 21120064
23:50:51 10588 8192000000 10927449408 11348250624 1946162112 19.196 98 56 112 2 1989187616 25930896 17094608
23:52:06 2361 8192000000 10954074384 11380199424 1982787376 19.487 100 78 155 0 2038393952 32547072 23059504
23:53:11 4370 8192000000 10962372992 11393196032 1992630960 19.565 100 68 136 0 2048056736 34227760 21198016
23:54:23 4335 8192000000 10949616816 11383144448 1980444816 19.468 100 61 122 2 2036672944 37528240 18699888
23:55:32 11454 8192000000 10901662608 11345297408 1947623824 19.208 99 41 82 2 1998696912 38252688 12820400
23:56:54 1135 8192000000 10958264720 11378786304 1975699792 19.431 100 79 158 0 2036243280 36216480 24327008
23:58:03 7101 8192000000 10914853408 11347636224 1937810048 19.129 99 39 77 0 1994907136 43694640 13402448
23:59:16 6878 8192000000 10932525552 11359309824 1940143632 19.148 99 60 120 0 2006371760 46654176 19573952
00:00:30 1288 8192000000 10960488848 11374268416 1965306176 19.348 100 80 160 1 2039607488 48665440 25635872
00:01:37 3164 8192000000 10973602976 11396194304 1987852704 19.527 100 79 158 3 2054945568 42996400 24096464
00:02:45 10566 8192000000 10955282304 11402080256 1984386464 19.499 100 35 70 0 2041039168 44530096 12122608
00:04:00 11947 8192000000 10962196016 11406524416 1982211632 19.482 100 41 81 8 2050568464 54237520 14119312
00:05:26 22311 8192000000 10968584736 11409457152 1987511280 19.524 100 54 107 1 2048743920 43629472 17603168
00:06:52 17154 8192000000 10938547024 11391459328 1956982832 19.282 99 28 54 0 2007608304 41560544 9064928
00:08:16 72910 8192000000 10893455904 11372818432 1943242576 19.173 99 7 14 2 1972937488 27836704 1858208
00:10:30 90698 8192000000 10887349168 11342012416 1890699760 18.751 96 9 18 0 1904101072 11353136 2048176
00:13:01 88443 8192000000 10896572832 11342594048 1921305520 18.997 98 4 8 0 1932971120 11075584 590016
00:15:30 99939 8192000000 10932666432 11383029760 1952733280 19.248 98 34 68 0 1971851056 9191184 9926592
00:18:10 128718 8192000000 10927831760 11376168960 1987239472 19.522 100 16 32 1 2001488272 9998752 4250048
00:21:25 73994 8192000000 10926333904 11382419456 1974336976 19.420 99 30 60 0 1996149520 13314112 8498432
00:23:39 128892 8192000000 10906118720 11360739328 1937582960 19.127 97 25 50 0 1960588768 15043376 7962432
00:26:53 13118 8192000000 10909323664 11364339712 1960457776 19.310 99 35 69 1 1980695952 10493664 9744512
00:28:08 26913 8192000000 10946423616 11378782208 1973705536 19.415 99 56 110 2 2000878288 10358784 16813968
00:29:36 43165 8192000000 10895251040 11385630720 1955023808 19.266 99 4 8 0 1972547312 16367200 1156304
00:31:20 57881 8192000000 10882132336 11353747456 1940283216 19.149 98 16 32 1 1957872976 12807824 4781936
00:33:55 36404 8192000000 10907309040 11381100544 1971554928 19.398 100 8 16 0 1986654368 12658288 2441152
00:35:32 37885 8192000000 10883712864 11371634688 1957917024 19.289 99 26 52 3 1980232112 14571024 7744064
00:37:16 47164 8192000000 10897010016 11368087552 1956607088 19.279 99 24 47 1 1973469616 10047184 6815344
00:39:03 64068 8192000000 10876971712 11361935360 1909095872 18.899 97 10 20 0 1916674656 5597152 1981632
00:41:10 62946 8192000000 10831987952 11300433920 1805936240 18.063 92 17 34 1 1820287408 9826000 4525168
00:43:13 40680 8192000000 10868699920 11331780608 1931251680 19.077 99 24 48 2 1955115360 16096320 7767360
00:44:54 23751 8192000000 10871494720 11325485056 1882471216 18.685 97 43 86 0 1903312272 8818128 12022928
00:46:26 36579 8192000000 10891371024 11365703680 1958056976 19.291 100 28 56 2 1976998464 10936016 8005472
00:48:05 68398 8192000000 10900312032 11363905536 1906245680 18.876 97 25 50 1 1922969456 9954336 6769440
00:50:13 99211 8192000000 10885213168 11369332736 1865935792 18.551 95 16 30 2 1888707104 18163072 4608240
00:52:53 57128 8192000000 10911149504 11398348800 1965270496 19.348 100 11 21 4 1995653488 27017776 3365216
00:54:54 60103 8192000000 10874365744 11344965632 1903938576 18.858 97 20 40 0 1917772048 8002496 5830976
00:56:55 47521 8192000000 10887884272 11360620544 1957275424 19.284 100 16 32 1 1972113216 10349712 4488080
00:58:53 60399 8192000000 10882869392 11376205824 1950357568 19.229 99 35 69 2 1975662688 14368464 10936656
01:02:33 43293 8192000000 10889617888 11362906112 1958405776 19.293 100 24 48 0 1981912256 16686880 6819600
01:04:17 57552 8192000000 10886166288 11377782784 1969120896 19.378 100 9 18 0 1983140752 11456928 2562928
01:06:24 85620 8192000000 10891324640 11384078336 1948617392 19.215 99 6 12 1 1963424000 13414560 1392048
01:08:50 43411 8192000000 10919957632 11402305536 1970593920 19.390 99 32 64 3 1994083504 14525568 8964016
01:10:41 52225 8192000000 10918505184 11402104832 1965603040 19.351 98 17 34 2 1988211360 17688032 4920288
01:12:34 57269 8192000000 10955719776 11417976832 2000341296 19.625 100 22 41 1 2018114096 11217488 6555312
01:14:31 60985 8192000000 10958069168 11422642176 1990867120 19.551 99 24 48 0 2013828720 15961392 7000208
01:16:59 45090 8192000000 10913127264 11392983040 1967008640 19.362 99 8 16 2 1983059136 13803296 2247200
01:18:46 27194 8192000000 10908929408 11388973056 1964452016 19.341 99 27 54 2 1984330928 12417392 7461520
01:20:20 45128 8192000000 10929407568 11393785856 1971808096 19.400 99 32 64 1 1990050352 9689056 8553200
01:22:08 70084 8192000000 10909771232 11385151488 1962389936 19.325 98 31 61 1 1978056512 7058192 8608384
01:24:20 72355 8192000000 10925287408 11395923968 1926228384 19.037 96 31 62 1 1946698192 10932032 9537776
01:26:37 56524 8192000000 10888219040 11374333952 1905772272 18.873 96 6 12 0 1923038240 15228000 2037968
01:28:38 79249 8192000000 10888984576 11369459712 1955804048 19.273 99 27 54 1 1976292912 11835696 8653168
01:31:21 77010 8192000000 10896676528 11374895104 1946579712 19.199 99 7 14 0 1959430560 10682992 2167856
01:34:18 40190 8192000000 10855158464 11319681024 1884153744 18.699 97 33 64 1 1904876912 10949920 9773248
01:36:03 76805 8192000000 10842613808 11307294720 1751583392 17.615 90 20 40 2 1767764320 10246512 5934416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-08-2021 01:38:49 to 31-08-2021 01:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 31-08-2021 01:39:05 to 31-08-2021 01:39:06.

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          245413
MON$OLDEST_ACTIVE               247794
MON$OLDEST_SNAPSHOT             247794
MON$NEXT_TRANSACTION            247794
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                       2064864
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:801: 
:802: mscs-test-161.budzdorov.ru    Tue Aug 31 01:37:48 2021
:803:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:804: 
:805: 
:806: mscs-test-161.budzdorov.ru    Tue Aug 31 01:37:49 2021
:807:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:808: 
:809: 
:810: mscs-test-161.budzdorov.ru    Tue Aug 31 01:37:53 2021
:811:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:812: 
--- end of diff output ---

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

31.08.21 01:39:07. 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-08-31 01:39:10 No severe PSQL-related problems occured
31.08.21 01:39:11. End of report.