Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2021 22: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 15997012 kB
MemAvailable 30663288 kB
Buffers 0 kB
Cached 15697876 kB
SwapCached 3776 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-10-14 22:37:51
DTS_END                         2021-10-14 22:37:51.7390
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/43780
TRN_ID                          231435
ATT_ID                          683
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: 8823.48 25004 1058817
customer order: creation 160.13 397 19215
customer order: refuse 13.35 372 1602
order to supplier: creation 41.93 1412 5032
order to supplier: removal 3.41 100440 409
invoice (draft): creation 42.30 3938 5076
invoice (draft): removal 52.38 744 6286
invoice accept: apply 39.29 19135 4715
invoice accept: cancel 46.97 814 5636
customer reserve: creation 3558.52 193 427022
customer reserve: removal 4584.38 58 550126
realization accept: apply 76.63 206 9196
realization accept: cancel 85.39 124 10247
payment from customer: creation 53.68 129 6442
payment from customer: removal 5.25 84 630
payment to supplier: creation 44.91 142 5389
payment to supplier: removal 3.44 69 413
service: total inventory turnovers 4.41 1463 529
service: total monetary turnovers 6.26 34 751
service: aggregate perf. data 0.81 21307 97
service: refresh index statistics 0.03 349022 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2021 22:39:15 to 14-10-2021 22:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2437
TEST_TIME 2 2579
TEST_TIME 3 2581
TEST_TIME 4 2638
TEST_TIME 5 2987
TEST_TIME 6 3325
TEST_TIME 7 3990
TEST_TIME 8 4390
TEST_TIME 9 4385
TEST_TIME 10 4947
TEST_TIME 11 5536
TEST_TIME 12 5595
TEST_TIME 13 6224
TEST_TIME 14 7195
TEST_TIME 15 7555
TEST_TIME 16 8287
TEST_TIME 17 8325
TEST_TIME 18 8675
TEST_TIME 19 8899
TEST_TIME 20 8646
TEST_TIME 21 8764
TEST_TIME 22 9010
TEST_TIME 23 8827
TEST_TIME 24 8882
TEST_TIME 25 9023
TEST_TIME 26 9196
TEST_TIME 27 9464
TEST_TIME 28 9253
TEST_TIME 29 9381
TEST_TIME 30 9487
TEST_TIME 31 9587
TEST_TIME 32 9621
TEST_TIME 33 10110
TEST_TIME 34 9829
TEST_TIME 35 9806
TEST_TIME 36 9646
TEST_TIME 37 9725
TEST_TIME 38 9604
TEST_TIME 39 9507
TEST_TIME 40 9530
TEST_TIME 41 9491
TEST_TIME 42 9306
TEST_TIME 43 9239
TEST_TIME 44 9169
TEST_TIME 45 9193
TEST_TIME 46 9310
TEST_TIME 47 9244
TEST_TIME 48 9159
TEST_TIME 49 9104
TEST_TIME 50 9019
TEST_TIME 51 9023
TEST_TIME 52 8984
TEST_TIME 53 8875
TEST_TIME 54 8749
TEST_TIME 55 8860
TEST_TIME 56 8801
TEST_TIME 57 8730
TEST_TIME 58 8658
TEST_TIME 59 8761
TEST_TIME 60 8655
TEST_TIME 61 8736
TEST_TIME 62 8773
TEST_TIME 63 8874
TEST_TIME 64 8877
TEST_TIME 65 8826
TEST_TIME 66 8980
TEST_TIME 67 8950
TEST_TIME 68 8946
TEST_TIME 69 8920
TEST_TIME 70 9040
TEST_TIME 71 9111
TEST_TIME 72 9118
TEST_TIME 73 9075
TEST_TIME 74 9121
TEST_TIME 75 9199
TEST_TIME 76 9093
TEST_TIME 77 9020
TEST_TIME 78 9021
TEST_TIME 79 8982
TEST_TIME 80 8985
TEST_TIME 81 8966
TEST_TIME 82 8909
TEST_TIME 83 8951
TEST_TIME 84 8944
TEST_TIME 85 8928
TEST_TIME 86 8881
TEST_TIME 87 8915
TEST_TIME 88 8877
TEST_TIME 89 8862
TEST_TIME 90 8807
TEST_TIME 91 8770
TEST_TIME 92 8768
TEST_TIME 93 8742
TEST_TIME 94 8727
TEST_TIME 95 8723
TEST_TIME 96 8803
TEST_TIME 97 8761
TEST_TIME 98 8891
TEST_TIME 99 8915
TEST_TIME 100 8918
TEST_TIME 101 8936
TEST_TIME 102 8972
TEST_TIME 103 8993
TEST_TIME 104 8997
TEST_TIME 105 9023
TEST_TIME 106 9082
TEST_TIME 107 9016
TEST_TIME 108 8979
TEST_TIME 109 8950
TEST_TIME 110 8936
TEST_TIME 111 8909
TEST_TIME 112 8878
TEST_TIME 113 8869
TEST_TIME 114 8848
TEST_TIME 115 8830
TEST_TIME 116 8788
TEST_TIME 117 8751
TEST_TIME 118 8831
TEST_TIME 119 8790
TEST_TIME 120 8826

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2021 22:39:18 to 14-10-2021 22:39:22.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1455337 1455337 0 0.00 0 21160 6 0 0
doc_list_biud 1455337 1455337 0 0.00 0 20566 28 0 0
sp_add_invoice_to_stock 4838 4715 123 2.54 45 161699 19136 0 123
sp_cancel_adding_invoice 5636 5636 0 0.00 4 41237 815 0 0
sp_cancel_client_order 1602 1602 0 0.00 4 9132 373 0 0
sp_cancel_customer_reserve 550126 550126 0 0.00 1 18804 59 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 2 4986 85 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 2528 70 0 0
sp_cancel_supplier_invoice 6295 6286 9 0.14 1 19723 744 0 9
sp_cancel_supplier_order 409 409 0 0.00 6 939316 100441 0 0
sp_cancel_write_off 10247 10247 0 0.00 1 20744 125 0 0
sp_client_order 19215 19215 0 0.00 3 67224 397 0 0
sp_customer_reserve 427022 427022 0 0.00 0 34400 193 0 0
sp_fill_shopping_cart 29312 29312 0 0.00 1 25793 48 0 0
sp_fill_shopping_cart_clo_res 427017 427017 0 0.00 0 14865 14 0 0
sp_get_clo_for_invoice 4710 4710 0 0.00 0 21361 334 0 0
sp_kill_cost_storno 16926 16926 0 0.00 0 21112 59 0 0
sp_kill_qstorno_ret_qs2qd 1117194 1117194 0 0.00 0 20564 32 0 0
sp_kill_qty_storno 588211 588211 0 0.00 0 20566 69 0 0
sp_lock_selected_doc 19290 19290 0 0.00 0 6018 4 0 0
sp_make_cost_storno 25736 25736 0 0.00 0 20590 61 0 0
sp_multiply_rows_for_pdistr 25736 25736 0 0.00 0 8950 33 0 0
sp_multiply_rows_for_qdistr 19215 19215 0 0.00 0 64177 164 0 0
sp_pay_from_customer 6442 6442 0 0.00 4 17563 129 0 0
sp_pay_to_supplier 5389 5389 0 0.00 4 20157 143 0 0
sp_payment_common 11831 11831 0 0.00 1 10215 35 0 0
sp_qd_handle_on_cancel_clo 1602 1602 0 0.00 0 9126 341 0 0
sp_qd_handle_on_invoice_upd_sts 10345 10345 0 0.00 0 15257 370 0 0
sp_qd_handle_on_reserve_upd_sts 9196 9196 0 0.00 0 15582 63 0 0
sp_reserve_write_off 9196 9196 0 0.00 6 15792 207 0 0
sp_supplier_invoice 5076 5076 0 0.00 6 94895 3938 0 0
sp_supplier_order 5032 5032 0 0.00 5 35328 1412 0 0
srv_aggregate_perf_data 97 97 0 0.00 2500 238547 21308 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2310 161162 43925 0 0
srv_find_qd_qs_mism 1015345 1015345 0 0.00 0 19938 5 0 0
srv_make_invnt_saldo 529 529 0 0.00 59 103977 1464 0 0
srv_make_money_saldo 751 751 0 0.00 1 3261 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 226373 576732 349023 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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 4715 4715 0 0.00 0 11327 177 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 5866 117 0 0
t$perf-norm:sp_cancel_client_order 1602 1602 0 0.00 0 2779 14 0 0
t$perf-norm:sp_cancel_customer_reserve 609 609 0 0.00 0 1630 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 2841 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 3335 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 2543 20 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 18367 1494 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 1341 8 0 0
t$perf-norm:sp_client_order 19215 19215 0 0.00 0 7119 18 0 0
t$perf-norm:sp_customer_reserve 903 903 0 0.00 0 2159 11 0 0
t$perf-norm:sp_pay_from_customer 6442 6442 0 0.00 0 6572 11 0 0
t$perf-norm:sp_pay_to_supplier 5389 5389 0 0.00 0 4900 12 0 0
t$perf-norm:sp_reserve_write_off 9196 9196 0 0.00 0 5978 12 0 0
t$perf-norm:sp_supplier_invoice 5076 5076 0 0.00 0 5919 20 0 0
t$perf-norm:sp_supplier_order 5032 5032 0 0.00 0 3453 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 4309 55 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 1898 24 0 0
t$perf-norm:srv_make_money_saldo 751 751 0 0.00 0 4531 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9664 9418 246 2.54 0 11049 22 0 246
v_all_customers 34568 34568 0 0.00 0 18 1 0 0
v_all_suppliers 27774 27774 0 0.00 0 23 1 0 0
v_all_wares 38430 38430 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 3062 15 0 0
v_cancel_client_order 3204 3204 0 0.00 0 6169 9 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 1946 5 0 0
v_cancel_customer_reserve 1218 1218 0 0.00 0 2540 9 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2520 6 0 0
v_cancel_supplier_invoice 846 828 18 2.12 0 6372 26 0 18
v_cancel_supplier_order 818 818 0 0.00 0 8674 17 0 0
v_cancel_write_off 1824 1824 0 0.00 0 6393 17 0 0
v_max_id_clo_ord 5032 5032 0 0.00 0 482 2 0 0
v_max_id_clo_res 903 903 0 0.00 0 2893 8 0 0
v_max_id_ord_sup 5065 5065 0 0.00 0 3627 5 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 4579 5 0 0
v_max_non_paid_realizn 3552 3552 0 0.00 0 4571 7 0 0
v_min_id_clo_ord 5032 5032 0 0.00 0 8956 10 0 0
v_min_id_clo_res 903 903 0 0.00 0 2635 12 0 0
v_min_id_ord_sup 5065 5065 0 0.00 0 4935 7 0 0
v_min_non_paid_invoice 5389 5389 0 0.00 0 6600 24 0 0
v_min_non_paid_realizn 6442 6442 0 0.00 0 10212 30 0 0
v_our_firm 9642 9642 0 0.00 0 19 1 0 0
v_reserve_write_off 18392 18392 0 0.00 0 6484 9 0 0
x_lock_depdocs_on_canc_invoice 11508 11508 0 0.00 0 17090 129 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 5149 372 0 0
x_make_qstorno_1000_1200 5032 5032 0 0.00 2 35314 1314 0 0
x_make_qstorno_1200_2000 5076 5076 0 0.00 2 71165 3761 0 0
x_make_qstorno_2100_3300 427022 427022 0 0.00 0 34398 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2021 22:39:23 to 14-10-2021 22:39:27.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:55 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:56 40619 8192000000 9909428096 10222514176 989190080 10.774 100 93 186 0 1020892160 6829024 24873056
20:10:42 33210 8192000000 9943052800 10266038272 1020321600 11.075 100 90 180 0 1052532992 8661072 23550320
20:12:18 19817 8192000000 9989582976 10327928832 1070646048 11.558 100 75 150 0 1098504768 9294928 18563792
20:13:43 11535 8192000000 10063369328 10420391936 1146732224 12.279 100 72 144 0 1173959024 10656768 16570032
20:14:58 9755 8192000000 10123482912 10491817984 1205669472 12.829 100 76 152 1 1236419664 13251216 17498976
20:16:14 15826 8192000000 10142277328 10528530432 1243178064 13.175 100 68 136 1 1270860336 12282848 15399424
20:17:32 2103 8192000000 10193337568 10574204928 1276464992 13.481 100 86 172 1 1307749216 11943264 19340960
20:18:37 2358 8192000000 10213945424 10597281792 1293997296 13.641 100 88 176 0 1327454528 13556928 19900304
20:19:40 1681 8192000000 10214411792 10606723072 1304233488 13.734 100 70 140 1 1331187824 11118592 15835744
20:20:44 2670 8192000000 10200249664 10608963584 1309007072 13.777 100 42 84 3 1326841520 8117200 9717248
20:21:51 7613 8192000000 10203282384 10618769408 1319019360 13.868 100 20 40 0 1329311584 6014224 4278000
20:23:00 9715 8192000000 10214635264 10629582848 1326901024 13.939 100 29 58 2 1340321088 5681056 7739008
20:24:13 50895 8192000000 10223534064 10646503424 1333229792 13.996 100 26 52 1 1343766128 4984528 5551808
20:26:07 6538 8192000000 10237478032 10656673792 1327262096 13.942 99 31 62 0 1344761024 10128000 7370928
20:27:15 7485 8192000000 10222389888 10635186176 1305659328 13.747 98 24 48 0 1316295440 4781680 5854432
20:28:23 12920 8192000000 10194015936 10602467328 1271064208 13.431 97 34 68 0 1285844896 6442864 8337824
20:29:38 15709 8192000000 10134327136 10542465024 1174794400 12.542 92 19 38 2 1184066992 5033488 4239104
20:30:54 6192 8192000000 10138194384 10532827136 1212154816 12.889 97 28 54 1 1224407040 5950560 6301664
20:32:01 8989 8192000000 10145913728 10538102784 1239567200 13.142 99 24 48 0 1250882256 5982672 5332384
20:33:13 17113 8192000000 10168612992 10570870784 1267301920 13.397 99 31 62 0 1280443152 5042464 8098768
20:34:39 11500 8192000000 10174168160 10588659712 1285338272 13.562 100 30 58 2 1298774464 7121696 6314496
20:35:52 10190 8192000000 10196253680 10600968192 1296151136 13.660 100 28 56 5 1308480928 5253056 7076736
20:37:03 7746 8192000000 10202666688 10617466880 1311975712 13.804 100 13 26 1 1319145904 4495936 2674256
20:38:13 30283 8192000000 10239041792 10652622848 1338288768 14.042 100 41 81 2 1353554352 6130672 9134912
20:39:56 25889 8192000000 10266788896 10681561088 1356295904 14.204 100 31 60 2 1373601264 10314880 6990480
20:41:38 46012 8192000000 10282461968 10702848000 1371257760 14.338 100 34 66 1 1394848688 15709152 7881776
20:43:32 6004 8192000000 10298216592 10717143040 1381487536 14.430 100 49 96 3 1410404752 18213008 10704208
20:44:44 7098 8192000000 10316515712 10731536384 1390595056 14.511 100 74 146 4 1427527344 19591776 17340512
20:45:56 7568 8192000000 10318307680 10741063680 1398026288 14.577 100 58 116 0 1432514320 20137376 14350656
20:47:11 13974 8192000000 10346853152 10767044608 1417121552 14.747 100 64 128 2 1456924656 22992336 16810768
20:48:31 13267 8192000000 10328966320 10741964800 1385625520 14.467 98 61 121 3 1423679088 22960560 15093008
20:49:54 2309 8192000000 10345215152 10758107136 1399281296 14.589 100 82 164 1 1446181552 27724944 19175312
20:51:02 3098 8192000000 10329349488 10737729536 1379690208 14.414 99 62 121 2 1420527552 25885472 14951872
20:52:06 19737 8192000000 10282804288 10706771968 1358648144 14.225 99 47 93 1 1392552544 22417888 11486512
20:53:32 2641 8192000000 10283117520 10692030464 1342557040 14.080 99 75 151 1 1384190912 23490848 18143024
20:54:38 5957 8192000000 10271665200 10696032256 1327278944 13.943 98 43 86 1 1366957984 28716784 10962256
20:55:59 7878 8192000000 10305710992 10710585344 1366536400 14.296 100 68 135 0 1401647008 18842720 16267888
20:57:09 9088 8192000000 10271200112 10698698752 1342627840 14.081 99 28 55 2 1368727184 19202992 6896352
20:58:43 3780 8192000000 10291921520 10710208512 1351525616 14.161 99 57 113 1 1390458032 24106224 14826192
20:59:51 11722 8192000000 10281192176 10707050496 1355301680 14.195 100 34 66 0 1386337600 22468512 8567408
21:01:10 11262 8192000000 10313227920 10743410688 1377631472 14.395 100 46 90 2 1422891680 33789264 11470944
21:02:26 6716 8192000000 10293846960 10718162944 1352872688 14.173 99 36 72 1 1397960160 35870912 9216560
21:03:36 11804 8192000000 10301387296 10730684416 1364507040 14.278 100 24 48 0 1404604544 33194608 6902896
21:04:58 21735 8192000000 10305006064 10735046656 1366566704 14.296 100 33 67 0 1412656592 37511568 8578320
21:06:37 19839 8192000000 10306962704 10739445760 1365383264 14.286 100 29 56 0 1414518784 41347280 7788240
21:08:14 24117 8192000000 10310640528 10749042688 1378877712 14.407 100 29 58 2 1423389920 37429472 7082736
21:09:40 38316 8192000000 10308237968 10765639680 1377431792 14.394 100 18 36 0 1422969200 40778192 4759216
21:11:57 13594 8192000000 10301638160 10730147840 1375020992 14.372 99 48 95 2 1398363296 13115552 10226752
21:13:11 91086 8192000000 10297241696 10757206016 1393217696 14.535 100 9 18 0 1406770416 11391792 2160928
21:15:43 26648 8192000000 10306455040 10753617920 1402816320 14.620 100 21 43 0 1415307648 7345584 5145744
21:17:15 56085 8192000000 10303658384 10766716928 1402657792 14.619 100 20 40 0 1422028544 14418560 4952192
21:19:29 29229 8192000000 10301869856 10748977152 1394895616 14.550 100 46 92 2 1413791888 7563296 11332976
21:21:00 65088 8192000000 10332860288 10770833408 1403540256 14.627 100 23 47 0 1426818496 17965168 5313072
21:23:45 81139 8192000000 10298962624 10762465280 1397955728 14.577 100 6 12 0 1413669472 14046640 1667104
21:26:08 29202 8192000000 10323219280 10773008384 1410971472 14.693 100 46 93 0 1433189392 11370912 10847008
21:27:42 70720 8192000000 10319643008 10787299328 1389917600 14.505 98 20 40 0 1412400432 17807024 4675808
21:30:04 66580 8192000000 10316441216 10777079808 1418784240 14.762 100 13 26 0 1437688016 15499264 3404512
21:32:38 82064 8192000000 10293016096 10751262720 1357302144 14.213 96 19 38 1 1369255168 7636176 4316848
21:35:07 51547 8192000000 10302989984 10760519680 1388203024 14.490 99 17 35 2 1403649968 11124576 4322368
21:37:07 61276 8192000000 10301106304 10761838592 1380090608 14.417 98 15 29 1 1396648784 13147232 3410944
21:39:29 34963 8192000000 10311615472 10771574784 1409038368 14.675 100 11 21 0 1419332096 8377648 1916080
21:41:17 76816 8192000000 10276386256 10714660864 1280152992 13.514 91 24 48 2 1299560208 14196272 5210944
21:43:37 55990 8192000000 10263566144 10712690688 1322843984 13.902 96 15 30 2 1338883680 12475280 3564416
21:45:37 12652 8192000000 10300354560 10728517632 1362230144 14.257 99 53 104 4 1384054128 9049312 12774672
21:46:51 51305 8192000000 10288639024 10747453440 1380432704 14.420 100 34 68 1 1404499856 16571920 7495232
21:49:23 85550 8192000000 10302816816 10743324672 1354389472 14.187 97 26 51 1 1371503584 11475984 5638128
21:51:49 90756 8192000000 10293422736 10763931648 1362317840 14.258 98 10 20 0 1384230656 19626880 2285936
21:54:19 82811 8192000000 10305572848 10757042176 1382842592 14.442 99 36 72 0 1402117216 11682496 7592128
21:56:44 4478 8192000000 10322256864 10773372928 1401752208 14.611 100 42 82 2 1429200144 18096720 9351216
21:57:50 81790 8192000000 10286715824 10747334656 1361021056 14.247 97 17 33 0 1380359216 15030480 4307680
22:00:44 64791 8192000000 10286430272 10744434688 1358081600 14.220 97 27 54 2 1375282224 10986752 6213872
22:02:48 38812 8192000000 10273439664 10732052480 1357663536 14.216 98 9 18 0 1373418544 13435360 2319648
22:04:32 78458 8192000000 10257226208 10705821696 1322242544 13.897 96 18 36 1 1333004336 7275312 3486480
22:07:00 67040 8192000000 10247054160 10701492224 1354366656 14.187 99 15 30 0 1365766592 8254384 3145552
22:09:10 72385 8192000000 10271467072 10723971072 1338500528 14.044 97 11 21 1 1347343920 6707376 2136016
22:11:27 70316 8192000000 10307191408 10758590464 1371131072 14.337 98 23 46 1 1394270144 17538256 5600816
22:14:09 43971 8192000000 10307518528 10754072576 1358747632 14.226 97 16 32 1 1379459712 16621968 4090112
22:16:26 60998 8192000000 10324530576 10769072128 1388380736 14.491 99 28 56 1 1405532816 10431104 6720976
22:18:28 51663 8192000000 10328294096 10776772608 1399688656 14.592 99 35 67 1 1418889600 11917520 7283424
22:20:26 81987 8192000000 10332482160 10782089216 1396639888 14.565 99 17 34 2 1413131456 12832384 3659184
22:22:49 161159 8192000000 10324850368 10780213248 1360834640 14.245 96 15 30 0 1389123760 24762416 3526704
22:26:39 44247 8192000000 10314804608 10783571968 1409572000 14.680 100 18 36 0 1430453168 15629136 5252032
22:28:23 102995 8192000000 10288543216 10755137536 1375334432 14.375 98 7 14 0 1386400128 9293632 1772064
22:31:06 120522 8192000000 10265836272 10727428096 1305453632 13.745 94 17 34 1 1320740112 11503584 3782896
22:34:07 44516 8192000000 10274399296 10726457344 1346140640 14.113 98 11 22 0 1363510656 14973520 2396496
22:36:07 39149 8192000000 10289815904 10729705472 1353027712 14.175 98 29 58 0 1367282976 6739168 7516096
22:37:49 16652 8192000000 9071710384 9300496384 239922272 2.845 18 1 2 0 242409184 2444560 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2021 22:39:28 to 14-10-2021 22:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 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 14-10-2021 22:39:44 to 14-10-2021 22:39:46.

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          232155
MON$OLDEST_ACTIVE               232156
MON$OLDEST_SNAPSHOT             232156
MON$NEXT_TRANSACTION            232156
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                       2024320
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:328: 
:329: mscs-test-161.budzdorov.ru    Thu Oct 14 22:38:07 2021
:330:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:331: 
:332: 
:333: mscs-test-161.budzdorov.ru    Thu Oct 14 22:38:07 2021
:334:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:335: 
:336: 
:337: mscs-test-161.budzdorov.ru    Thu Oct 14 22:38:08 2021
:338:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:339: 
--- end of diff output ---

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

14.10.21 22:39:47. 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-10-14 22:39:50 No severe PSQL-related problems occured
14.10.21 22:39:51. End of report.