Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2021 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 16802476 kB
MemAvailable 31391276 kB
Buffers 0 kB
Cached 14906652 kB
SwapCached 436 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.9
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-12-28 19:38:01
DTS_END                         2021-12-28 19:38:01.9140
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/43824
TRN_ID                          229261
ATT_ID                          681
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: 9000.50 31944 1080060
customer order: creation 159.28 372 19113
customer order: refuse 14.12 334 1694
order to supplier: creation 43.70 1277 5244
order to supplier: removal 3.33 96625 399
invoice (draft): creation 44.07 3509 5288
invoice (draft): removal 52.85 761 6342
invoice accept: apply 39.30 17369 4716
invoice accept: cancel 47.32 762 5678
customer reserve: creation 3696.30 168 443556
customer reserve: removal 4616.68 53 554002
realization accept: apply 77.38 174 9286
realization accept: cancel 83.58 115 10030
payment from customer: creation 56.87 110 6824
payment from customer: removal 5.26 89 631
payment to supplier: creation 45.37 132 5444
payment to supplier: removal 3.64 68 437
service: total inventory turnovers 4.61 1069 553
service: total monetary turnovers 6.05 28 726
service: aggregate perf. data 0.78 20592 93
service: refresh index statistics 0.03 495263 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2021 19:39:44 to 28-12-2021 19:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4097
TEST_TIME 2 5277
TEST_TIME 3 3914
TEST_TIME 4 3421
TEST_TIME 5 3303
TEST_TIME 6 3508
TEST_TIME 7 3568
TEST_TIME 8 4362
TEST_TIME 9 4806
TEST_TIME 10 5266
TEST_TIME 11 5371
TEST_TIME 12 5848
TEST_TIME 13 6115
TEST_TIME 14 6713
TEST_TIME 15 7105
TEST_TIME 16 7796
TEST_TIME 17 8211
TEST_TIME 18 8791
TEST_TIME 19 9071
TEST_TIME 20 9178
TEST_TIME 21 9127
TEST_TIME 22 9144
TEST_TIME 23 9499
TEST_TIME 24 9853
TEST_TIME 25 10423
TEST_TIME 26 10559
TEST_TIME 27 10213
TEST_TIME 28 10829
TEST_TIME 29 10653
TEST_TIME 30 10549
TEST_TIME 31 10366
TEST_TIME 32 10357
TEST_TIME 33 10166
TEST_TIME 34 10050
TEST_TIME 35 9957
TEST_TIME 36 9736
TEST_TIME 37 9665
TEST_TIME 38 9723
TEST_TIME 39 9686
TEST_TIME 40 9531
TEST_TIME 41 9571
TEST_TIME 42 9489
TEST_TIME 43 9503
TEST_TIME 44 9372
TEST_TIME 45 9400
TEST_TIME 46 9249
TEST_TIME 47 9276
TEST_TIME 48 9284
TEST_TIME 49 9154
TEST_TIME 50 9173
TEST_TIME 51 9182
TEST_TIME 52 9125
TEST_TIME 53 9077
TEST_TIME 54 9186
TEST_TIME 55 9119
TEST_TIME 56 9274
TEST_TIME 57 9412
TEST_TIME 58 9381
TEST_TIME 59 9383
TEST_TIME 60 9533
TEST_TIME 61 9566
TEST_TIME 62 9532
TEST_TIME 63 9623
TEST_TIME 64 9561
TEST_TIME 65 9444
TEST_TIME 66 9451
TEST_TIME 67 9362
TEST_TIME 68 9263
TEST_TIME 69 9251
TEST_TIME 70 9198
TEST_TIME 71 9120
TEST_TIME 72 9099
TEST_TIME 73 9094
TEST_TIME 74 9040
TEST_TIME 75 9055
TEST_TIME 76 9080
TEST_TIME 77 9100
TEST_TIME 78 9062
TEST_TIME 79 9122
TEST_TIME 80 9116
TEST_TIME 81 9118
TEST_TIME 82 9096
TEST_TIME 83 9179
TEST_TIME 84 9157
TEST_TIME 85 9145
TEST_TIME 86 9190
TEST_TIME 87 9185
TEST_TIME 88 9293
TEST_TIME 89 9228
TEST_TIME 90 9170
TEST_TIME 91 9110
TEST_TIME 92 9089
TEST_TIME 93 9094
TEST_TIME 94 9069
TEST_TIME 95 9021
TEST_TIME 96 9080
TEST_TIME 97 9065
TEST_TIME 98 9124
TEST_TIME 99 9149
TEST_TIME 100 9118
TEST_TIME 101 9135
TEST_TIME 102 9180
TEST_TIME 103 9150
TEST_TIME 104 9139
TEST_TIME 105 9271
TEST_TIME 106 9259
TEST_TIME 107 9245
TEST_TIME 108 9246
TEST_TIME 109 9235
TEST_TIME 110 9190
TEST_TIME 111 9163
TEST_TIME 112 9152
TEST_TIME 113 9140
TEST_TIME 114 9152
TEST_TIME 115 9101
TEST_TIME 116 9070
TEST_TIME 117 9048
TEST_TIME 118 9041
TEST_TIME 119 9079
TEST_TIME 120 9004

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2021 19:39:47 to 28-12-2021 19:39:51.

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 1489254 1489254 0 0.00 0 32619 6 0 0
doc_list_biud 1489253 1489253 0 0.00 0 36346 26 0 0
sp_add_invoice_to_stock 4782 4716 66 1.38 28 173344 17369 0 66
sp_cancel_adding_invoice 5678 5678 0 0.00 5 69620 763 0 0
sp_cancel_client_order 1694 1694 0 0.00 4 11548 335 0 0
sp_cancel_customer_reserve 554002 554002 0 0.00 1 36348 54 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 5520 89 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 4964 69 0 0
sp_cancel_supplier_invoice 6353 6342 11 0.17 2 33350 761 0 11
sp_cancel_supplier_order 399 399 0 0.00 8 994393 96626 0 0
sp_cancel_write_off 10030 10030 0 0.00 1 15688 115 0 0
sp_client_order 19113 19113 0 0.00 4 26491 373 0 0
sp_customer_reserve 443556 443556 0 0.00 0 56227 168 0 0
sp_fill_shopping_cart 29641 29641 0 0.00 1 38489 45 0 0
sp_fill_shopping_cart_clo_res 443549 443549 0 0.00 0 25053 15 0 0
sp_get_clo_for_invoice 4709 4709 0 0.00 0 28837 323 0 0
sp_kill_cost_storno 16776 16776 0 0.00 0 14638 61 0 0
sp_kill_qstorno_ret_qs2qd 1124775 1124775 0 0.00 0 36340 30 0 0
sp_kill_qty_storno 592140 592140 0 0.00 0 36345 64 0 0
sp_lock_selected_doc 19545 19545 0 0.00 0 7993 6 0 0
sp_make_cost_storno 26263 26263 0 0.00 0 32617 58 0 0
sp_multiply_rows_for_pdistr 26263 26263 0 0.00 0 13131 30 0 0
sp_multiply_rows_for_qdistr 19113 19113 0 0.00 0 13018 183 0 0
sp_pay_from_customer 6824 6824 0 0.00 4 14357 111 0 0
sp_pay_to_supplier 5444 5444 0 0.00 5 14219 132 0 0
sp_payment_common 12268 12268 0 0.00 1 12027 28 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 11502 301 0 0
sp_qd_handle_on_invoice_upd_sts 10387 10387 0 0.00 0 29723 342 0 0
sp_qd_handle_on_reserve_upd_sts 9286 9286 0 0.00 0 10070 53 0 0
sp_reserve_write_off 9286 9286 0 0.00 6 32626 174 0 0
sp_supplier_invoice 5288 5288 0 0.00 6 105909 3509 0 0
sp_supplier_order 5244 5244 0 0.00 5 37394 1278 0 0
srv_aggregate_perf_data 93 93 0 0.00 2110 346077 20592 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1724 140797 46155 0 0
srv_find_qd_qs_mism 1034033 1034033 0 0.00 0 11978 5 0 0
srv_make_invnt_saldo 553 553 0 0.00 31 79407 1070 0 0
srv_make_money_saldo 726 726 0 0.00 1 4948 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 354326 694042 495263 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4716 4716 0 0.00 0 15085 158 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 7202 140 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 5448 12 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 894 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 533 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 5856 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 2800 30 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 27634 1901 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1988 6 0 0
t$perf-norm:sp_client_order 19113 19113 0 0.00 0 14311 20 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1775 6 0 0
t$perf-norm:sp_pay_from_customer 6824 6824 0 0.00 0 6280 12 0 0
t$perf-norm:sp_pay_to_supplier 5444 5444 0 0.00 0 11413 15 0 0
t$perf-norm:sp_reserve_write_off 9286 9286 0 0.00 0 16246 15 0 0
t$perf-norm:sp_supplier_invoice 5288 5288 0 0.00 0 8649 23 0 0
t$perf-norm:sp_supplier_order 5244 5244 0 0.00 0 7503 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 711 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2432 41 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 1680 20 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 5921 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 46 20 0 0
v_add_invoice_to_stock 9550 9418 132 1.38 0 21082 21 0 132
v_all_customers 35262 35262 0 0.00 0 16 0 0 0
v_all_suppliers 28772 28772 0 0.00 0 17 1 0 0
v_all_wares 38226 38226 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 56 5 0 0
v_cancel_client_order 3388 3388 0 0.00 0 3544 5 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 3271 10 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 1468 4 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 196 2 0 0
v_cancel_supplier_invoice 788 766 22 2.79 0 1435 18 0 22
v_cancel_supplier_order 798 798 0 0.00 0 233 2 0 0
v_cancel_write_off 1930 1930 0 0.00 0 4645 14 0 0
v_max_id_clo_ord 5243 5243 0 0.00 0 5915 3 0 0
v_max_id_clo_res 984 984 0 0.00 0 4002 25 0 0
v_max_id_ord_sup 5285 5285 0 0.00 0 6715 5 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 127 2 0 0
v_max_non_paid_realizn 3495 3495 0 0.00 0 1774 4 0 0
v_min_id_clo_ord 5243 5243 0 0.00 0 9050 16 0 0
v_min_id_clo_res 984 984 0 0.00 0 6021 16 0 0
v_min_id_ord_sup 5285 5285 0 0.00 0 7723 8 0 0
v_min_non_paid_invoice 5444 5444 0 0.00 0 12020 19 0 0
v_min_non_paid_realizn 6824 6824 0 0.00 0 9985 20 0 0
v_our_firm 9622 9622 0 0.00 0 15 1 0 0
v_reserve_write_off 18572 18572 0 0.00 0 5302 6 0 0
x_lock_depdocs_on_canc_invoice 11637 11637 0 0.00 0 22977 110 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 16700 335 0 0
x_make_qstorno_1000_1200 5244 5244 0 0.00 2 37387 1183 0 0
x_make_qstorno_1200_2000 5288 5288 0 0.00 2 104210 3351 0 0
x_make_qstorno_2100_3300 443556 443556 0 0.00 0 56224 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2021 19:39:52 to 28-12-2021 19:39:56.

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:05 2 8192000000 8801766176 8813973504 7792032 0.095 2 2 3 0 7933136 66144 74960
17:09:06 32301 8192000000 9882108976 10189107200 964921744 10.537 100 88 177 0 994497216 5895056 23680416
17:10:43 26264 8192000000 9950602432 10270699520 1030153248 11.170 100 92 184 0 1062272304 8139808 23979248
17:12:19 11829 8192000000 9976877904 10312531968 1067227520 11.526 100 83 166 0 1096894608 9410352 20256736
17:13:40 13812 8192000000 10052399808 10401267712 1134770224 12.166 100 89 176 0 1166644496 10883008 20991264
17:14:56 8411 8192000000 10092712080 10454020096 1178175552 12.573 100 84 168 1 1209869232 12160128 19533552
17:16:08 6662 8192000000 10131270576 10500055040 1215747312 12.922 100 81 162 0 1247702256 13694288 18260656
17:17:17 2298 8192000000 10156136960 10530263040 1242235344 13.167 100 88 175 0 1275730944 14476176 19019424
17:18:21 2256 8192000000 10186394912 10565251072 1271440048 13.435 100 91 181 1 1305222512 13289024 20493440
17:19:24 1487 8192000000 10213014400 10594693120 1291492848 13.618 100 93 187 1 1324827536 12618224 20716464
17:20:27 14235 8192000000 10204806320 10599223296 1297027872 13.668 100 61 124 1 1318529680 7503056 13998752
17:21:42 11830 8192000000 10194143824 10606247936 1303880288 13.731 100 17 33 1 1313053072 5001792 4170992
17:22:54 6634 8192000000 10193908016 10616082432 1314365776 13.826 100 14 28 0 1323192864 5681152 3145936
17:24:02 5314 8192000000 10214658176 10629627904 1323865040 13.912 100 23 46 2 1335315456 6375008 5075408
17:25:09 67753 8192000000 10246788448 10647896064 1336939280 14.030 100 58 120 4 1356285648 5346400 13999968
17:27:17 5371 8192000000 10231225632 10648801280 1335188608 14.014 100 21 42 1 1345359488 5535712 4635168
17:28:22 13664 8192000000 10201637664 10624929792 1299678944 13.692 98 25 50 1 1311613520 5903936 6030640
17:29:40 24933 8192000000 10199292224 10615738368 1290907296 13.612 98 38 73 2 1305932688 6199840 8825552
17:31:06 6086 8192000000 10156971296 10564993024 1240806368 13.154 97 33 66 1 1255019744 7249872 6963504
17:32:12 9768 8192000000 10126640400 10524966912 1213690272 12.903 98 21 42 0 1224302112 5306864 5304976
17:33:25 7710 8192000000 10142084144 10539585536 1229211136 13.047 97 31 62 1 1242830608 5843760 7775712
17:34:35 32472 8192000000 10168118432 10583244800 1281890400 13.530 100 33 66 1 1297222752 7956752 7375600
17:36:11 7603 8192000000 10181677360 10593746944 1291403264 13.617 100 21 41 3 1301142528 4842576 4896688
17:37:23 7828 8192000000 10197931504 10610118656 1304331712 13.735 100 32 64 1 1318317664 5927952 8058000
17:38:34 41703 8192000000 10241459408 10648498176 1332316720 13.988 100 50 100 0 1351886496 8204912 11364864
17:40:36 60559 8192000000 10282585728 10698133504 1361814272 14.254 100 32 64 0 1386269392 16822944 7632176
17:42:45 59109 8192000000 10285622432 10712309760 1374381728 14.366 100 25 50 0 1403870144 23759872 5728544
17:44:55 8340 8192000000 10312232736 10730954752 1387613072 14.485 100 44 88 1 1426507072 28152320 10741680
17:46:19 7191 8192000000 10321398976 10743250944 1392137424 14.525 100 46 91 1 1432702128 29884256 10680448
17:47:36 21310 8192000000 10344936848 10769399808 1407515440 14.662 100 53 106 0 1450906256 30589168 12801648
17:49:01 6369 8192000000 10353266912 10778869760 1420341200 14.776 100 57 114 1 1468785984 34376240 14068544
17:50:12 6180 8192000000 10374828496 10797756416 1426641136 14.832 100 72 143 3 1483682368 39238624 17802608
17:51:23 9220 8192000000 10383955184 10808737792 1430994480 14.870 100 71 140 1 1490149296 42537424 16617392
17:52:36 4476 8192000000 10391688512 10815713280 1437346448 14.926 100 78 156 1 1505068048 47655456 20066144
17:53:49 1720 8192000000 10388526672 10811531264 1417065152 14.747 99 78 156 3 1488058336 51636960 19356224
17:54:55 4387 8192000000 10386563040 10816307200 1425858784 14.825 100 58 115 0 1491619184 51604848 14155552
17:56:09 4142 8192000000 10363582336 10789511168 1407258848 14.660 100 52 104 0 1469335840 48700032 13376960
17:57:18 4318 8192000000 10320668736 10747789312 1382083296 14.435 99 43 83 1 1428381376 35587456 10710624
17:58:26 23301 8192000000 10323021776 10760364032 1383491024 14.448 100 25 50 3 1428740208 38263072 6986112
17:59:54 18483 8192000000 10321781328 10753601536 1361020384 14.247 98 37 72 3 1410967344 40265664 9681296
18:01:14 14645 8192000000 10323084960 10751655936 1370076640 14.328 100 35 71 0 1424983840 45203360 9703840
18:02:36 36311 8192000000 10286812816 10723868672 1322116016 13.896 97 27 54 2 1367516992 38193392 7207584
18:04:14 24086 8192000000 10249718400 10683125760 1319512064 13.872 98 13 26 1 1352294928 29459584 3323280
18:06:04 12974 8192000000 10256979728 10702200832 1335053568 14.013 100 32 63 0 1369709792 26669328 7986896
18:07:23 61254 8192000000 10246008000 10696814592 1334920944 14.012 99 28 56 0 1353786512 12636192 6229376
18:09:34 65475 8192000000 10247968800 10693152768 1296610944 13.664 95 13 27 0 1310679760 11440992 2627824
18:12:40 61060 8192000000 10251278768 10690879488 1320689360 13.883 98 12 24 0 1333938400 10479904 2769136
18:14:42 140797 8192000000 10248774976 10706743296 1334662160 14.009 98 24 48 0 1360771584 20635456 5473968
18:18:15 9096 8192000000 10264418720 10708602880 1358137984 14.221 99 25 50 1 1371613904 6711584 6764336
18:19:26 47338 8192000000 10269654288 10726137856 1376206400 14.383 100 18 36 1 1391337136 10898544 4232192
18:21:19 51251 8192000000 10287045888 10750009344 1390175680 14.507 100 11 22 1 1411280000 18013040 3091280
18:23:18 63348 8192000000 10289146672 10755780608 1395212976 14.552 100 13 26 1 1412504352 14508432 2782944
18:25:22 32117 8192000000 10290272352 10735382528 1367799360 14.307 98 40 81 0 1383867328 7311168 8756800
18:26:55 85575 8192000000 10271265296 10731921408 1347336384 14.124 97 11 22 1 1358464384 8456640 2671360
18:29:43 49095 8192000000 10292292368 10746118144 1375587024 14.377 99 25 51 0 1390807984 9586432 5634528
18:31:32 62800 8192000000 10314259968 10785943552 1402238880 14.615 100 17 34 0 1427196560 20684480 4273200
18:33:43 58589 8192000000 10328813824 10790936576 1416614832 14.743 100 20 40 1 1438692560 17186192 4891536
18:35:43 81111 8192000000 10335141328 10800488448 1414312928 14.722 100 13 26 0 1436344880 18752080 3279872
18:38:04 103522 8192000000 10325534976 10790322176 1419407680 14.767 100 9 18 0 1438569616 17494112 1667824
18:40:49 59529 8192000000 10322498336 10789679104 1381795360 14.433 97 27 54 0 1403574304 15963184 5815760
18:42:54 58996 8192000000 10302814016 10755215360 1391849696 14.522 98 16 32 0 1416199056 20497184 3852176
18:44:55 47403 8192000000 10301714256 10777747456 1385721952 14.468 98 2 4 0 1401063520 15002704 338864
18:46:43 47720 8192000000 10297320720 10762813440 1392857984 14.531 99 18 36 1 1407283808 10328416 4097408
18:48:35 65186 8192000000 10285132848 10745778176 1366375264 14.295 98 15 30 0 1381142160 11447552 3319344
18:50:47 43403 8192000000 10279862160 10743050240 1362411968 14.259 99 19 38 3 1377741120 10758592 4570560
18:52:30 97390 8192000000 10281257664 10740539392 1358773696 14.226 99 9 18 0 1371786880 10947776 2065408
18:55:08 69081 8192000000 10273197120 10719719424 1327910704 13.948 96 30 60 0 1342696304 8387248 6398352
18:57:18 77576 8192000000 10264110816 10707509248 1303026176 13.723 95 17 34 0 1317099216 10081392 3991648
18:59:39 36776 8192000000 10272980576 10710519808 1353493088 14.179 100 45 90 2 1376001840 12603872 9904880
19:01:22 60827 8192000000 10231329872 10682064896 1297939632 13.677 97 13 26 0 1313766960 13029056 2798272
19:03:25 33480 8192000000 10238136944 10672246784 1280093040 13.514 96 30 60 1 1296842224 10141168 6608016
19:05:03 28508 8192000000 10266531760 10694545408 1341187488 14.068 100 27 54 1 1358293968 10804368 6302112
19:06:33 67044 8192000000 10254153616 10709340160 1334812288 14.011 99 7 14 0 1353127424 16770784 1544352
19:09:01 60971 8192000000 10280975328 10717556736 1344711696 14.100 98 40 79 1 1368129968 15062128 8356144
19:11:03 36879 8192000000 10261266656 10714923008 1346758864 14.118 98 8 16 0 1360850352 12386672 1704816
19:12:43 44228 8192000000 10281291824 10737696768 1376073360 14.381 100 7 14 0 1388062480 10545360 1443760
19:14:27 46841 8192000000 10286859952 10734510080 1349913760 14.147 97 27 54 0 1365491392 9610672 5966960
19:16:35 71792 8192000000 10274738800 10729795584 1310865808 13.794 94 13 24 1 1322189120 8415152 2908160
19:18:47 33214 8192000000 10303293712 10740051968 1375283616 14.374 100 40 80 1 1393398800 9176160 8939024
19:20:22 47139 8192000000 10303526576 10758266880 1395826832 14.558 100 15 30 2 1408240640 9323648 3090160
19:22:19 73599 8192000000 10315366928 10796691456 1409232832 14.677 100 20 40 0 1433255728 19230480 4792416
19:25:38 29648 8192000000 10333808128 10781667328 1409585216 14.680 100 34 66 0 1425042160 8203152 7253792
19:27:57 109964 8192000000 10293887520 10750164992 1385819792 14.469 99 33 66 4 1403272976 9931488 7521696
19:30:47 54105 8192000000 10310269408 10769158144 1406500848 14.653 100 17 34 0 1421635248 11106752 4027648
19:33:12 49137 8192000000 10305448624 10761027584 1382532832 14.439 98 10 18 0 1393062272 8432640 2096800
19:35:05 71278 8192000000 10282316272 10740207616 1384172096 14.454 99 10 20 0 1397600128 10826624 2601408
19:37:22 44713 8192000000 9650814944 10012692480 777075520 8.663 56 11 22 0 789597744 9910512 2611712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-12-2021 19:39:57 to 28-12-2021 19:40:12.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2021 19:40:13 to 28-12-2021 19:40:15.

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          229243
MON$OLDEST_ACTIVE               229459
MON$OLDEST_SNAPSHOT             229459
MON$NEXT_TRANSACTION            229459
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                       2003456
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Dec 28 19:38:19 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Dec 28 19:38:20 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Dec 28 19:38:20 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

28.12.21 19:40:16. 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-12-28 19:40:18 No severe PSQL-related problems occured
28.12.21 19:40:20. End of report.