Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.05.2022 19:39
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16616768 kB
MemAvailable 31453896 kB
Buffers 0 kB
Cached 15071640 kB
SwapCached 2608 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.10
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 2022-05-10 19:38:16
DTS_END                         2022-05-10 19:38:16.4920
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/53928
TRN_ID                          240211
ATT_ID                          708
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: 9097.55 22441 1091706
customer order: creation 175.33 355 21039
customer order: refuse 15.18 301 1822
order to supplier: creation 45.23 1331 5428
order to supplier: removal 3.46 102168 415
invoice (draft): creation 46.23 3534 5547
invoice (draft): removal 53.78 807 6454
invoice accept: apply 40.13 17483 4816
invoice accept: cancel 48.40 761 5808
customer reserve: creation 3714.80 172 445776
customer reserve: removal 4658.88 59 559066
realization accept: apply 85.23 201 10227
realization accept: cancel 80.28 103 9634
payment from customer: creation 60.93 137 7312
payment from customer: removal 5.66 67 679
payment to supplier: creation 48.58 160 5830
payment to supplier: removal 3.53 87 423
service: total inventory turnovers 4.88 962 585
service: total monetary turnovers 6.32 22 758
service: aggregate perf. data 0.69 28111 83
service: refresh index statistics 0.03 291990 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-05-2022 19:39:23 to 10-05-2022 19:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2634
TEST_TIME 2 4111
TEST_TIME 3 3559
TEST_TIME 4 4094
TEST_TIME 5 4321
TEST_TIME 6 4459
TEST_TIME 7 4339
TEST_TIME 8 4437
TEST_TIME 9 4870
TEST_TIME 10 5884
TEST_TIME 11 6208
TEST_TIME 12 6377
TEST_TIME 13 6473
TEST_TIME 14 6526
TEST_TIME 15 6999
TEST_TIME 16 7147
TEST_TIME 17 7470
TEST_TIME 18 7723
TEST_TIME 19 7753
TEST_TIME 20 8011
TEST_TIME 21 8356
TEST_TIME 22 8219
TEST_TIME 23 8807
TEST_TIME 24 9320
TEST_TIME 25 9589
TEST_TIME 26 9653
TEST_TIME 27 10787
TEST_TIME 28 11011
TEST_TIME 29 10687
TEST_TIME 30 10539
TEST_TIME 31 10372
TEST_TIME 32 10171
TEST_TIME 33 10059
TEST_TIME 34 10049
TEST_TIME 35 10023
TEST_TIME 36 9894
TEST_TIME 37 9719
TEST_TIME 38 9567
TEST_TIME 39 9689
TEST_TIME 40 9645
TEST_TIME 41 9497
TEST_TIME 42 9415
TEST_TIME 43 9388
TEST_TIME 44 9290
TEST_TIME 45 9314
TEST_TIME 46 9334
TEST_TIME 47 9299
TEST_TIME 48 9276
TEST_TIME 49 9466
TEST_TIME 50 9405
TEST_TIME 51 9347
TEST_TIME 52 9473
TEST_TIME 53 9472
TEST_TIME 54 9431
TEST_TIME 55 9413
TEST_TIME 56 9511
TEST_TIME 57 9486
TEST_TIME 58 9539
TEST_TIME 59 9389
TEST_TIME 60 9332
TEST_TIME 61 9381
TEST_TIME 62 9306
TEST_TIME 63 9287
TEST_TIME 64 9228
TEST_TIME 65 9156
TEST_TIME 66 9181
TEST_TIME 67 9103
TEST_TIME 68 9044
TEST_TIME 69 9065
TEST_TIME 70 9037
TEST_TIME 71 9034
TEST_TIME 72 9022
TEST_TIME 73 9139
TEST_TIME 74 9106
TEST_TIME 75 9093
TEST_TIME 76 9185
TEST_TIME 77 9217
TEST_TIME 78 9190
TEST_TIME 79 9253
TEST_TIME 80 9236
TEST_TIME 81 9266
TEST_TIME 82 9310
TEST_TIME 83 9218
TEST_TIME 84 9193
TEST_TIME 85 9203
TEST_TIME 86 9174
TEST_TIME 87 9116
TEST_TIME 88 9105
TEST_TIME 89 9067
TEST_TIME 90 9039
TEST_TIME 91 9060
TEST_TIME 92 9043
TEST_TIME 93 9034
TEST_TIME 94 9161
TEST_TIME 95 9131
TEST_TIME 96 9170
TEST_TIME 97 9139
TEST_TIME 98 9206
TEST_TIME 99 9234
TEST_TIME 100 9256
TEST_TIME 101 9249
TEST_TIME 102 9277
TEST_TIME 103 9279
TEST_TIME 104 9340
TEST_TIME 105 9315
TEST_TIME 106 9255
TEST_TIME 107 9219
TEST_TIME 108 9183
TEST_TIME 109 9175
TEST_TIME 110 9113
TEST_TIME 111 9115
TEST_TIME 112 9122
TEST_TIME 113 9097
TEST_TIME 114 9099
TEST_TIME 115 9141
TEST_TIME 116 9168
TEST_TIME 117 9165
TEST_TIME 118 9158
TEST_TIME 119 9149
TEST_TIME 120 9102

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-05-2022 19:39:26 to 10-05-2022 19:39:30.

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 1507018 1507018 0 0.00 0 31509 6 0 0
doc_list_biud 1507018 1507018 0 0.00 0 64333 28 0 0
sp_add_invoice_to_stock 4903 4816 87 1.77 36 152353 17484 0 87
sp_cancel_adding_invoice 5808 5808 0 0.00 4 56132 762 0 0
sp_cancel_client_order 1822 1822 0 0.00 4 10110 302 0 0
sp_cancel_customer_reserve 559066 559066 0 0.00 1 64334 59 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 4092 68 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 6067 87 0 0
sp_cancel_supplier_invoice 6461 6454 7 0.10 2 28431 807 0 7
sp_cancel_supplier_order 415 415 0 0.00 7 1122228 102168 0 0
sp_cancel_write_off 9634 9634 0 0.00 1 10124 103 0 0
sp_client_order 21039 21039 0 0.00 4 19968 355 0 0
sp_customer_reserve 445776 445776 0 0.00 0 43222 172 0 0
sp_fill_shopping_cart 32014 32014 0 0.00 1 12999 42 0 0
sp_fill_shopping_cart_clo_res 445767 445767 0 0.00 0 14833 14 0 0
sp_get_clo_for_invoice 4805 4805 0 0.00 0 35402 324 0 0
sp_kill_cost_storno 16544 16544 0 0.00 0 18165 54 0 0
sp_kill_qstorno_ret_qs2qd 1134635 1134635 0 0.00 0 64332 32 0 0
sp_kill_qty_storno 598231 598231 0 0.00 0 64333 70 0 0
sp_lock_selected_doc 20926 20926 0 0.00 0 6831 7 0 0
sp_make_cost_storno 28174 28174 0 0.00 0 31504 68 0 0
sp_multiply_rows_for_pdistr 28174 28174 0 0.00 0 16182 34 0 0
sp_multiply_rows_for_qdistr 21039 21039 0 0.00 0 16749 163 0 0
sp_pay_from_customer 7312 7312 0 0.00 4 16196 137 0 0
sp_pay_to_supplier 5830 5830 0 0.00 4 16406 161 0 0
sp_payment_common 13142 13142 0 0.00 1 12840 38 0 0
sp_qd_handle_on_cancel_clo 1822 1822 0 0.00 0 10102 274 0 0
sp_qd_handle_on_invoice_upd_sts 10613 10613 0 0.00 0 30489 341 0 0
sp_qd_handle_on_reserve_upd_sts 10227 10227 0 0.00 0 14254 60 0 0
sp_reserve_write_off 10227 10227 0 0.00 6 26745 202 0 0
sp_supplier_invoice 5547 5547 0 0.00 7 79853 3534 0 0
sp_supplier_order 5428 5428 0 0.00 5 32008 1331 0 0
srv_aggregate_perf_data 83 83 0 0.00 2316 401538 28112 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1968 150236 45691 0 0
srv_find_qd_qs_mism 1045506 1045506 0 0.00 0 12586 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 23 24704 963 0 0
srv_make_money_saldo 758 758 0 0.00 1 909 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 180372 497244 291990 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4816 4816 0 0.00 0 24720 175 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 11680 129 0 0
t$perf-norm:sp_cancel_client_order 1822 1822 0 0.00 0 6498 18 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 4292 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 2088 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 1764 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 6384 35 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 24566 1710 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1483 6 0 0
t$perf-norm:sp_client_order 21039 21039 0 0.00 0 16404 20 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 1396 7 0 0
t$perf-norm:sp_pay_from_customer 7312 7312 0 0.00 0 7505 11 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 11036 14 0 0
t$perf-norm:sp_reserve_write_off 10227 10227 0 0.00 0 10872 17 0 0
t$perf-norm:sp_supplier_invoice 5547 5547 0 0.00 0 5236 16 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 6331 18 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 2339 39 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 2851 28 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 4268 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 4 0 0
v_add_invoice_to_stock 9784 9610 174 1.77 0 5732 21 0 174
v_all_customers 38472 38472 0 0.00 0 19 1 0 0
v_all_suppliers 30540 30540 0 0.00 0 18 1 0 0
v_all_wares 42078 42078 0 0.00 0 119 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 5061 15 0 0
v_cancel_client_order 3644 3644 0 0.00 0 4786 6 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 3388 11 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 2421 7 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 3260 12 0 0
v_cancel_supplier_invoice 816 802 14 1.71 0 3981 18 0 14
v_cancel_supplier_order 830 830 0 0.00 0 2644 7 0 0
v_cancel_write_off 1996 1996 0 0.00 0 10106 19 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 1350 2 0 0
v_max_id_clo_res 986 986 0 0.00 0 2504 18 0 0
v_max_id_ord_sup 5547 5547 0 0.00 0 6190 7 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 520 3 0 0
v_max_non_paid_realizn 3897 3897 0 0.00 0 12707 9 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 4153 8 0 0
v_min_id_clo_res 986 986 0 0.00 0 5347 16 0 0
v_min_id_ord_sup 5547 5547 0 0.00 0 11994 10 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 12799 34 0 0
v_min_non_paid_realizn 7312 7312 0 0.00 0 12698 26 0 0
v_our_firm 10436 10436 0 0.00 0 13 1 0 0
v_reserve_write_off 20454 20454 0 0.00 0 8493 8 0 0
x_lock_depdocs_on_canc_invoice 11861 11861 0 0.00 0 23740 123 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 10710 386 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 1 31994 1249 0 0
x_make_qstorno_1200_2000 5547 5547 0 0.00 3 77642 3379 0 0
x_make_qstorno_2100_3300 445776 445776 0 0.00 0 37292 155 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-05-2022 19:39:30 to 10-05-2022 19:39:35.

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
17:08:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:21 18606 8192000000 9888339728 10192252928 973327136 10.619 100 89 177 1 1001588192 5096288 23164768
17:10:47 19914 8192000000 9937545248 10253451264 1013168880 11.006 100 95 190 0 1045992256 7738816 25084560
17:12:17 13664 8192000000 9972945568 10304663552 1062460896 11.480 100 74 148 1 1093945376 13257760 18226720
17:13:42 18946 8192000000 10039606832 10389504000 1128427968 12.107 100 72 144 1 1156525968 10559776 17538224
17:15:05 8856 8192000000 10103581744 10460725248 1186464960 12.650 100 86 172 1 1219568336 13032192 20071184
17:16:15 4793 8192000000 10136050992 10505728000 1226469376 13.021 100 90 179 0 1259469952 13437696 19562880
17:17:22 3701 8192000000 10169817232 10548998144 1258811536 13.319 100 84 169 2 1291040096 13271776 18956784
17:18:28 1812 8192000000 10203330112 10585567232 1289345056 13.598 100 88 173 0 1322532064 13068768 20118240
17:19:31 1095 8192000000 10223585552 10608713728 1312288672 13.807 100 89 178 1 1344483712 12529280 19665760
17:20:33 1820 8192000000 10236613632 10624606208 1315754368 13.838 100 82 165 0 1346929088 13232224 17942496
17:21:37 21289 8192000000 10212814656 10622246912 1320291664 13.879 100 33 63 1 1332955472 4203360 8460448
17:22:59 7383 8192000000 10211425680 10628100096 1325815904 13.929 100 19 38 1 1336544112 6149168 4579040
17:24:07 7015 8192000000 10215825824 10635169792 1332651008 13.991 100 27 54 1 1345229792 6156144 6422640
17:25:20 52207 8192000000 10224624368 10646634496 1337266608 14.033 100 40 80 1 1352940304 6350448 9323248
17:27:15 4684 8192000000 10247566688 10661113856 1342959344 14.084 100 35 70 1 1357208544 6231600 8017600
17:28:20 4683 8192000000 10225283680 10645041152 1335580896 14.018 100 20 40 1 1346150400 5503280 5066224
17:29:29 23355 8192000000 10167515648 10579017728 1240241088 13.148 95 35 72 4 1253982064 5280192 8460784
17:31:16 5600 8192000000 10143027168 10544963584 1236384608 13.113 98 30 59 5 1249441248 5680320 7376320
17:32:23 6931 8192000000 10142385008 10540695552 1240258272 13.149 99 35 68 2 1254654224 7076960 7318992
17:33:30 8435 8192000000 10159418208 10560167936 1249780736 13.236 99 22 44 4 1259818736 5236208 4801792
17:34:39 50421 8192000000 10196980560 10589401088 1280075360 13.514 100 61 124 0 1299557984 4729568 14753056
17:36:34 4291 8192000000 10191316608 10602283008 1298545696 13.682 100 35 70 0 1313675904 6614176 8516032
17:37:42 9211 8192000000 10199107104 10613989376 1306303424 13.753 100 30 60 1 1319123408 5170560 7649424
17:39:02 11989 8192000000 10236953360 10649677824 1334999296 14.012 100 28 56 0 1349389584 8022000 6368288
17:40:20 45633 8192000000 10258451200 10676023296 1351108192 14.157 100 43 86 3 1374772336 13779904 9884240
17:42:15 35940 8192000000 10277493840 10696429568 1363658816 14.270 100 38 76 1 1389293248 17081984 8552448
17:43:59 24447 8192000000 10288915552 10714517504 1373739536 14.361 100 21 42 1 1397641360 18862272 5039552
17:46:04 11483 8192000000 10326876672 10747027456 1396985328 14.568 100 48 95 3 1435584208 26993424 11605456
17:47:25 5322 8192000000 10338425936 10763264000 1407879568 14.665 100 53 105 2 1451200704 30055648 13265488
17:48:34 1967 8192000000 10346279728 10769031168 1410843168 14.691 100 60 120 1 1456662448 31453472 14365808
17:49:41 3035 8192000000 10364016944 10788798464 1428882880 14.851 100 69 138 0 1476540192 30713216 16944096
17:50:52 3117 8192000000 10365387920 10789425152 1413613712 14.716 99 69 138 3 1465206000 33457584 18134704
17:51:58 6424 8192000000 10368268624 10791526400 1421377984 14.785 100 62 121 2 1473980864 37421872 15181008
17:53:10 2447 8192000000 10364400688 10779811840 1400972304 14.604 99 81 160 3 1463683472 43048688 19662480
17:54:17 4672 8192000000 10347006928 10766368768 1399321072 14.589 100 57 114 1 1451912352 37267760 15323520
17:55:25 1980 8192000000 10332892976 10734055424 1371373888 14.339 99 86 172 1 1431613120 38600000 21639232
17:56:33 8094 8192000000 10322658720 10736865280 1366662992 14.297 99 46 91 1 1418491712 39015488 12813232
17:57:46 6808 8192000000 10333257136 10740056064 1377298304 14.392 100 75 147 1 1436158624 40131504 18728816
17:58:58 10936 8192000000 10320810624 10744827904 1357156048 14.212 98 47 94 2 1415245696 45640016 12449632
18:00:11 10363 8192000000 10332449408 10754539520 1378681984 14.405 100 41 82 0 1432990576 43123872 11184720
18:01:29 14355 8192000000 10313176880 10741080064 1360313968 14.240 99 38 76 0 1418894272 47825552 10754752
18:02:46 8123 8192000000 10314119552 10733785088 1369806032 14.325 100 36 69 3 1411229792 31798992 9624768
18:03:56 24103 8192000000 10291832048 10720624640 1352645952 14.171 99 24 47 1 1395168832 35725296 6797584
18:05:40 32027 8192000000 10263792928 10713645056 1334713056 14.010 98 16 32 0 1363730592 25103392 3914144
18:07:13 36498 8192000000 10265073472 10712477696 1362195760 14.257 100 30 59 2 1375002304 5931120 6875424
18:08:55 33098 8192000000 10287640832 10710425600 1358771808 14.226 99 50 100 3 1376722176 5367632 12582736
18:10:31 80345 8192000000 10284731104 10740150272 1370456752 14.331 99 25 50 1 1387292032 11454576 5380704
18:13:23 21267 8192000000 10267555280 10722095104 1347805632 14.128 98 12 24 0 1362311536 11905824 2600080
18:14:47 54932 8192000000 10282090464 10732027904 1365011072 14.282 99 15 30 0 1383847536 15380176 3456288
18:16:42 112739 8192000000 10285611696 10749702144 1351721312 14.163 98 10 20 1 1369388304 15070448 2596544
18:19:48 21654 8192000000 10297832080 10742140928 1386115616 14.471 100 49 102 2 1409460928 11625840 11719472
18:21:15 66690 8192000000 10288913920 10753601536 1384599264 14.458 100 9 18 0 1402495072 15711168 2184640
18:23:28 27678 8192000000 10316646464 10755526656 1390715600 14.512 100 31 60 1 1410811808 13015056 7081152
18:25:07 71550 8192000000 10287063792 10741899264 1363556944 14.269 98 22 44 0 1380504880 11822352 5125584
18:27:35 70878 8192000000 10312095360 10758094848 1384868352 14.460 99 13 26 0 1399938784 11931584 3138848
18:30:02 56055 8192000000 10298537808 10765127680 1391934848 14.523 100 23 46 1 1414884160 17523104 5426208
18:32:00 68980 8192000000 10289784512 10739671040 1334862448 14.011 96 15 30 1 1350373264 12295024 3215792
18:34:13 103492 8192000000 10303054864 10755399680 1348930384 14.138 98 12 24 0 1371176656 19606544 2639728
18:37:03 79761 8192000000 10264408096 10710294528 1316843616 13.848 95 25 51 0 1333265968 11096624 5325728
18:39:25 41821 8192000000 10255982624 10712199168 1334835696 14.011 98 17 32 1 1349140528 11191280 3113552
18:41:08 38247 8192000000 10272423504 10724909056 1365921968 14.290 100 32 64 4 1382122272 7971936 8228368
18:42:58 47788 8192000000 10268022624 10728714240 1352774752 14.172 98 11 22 0 1363167984 7824432 2568800
18:44:46 39724 8192000000 10260675488 10725429248 1366520976 14.296 100 11 22 0 1378945152 9827584 2596592
18:46:49 62531 8192000000 10243262304 10704891904 1319458608 13.872 97 6 12 0 1329448608 8715152 1274848
18:48:57 29660 8192000000 10265923664 10709106688 1346006400 14.112 99 44 86 0 1364234448 7453488 10774560
18:50:31 58162 8192000000 10261920832 10717663232 1339754608 14.055 98 11 22 1 1355005088 12718144 2532336
18:52:31 40303 8192000000 10277568400 10710147072 1337080960 14.031 98 33 66 0 1353783024 9223872 7478192
18:54:37 48246 8192000000 10260948736 10710331392 1318059136 13.859 97 11 21 0 1327975024 7705792 2210096
18:56:27 105158 8192000000 10253836704 10699075584 1284109568 13.551 95 18 36 0 1298187216 10463440 3614208
18:59:13 34327 8192000000 10279506736 10718302208 1353726064 14.181 100 23 46 1 1368790560 9911680 5152816
19:00:54 150236 8192000000 10275970288 10731065344 1369786128 14.325 100 22 42 1 1391613040 17385568 4441344
19:04:25 65201 8192000000 10275948096 10741915648 1381008896 14.426 100 13 25 1 1396004608 12118256 2877456
19:07:02 38368 8192000000 10258367296 10708930560 1349112720 14.139 99 12 23 1 1359161760 6560208 3488832
19:09:01 71171 8192000000 10268832688 10740084736 1360879680 14.245 99 5 10 0 1375236944 13508368 848896
19:11:14 71770 8192000000 10287062752 10753998848 1375763280 14.379 99 14 28 2 1393956560 14881424 3311856
19:13:30 75871 8192000000 10303357600 10770739200 1378192448 14.400 99 19 38 0 1398828960 16269776 4366736
19:15:49 64274 8192000000 10303363072 10761240576 1391379136 14.518 100 8 16 0 1411128304 17786272 1962896
19:17:56 90146 8192000000 10305377520 10744467456 1356984576 14.210 98 23 46 0 1382072656 20181104 4906976
19:20:29 62239 8192000000 10284592128 10721345536 1343584272 14.090 97 29 58 2 1365316128 15546336 6185520
19:22:33 60888 8192000000 10300698128 10742546432 1348069376 14.130 97 25 50 0 1372496816 18531728 5895712
19:24:35 113683 8192000000 10278937584 10732240896 1366224432 14.293 98 29 58 0 1379442608 6564672 6653504
19:27:30 73002 8192000000 10275381568 10741346304 1378797616 14.406 100 12 24 1 1392433792 10775456 2860720
19:30:23 68902 8192000000 10270086608 10715738112 1348076880 14.130 98 10 20 0 1360317312 9554976 2685456
19:32:34 92298 8192000000 10284642832 10745270272 1348681664 14.136 98 9 18 0 1366082656 15173568 2227424
19:35:11 25082 8192000000 10277182368 10730639360 1372858976 14.353 100 14 28 0 1382707472 6914752 2933744
19:36:42 30514 8192000000 10263353744 10704015360 1321508416 13.890 97 33 66 0 1336496256 7779504 7208336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-05-2022 19:39:36 to 10-05-2022 19:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-05-2022 19:39:52 to 10-05-2022 19:39:54.

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          237481
MON$OLDEST_ACTIVE               241047
MON$OLDEST_SNAPSHOT             241047
MON$NEXT_TRANSACTION            241047
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                       2019008
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:115: 
:116: mscs-test-161.budzdorov.ru    Tue May 10 19:38:19 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Tue May 10 19:38:20 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Tue May 10 19:38:24 2022
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:126: 
--- end of diff output ---

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

10.05.22 19:39:55. 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
2022-05-10 19:39:57 No severe PSQL-related problems occured
10.05.22 19:39:59. End of report.