Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.11.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 15889296 kB
MemAvailable 30805472 kB
Buffers 0 kB
Cached 15782264 kB
SwapCached 4116 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-11-02 01:37:56
DTS_END                         2021-11-02 01:37:56.1960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45242
TRN_ID                          250843
ATT_ID                          672
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: 9362.28 21327 1123473
customer order: creation 185.72 312 22286
customer order: refuse 15.57 341 1868
order to supplier: creation 48.65 1299 5838
order to supplier: removal 3.53 98230 423
invoice (draft): creation 48.34 3354 5801
invoice (draft): removal 52.75 881 6330
invoice accept: apply 43.62 15916 5234
invoice accept: cancel 47.18 825 5661
customer reserve: creation 3987.99 159 478559
customer reserve: removal 4620.45 57 554454
realization accept: apply 90.48 201 10857
realization accept: cancel 80.34 100 9641
payment from customer: creation 62.95 120 7554
payment from customer: removal 5.91 73 709
payment to supplier: creation 52.13 147 6255
payment to supplier: removal 3.56 76 427
service: total inventory turnovers 5.26 1105 631
service: total monetary turnovers 6.98 37 838
service: aggregate perf. data 0.86 19200 103
service: refresh index statistics 0.03 284100 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-11-2021 01:38:57 to 02-11-2021 01:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2265
TEST_TIME 2 2700
TEST_TIME 3 2957
TEST_TIME 4 3254
TEST_TIME 5 3424
TEST_TIME 6 3315
TEST_TIME 7 3702
TEST_TIME 8 4552
TEST_TIME 9 4558
TEST_TIME 10 4542
TEST_TIME 11 4892
TEST_TIME 12 5144
TEST_TIME 13 5374
TEST_TIME 14 5999
TEST_TIME 15 6606
TEST_TIME 16 6517
TEST_TIME 17 6883
TEST_TIME 18 7275
TEST_TIME 19 7501
TEST_TIME 20 7671
TEST_TIME 21 8041
TEST_TIME 22 8400
TEST_TIME 23 8181
TEST_TIME 24 8868
TEST_TIME 25 9221
TEST_TIME 26 10115
TEST_TIME 27 10462
TEST_TIME 28 10203
TEST_TIME 29 10535
TEST_TIME 30 10386
TEST_TIME 31 10236
TEST_TIME 32 10183
TEST_TIME 33 10145
TEST_TIME 34 10035
TEST_TIME 35 9918
TEST_TIME 36 9978
TEST_TIME 37 10012
TEST_TIME 38 9947
TEST_TIME 39 10016
TEST_TIME 40 9996
TEST_TIME 41 9929
TEST_TIME 42 9947
TEST_TIME 43 10088
TEST_TIME 44 10072
TEST_TIME 45 10105
TEST_TIME 46 9990
TEST_TIME 47 9805
TEST_TIME 48 9822
TEST_TIME 49 9772
TEST_TIME 50 9730
TEST_TIME 51 9614
TEST_TIME 52 9694
TEST_TIME 53 9627
TEST_TIME 54 9560
TEST_TIME 55 9616
TEST_TIME 56 9603
TEST_TIME 57 9549
TEST_TIME 58 9464
TEST_TIME 59 9637
TEST_TIME 60 9758
TEST_TIME 61 9683
TEST_TIME 62 9728
TEST_TIME 63 9730
TEST_TIME 64 9702
TEST_TIME 65 9761
TEST_TIME 66 9717
TEST_TIME 67 9777
TEST_TIME 68 9727
TEST_TIME 69 9693
TEST_TIME 70 9745
TEST_TIME 71 9806
TEST_TIME 72 9829
TEST_TIME 73 9785
TEST_TIME 74 9729
TEST_TIME 75 9892
TEST_TIME 76 9790
TEST_TIME 77 9718
TEST_TIME 78 9680
TEST_TIME 79 9614
TEST_TIME 80 9638
TEST_TIME 81 9593
TEST_TIME 82 9527
TEST_TIME 83 9489
TEST_TIME 84 9459
TEST_TIME 85 9478
TEST_TIME 86 9466
TEST_TIME 87 9407
TEST_TIME 88 9403
TEST_TIME 89 9442
TEST_TIME 90 9388
TEST_TIME 91 9367
TEST_TIME 92 9364
TEST_TIME 93 9340
TEST_TIME 94 9345
TEST_TIME 95 9344
TEST_TIME 96 9383
TEST_TIME 97 9440
TEST_TIME 98 9424
TEST_TIME 99 9485
TEST_TIME 100 9423
TEST_TIME 101 9500
TEST_TIME 102 9511
TEST_TIME 103 9446
TEST_TIME 104 9505
TEST_TIME 105 9549
TEST_TIME 106 9537
TEST_TIME 107 9457
TEST_TIME 108 9437
TEST_TIME 109 9464
TEST_TIME 110 9443
TEST_TIME 111 9457
TEST_TIME 112 9411
TEST_TIME 113 9377
TEST_TIME 114 9358
TEST_TIME 115 9403
TEST_TIME 116 9390
TEST_TIME 117 9368
TEST_TIME 118 9412
TEST_TIME 119 9402
TEST_TIME 120 9367

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-11-2021 01:39:00 to 02-11-2021 01:39:04.

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 1566993 1566993 0 0.00 0 26199 7 0 0
doc_list_biud 1566992 1566992 0 0.00 0 32263 26 0 0
sp_add_invoice_to_stock 5404 5234 170 3.14 21 158826 15916 0 170
sp_cancel_adding_invoice 5661 5661 0 0.00 6 83607 825 0 0
sp_cancel_client_order 1868 1868 0 0.00 4 24769 342 0 0
sp_cancel_customer_reserve 554454 554454 0 0.00 1 29472 58 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 7522 74 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 4415 76 0 0
sp_cancel_supplier_invoice 6340 6330 10 0.15 2 27504 881 0 10
sp_cancel_supplier_order 423 423 0 0.00 9 1062637 98231 0 0
sp_cancel_write_off 9641 9641 0 0.00 1 11097 100 0 0
sp_client_order 22286 22286 0 0.00 3 30124 312 0 0
sp_customer_reserve 478559 478559 0 0.00 0 103662 159 0 0
sp_fill_shopping_cart 33921 33921 0 0.00 1 14027 40 0 0
sp_fill_shopping_cart_clo_res 478554 478554 0 0.00 0 13468 12 0 0
sp_get_clo_for_invoice 5229 5229 0 0.00 0 24596 272 0 0
sp_kill_cost_storno 16438 16438 0 0.00 0 8392 56 0 0
sp_kill_qstorno_ret_qs2qd 1125302 1125302 0 0.00 0 29456 32 0 0
sp_kill_qty_storno 594463 594463 0 0.00 0 32262 69 0 0
sp_lock_selected_doc 22148 22148 0 0.00 0 5944 5 0 0
sp_make_cost_storno 29895 29895 0 0.00 0 22876 66 0 0
sp_multiply_rows_for_pdistr 29894 29894 0 0.00 0 18738 36 0 0
sp_multiply_rows_for_qdistr 22286 22286 0 0.00 0 10729 128 0 0
sp_pay_from_customer 7554 7554 0 0.00 4 20139 120 0 0
sp_pay_to_supplier 6255 6255 0 0.00 5 22894 148 0 0
sp_payment_common 13808 13808 0 0.00 1 11517 30 0 0
sp_qd_handle_on_cancel_clo 1868 1868 0 0.00 0 24760 315 0 0
sp_qd_handle_on_invoice_upd_sts 10890 10890 0 0.00 0 32261 332 0 0
sp_qd_handle_on_reserve_upd_sts 10857 10857 0 0.00 0 26661 61 0 0
sp_reserve_write_off 10857 10857 0 0.00 6 26712 202 0 0
sp_supplier_invoice 5801 5801 0 0.00 7 74905 3355 0 0
sp_supplier_order 5838 5838 0 0.00 5 71687 1300 0 0
srv_aggregate_perf_data 103 103 0 0.00 2123 128080 19201 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1854 154143 46228 0 0
srv_find_qd_qs_mism 1073258 1073258 0 0.00 0 10413 5 0 0
srv_make_invnt_saldo 631 631 0 0.00 29 62521 1106 0 0
srv_make_money_saldo 838 838 0 0.00 1 5632 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 169849 408896 284101 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5234 5234 0 0.00 0 19757 161 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 6794 101 0 0
t$perf-norm:sp_cancel_client_order 1868 1868 0 0.00 0 6103 20 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 5478 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 3691 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 2169 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 5596 27 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 24073 1660 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 2638 12 0 0
t$perf-norm:sp_client_order 22286 22286 0 0.00 0 6158 17 0 0
t$perf-norm:sp_customer_reserve 1095 1095 0 0.00 0 9823 20 0 0
t$perf-norm:sp_pay_from_customer 7554 7554 0 0.00 0 5379 9 0 0
t$perf-norm:sp_pay_to_supplier 6255 6255 0 0.00 0 3142 11 0 0
t$perf-norm:sp_reserve_write_off 10857 10857 0 0.00 0 15816 13 0 0
t$perf-norm:sp_supplier_invoice 5801 5801 0 0.00 0 4359 16 0 0
t$perf-norm:sp_supplier_order 5838 5838 0 0.00 0 7776 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 344 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 106 3 0 0
t$perf-norm:srv_make_invnt_saldo 631 631 0 0.00 0 750 13 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 675 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 3 0 0
v_add_invoice_to_stock 10796 10456 340 3.14 0 7376 17 0 340
v_all_customers 40032 40032 0 0.00 0 30 1 0 0
v_all_suppliers 32216 32216 0 0.00 0 145 1 0 0
v_all_wares 44568 44568 0 0.00 0 235 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 901 9 0 0
v_cancel_client_order 3736 3736 0 0.00 0 3258 5 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 2465 6 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 1898 6 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 533 4 0 0
v_cancel_supplier_invoice 920 900 20 2.17 0 3954 23 0 20
v_cancel_supplier_order 846 846 0 0.00 0 2089 7 0 0
v_cancel_write_off 2060 2060 0 0.00 0 2684 22 0 0
v_max_id_clo_ord 5838 5838 0 0.00 0 5776 3 0 0
v_max_id_clo_res 1095 1095 0 0.00 0 4042 21 0 0
v_max_id_ord_sup 5799 5799 0 0.00 0 7384 5 0 0
v_max_non_paid_invoice 1783 1783 0 0.00 0 1998 5 0 0
v_max_non_paid_realizn 4182 4182 0 0.00 0 11139 7 0 0
v_min_id_clo_ord 5838 5838 0 0.00 0 6542 6 0 0
v_min_id_clo_res 1095 1095 0 0.00 0 3595 12 0 0
v_min_id_ord_sup 5799 5799 0 0.00 0 1988 3 0 0
v_min_non_paid_invoice 6254 6254 0 0.00 0 8176 18 0 0
v_min_non_paid_realizn 7554 7554 0 0.00 0 11509 23 0 0
v_our_firm 11280 11280 0 0.00 0 20 1 0 0
v_reserve_write_off 21712 21712 0 0.00 0 3892 6 0 0
x_lock_depdocs_on_canc_invoice 11541 11541 0 0.00 0 21899 140 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 16549 382 0 0
x_make_qstorno_1000_1200 5838 5838 0 0.00 1 71594 1212 0 0
x_make_qstorno_1200_2000 5801 5801 0 0.00 3 74887 3214 0 0
x_make_qstorno_2100_3300 478559 478559 0 0.00 0 103662 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-11-2021 01:39:05 to 02-11-2021 01:39:09.

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:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 33152 8192000000 9907172912 10221064192 987734672 10.759 100 90 180 0 1017491344 5793104 23963568
23:10:35 26749 8192000000 9967948768 10297556992 1049041744 11.351 100 87 174 0 1079406016 8257504 22106768
23:12:05 13149 8192000000 10003061152 10341650432 1088546576 11.729 100 85 170 1 1119559568 9619248 21393744
23:13:23 13444 8192000000 10048724368 10396938240 1133039184 12.150 100 84 168 0 1164089328 11086192 19963952
23:14:41 9151 8192000000 10100431184 10459484160 1181872176 12.608 100 81 162 0 1213203312 12698544 18632592
23:15:53 5926 8192000000 10138042144 10511290368 1224417184 13.003 100 82 162 3 1254491632 11955952 18118496
23:17:01 2425 8192000000 10167346176 10544721920 1253741504 13.273 100 86 173 3 1285609120 12769232 19098384
23:18:05 1368 8192000000 10200591120 10581966848 1281184192 13.524 100 95 187 0 1316670976 14635168 20851616
23:19:07 2220 8192000000 10215789392 10607005696 1302022432 13.714 100 63 124 2 1330354048 14925088 13406528
23:20:11 22873 8192000000 10204128896 10617606144 1309284880 13.780 100 46 92 2 1328002528 7928368 10789280
23:21:36 6327 8192000000 10205779904 10621505536 1318211344 13.861 100 25 50 2 1330621632 5720752 6689536
23:22:45 8852 8192000000 10211335632 10631262208 1328261312 13.951 100 18 36 0 1341941040 10073152 3606576
23:23:57 6407 8192000000 10216388864 10640474112 1333187408 13.996 100 24 48 0 1345333712 6669104 5477200
23:25:05 57158 8192000000 10225051408 10647433216 1333352816 13.997 100 39 78 0 1348343872 5528544 9462512
23:27:05 7669 8192000000 10222433472 10648870912 1335928304 14.021 100 23 45 0 1347153584 6155760 5069520
23:28:15 7101 8192000000 10190531648 10609975296 1294988880 13.650 99 23 46 1 1307182144 6810608 5382656
23:29:23 8500 8192000000 10158075088 10569203712 1251343712 13.251 98 21 42 2 1260879232 4877984 4657536
23:30:31 18925 8192000000 10145904544 10555752448 1231488288 13.068 97 29 58 1 1243622128 4780016 7353824
23:31:51 4050 8192000000 10144586560 10541150208 1237249648 13.121 99 34 68 0 1252012608 7035344 7727616
23:32:57 9002 8192000000 10151978576 10549952512 1226092000 13.018 97 24 48 1 1237330944 5192720 6046224
23:34:07 15014 8192000000 10185934096 10590412800 1286891264 13.576 100 39 78 2 1302163744 5209280 10063200
23:35:22 7677 8192000000 10188282624 10600038400 1295468192 13.654 100 31 62 1 1309387120 6863584 7055344
23:36:31 5610 8192000000 10201910624 10615422976 1308535424 13.773 100 40 80 3 1324473008 6793696 9143888
23:37:40 8700 8192000000 10208443968 10622242816 1315547536 13.836 100 23 45 2 1326510608 5876416 5086656
23:38:55 27611 8192000000 10252485696 10660347904 1341466864 14.071 100 44 88 0 1358868208 7794896 9606448
23:40:28 22304 8192000000 10270419888 10683473920 1358277392 14.222 99 49 96 1 1382853488 12822112 11753984
23:41:57 74681 8192000000 10279512320 10710503424 1363474112 14.269 99 32 63 1 1384202256 13459680 7268464
23:44:15 21498 8192000000 10309054288 10728460288 1387715456 14.485 100 51 102 1 1421253040 21016416 12521168
23:45:42 3348 8192000000 10325503888 10745438208 1399656208 14.592 100 59 119 0 1433182592 19791280 13735104
23:46:49 15562 8192000000 10338133184 10766573568 1408972368 14.675 100 45 87 3 1449738848 29697776 11068704
23:48:12 6748 8192000000 10364110784 10790141952 1423882512 14.807 100 64 129 1 1474413120 34193568 16337040
23:49:25 10055 8192000000 10355821008 10785992704 1389467904 14.501 97 47 95 1 1436569440 35163520 11938016
23:50:43 1850 8192000000 10360516128 10771873792 1401548608 14.609 100 86 170 1 1468641424 45879968 21212848
23:51:51 4117 8192000000 10374523536 10795061248 1416632928 14.743 100 71 143 0 1480026880 45271008 18122944
23:52:58 2641 8192000000 10346452992 10758688768 1394729296 14.548 100 80 158 0 1454369920 39240800 20399824
23:54:10 5423 8192000000 10332512560 10743857152 1356867616 14.209 98 62 124 0 1420309424 47486848 15954960
23:55:22 2199 8192000000 10325486656 10728632320 1352024464 14.166 99 84 168 0 1420208256 46126032 22057760
23:56:28 4813 8192000000 10331687696 10737491968 1355919728 14.201 99 73 146 1 1422096896 47068416 19108752
23:57:35 10643 8192000000 10322755104 10741026816 1353319488 14.177 98 42 84 0 1410704976 45756432 11629056
23:58:50 9998 8192000000 10324668784 10751180800 1374714224 14.369 100 43 86 1 1435754608 49075008 11965376
00:00:06 37737 8192000000 10313668736 10742804480 1352919120 14.174 99 29 58 1 1409607184 48235968 8452096
00:01:57 15824 8192000000 10306622096 10738966528 1357531248 14.215 100 30 60 1 1415596720 49508832 8556640
00:03:14 26374 8192000000 10302151904 10745421824 1370022512 14.327 100 25 49 2 1423591328 46031760 7537056
00:04:42 61863 8192000000 10272561600 10710286336 1336978464 14.030 98 28 56 1 1358006288 13976448 7051376
00:06:50 70389 8192000000 10275351440 10725634048 1339328896 14.051 98 7 15 1 1351437232 10418992 1689344
00:09:00 71338 8192000000 10295483792 10752032768 1389084768 14.498 100 15 30 2 1399737456 7690448 2962240
00:11:12 62110 8192000000 10295259840 10740244480 1334384480 14.007 95 19 38 3 1350797296 12063568 4349248
00:13:23 67575 8192000000 10290331952 10753355776 1358287968 14.222 98 8 16 1 1378312944 18002320 2022656
00:15:31 23477 8192000000 10311085952 10755416064 1396928192 14.568 100 36 71 1 1415715040 10592768 8194080
00:17:12 102449 8192000000 10307578864 10759393280 1362153760 14.257 97 13 26 0 1381550832 16353808 3043264
00:20:01 104428 8192000000 10308959520 10765676544 1354898608 14.192 97 5 10 0 1369220720 13194032 1128080
00:22:46 45087 8192000000 10291017568 10734055424 1344499568 14.098 97 13 26 0 1356485392 8882160 3103664
00:24:54 48187 8192000000 10299370416 10747121664 1381946464 14.434 100 24 48 1 1395221488 8223376 5051648
00:27:07 107930 8192000000 10278711008 10701856768 1294642768 13.647 93 24 47 1 1311656240 11590240 5423232
00:29:55 19560 8192000000 10289893456 10734747648 1374260560 14.365 99 20 39 0 1394279824 15412928 4606336
00:31:17 16953 8192000000 10305393872 10739261440 1349749248 14.145 97 48 92 2 1385797568 25037872 11010448
00:32:36 68405 8192000000 10297570176 10764877824 1376407584 14.384 100 12 24 0 1411901472 32183904 3309984
00:35:27 79304 8192000000 10273856432 10732212224 1342823760 14.083 98 13 26 1 1367714064 21640848 3249456
00:37:49 52574 8192000000 10254603424 10699292672 1292706352 13.629 95 6 12 0 1311648080 18064032 877696
00:39:42 73417 8192000000 10254205296 10692661248 1251478640 13.252 92 18 36 0 1265916352 10223696 4214016
00:41:57 83693 8192000000 10252654624 10686595072 1245236592 13.194 92 23 46 1 1260325312 10474320 4614400
00:44:23 61840 8192000000 10252327184 10699100160 1318880816 13.867 98 19 38 2 1332856512 9813776 4161920
00:46:25 15736 8192000000 10262845280 10700021760 1335251040 14.015 98 40 81 0 1352339584 6438064 10650480
00:47:44 85837 8192000000 10278163296 10719293440 1339518128 14.053 98 12 24 1 1353586544 11449376 2619040
00:50:11 38160 8192000000 10284622400 10728304640 1359570688 14.234 99 28 56 3 1375247648 9016560 6660400
00:51:56 58982 8192000000 10285499088 10749960192 1362564368 14.260 99 39 78 1 1390639376 19167232 8907776
00:53:56 93900 8192000000 10283559008 10736947200 1361193168 14.248 99 27 54 0 1379393776 11932096 6268512
00:56:30 16332 8192000000 10262406128 10720083968 1336861344 14.029 98 25 50 1 1354814080 12692112 5260624
00:57:47 69369 8192000000 10262054608 10724954112 1360985024 14.246 100 7 14 3 1371684592 8928832 1770736
00:59:58 32576 8192000000 10283056512 10730414080 1359990720 14.237 98 33 66 1 1375987840 8584960 7412160
01:01:32 67949 8192000000 10287896240 10750251008 1380811552 14.424 100 10 20 0 1395430944 11810480 2808912
01:03:41 42159 8192000000 10275811568 10734022656 1368011040 14.309 99 14 27 1 1377355120 6045328 3298752
01:05:54 38089 8192000000 10287784176 10764992512 1384534960 14.457 100 12 24 0 1408373520 20586848 3251712
01:07:41 70535 8192000000 10298943456 10764513280 1392667824 14.530 100 11 22 0 1408125024 11732224 3724976
01:09:52 52075 8192000000 10292518560 10752479232 1342609504 14.081 96 13 26 1 1357220160 12245728 2364928
01:11:51 37469 8192000000 10293917440 10749960192 1373879520 14.362 99 22 44 0 1388384400 9443408 5061472
01:13:34 40568 8192000000 10291591792 10753425408 1372081904 14.346 98 18 36 0 1386760576 10673856 4004816
01:15:19 23802 8192000000 10271066736 10709671936 1327995600 13.949 97 44 88 1 1347526272 9861408 9669264
01:16:46 49334 8192000000 10272671808 10721968128 1332896928 13.993 97 22 44 2 1348800736 10912848 4990960
01:18:39 106329 8192000000 10300640336 10755092480 1368396640 14.313 99 11 22 0 1382342704 11630048 2316016
01:21:27 14259 8192000000 10276825712 10722471936 1350768512 14.154 99 37 74 0 1373979168 15458112 7752544
01:22:44 154143 8192000000 10281639040 10729488384 1315084896 13.832 96 19 38 1 1327979168 8789600 4104672
01:26:23 60093 8192000000 10271962544 10738696192 1350082464 14.148 99 12 24 0 1378366064 25503792 2779808
01:28:25 40211 8192000000 10250881616 10718916608 1339663056 14.054 99 11 22 0 1356388272 14433344 2291872
01:30:47 69743 8192000000 10264554320 10719850496 1335414272 14.016 98 9 18 0 1355097904 16965648 2717984
01:33:22 70242 8192000000 10275834448 10722902016 1336975520 14.030 98 15 31 0 1354105872 13808480 3321872
01:35:32 40393 8192000000 10267767200 10703327232 1295865632 13.658 95 31 61 0 1309227792 6375040 6987120
01:37:14 46245 8192000000 9577868368 9921912832 697656288 7.847 51 14 28 0 707919936 7201168 3062480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-11-2021 01:39:11 to 02-11-2021 01:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 170 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-11-2021 01:39:27 to 02-11-2021 01:39:29.

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          251199
MON$OLDEST_ACTIVE               251200
MON$OLDEST_SNAPSHOT             251200
MON$NEXT_TRANSACTION            251200
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                       2048992
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.33525 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33525 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33525 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33525 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 ---
:205: 
:206: mscs-test-161.budzdorov.ru    Tue Nov  2 01:38:08 2021
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Tue Nov  2 01:38:08 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
--- end of diff output ---

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

02.11.21 01:39:30. 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-11-02 01:39:32 No severe PSQL-related problems occured
02.11.21 01:39:33. End of report.