Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.04.2022 16: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 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 16742932 kB
MemAvailable 31492632 kB
Buffers 0 kB
Cached 14950776 kB
SwapCached 2688 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-04-14 16:38:12
DTS_END                         2022-04-14 16:38:12.6130
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/53176
TRN_ID                          235654
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: 9386.17 20846 1126340
customer order: creation 169.42 310 20330
customer order: refuse 14.78 299 1773
order to supplier: creation 46.04 1258 5525
order to supplier: removal 3.18 120059 382
invoice (draft): creation 44.56 2955 5347
invoice (draft): removal 54.37 826 6524
invoice accept: apply 41.27 16728 4952
invoice accept: cancel 49.41 726 5929
customer reserve: creation 3815.88 166 457905
customer reserve: removal 4851.30 62 582156
realization accept: apply 81.58 167 9789
realization accept: cancel 85.73 107 10287
payment from customer: creation 59.30 98 7116
payment from customer: removal 5.91 70 709
payment to supplier: creation 47.93 129 5752
payment to supplier: removal 3.39 63 407
service: total inventory turnovers 4.88 846 586
service: total monetary turnovers 6.41 28 769
service: aggregate perf. data 0.82 16379 98
service: refresh index statistics 0.03 255652 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-04-2022 16:39:07 to 14-04-2022 16:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4368
TEST_TIME 2 4764
TEST_TIME 3 5227
TEST_TIME 4 5049
TEST_TIME 5 4186
TEST_TIME 6 4033
TEST_TIME 7 4361
TEST_TIME 8 4977
TEST_TIME 9 4936
TEST_TIME 10 5467
TEST_TIME 11 5744
TEST_TIME 12 5966
TEST_TIME 13 6084
TEST_TIME 14 6602
TEST_TIME 15 6609
TEST_TIME 16 7114
TEST_TIME 17 7603
TEST_TIME 18 7412
TEST_TIME 19 7856
TEST_TIME 20 8387
TEST_TIME 21 8496
TEST_TIME 22 8699
TEST_TIME 23 9065
TEST_TIME 24 9682
TEST_TIME 25 9987
TEST_TIME 26 10240
TEST_TIME 27 11492
TEST_TIME 28 11646
TEST_TIME 29 11425
TEST_TIME 30 11334
TEST_TIME 31 11094
TEST_TIME 32 11054
TEST_TIME 33 10930
TEST_TIME 34 10748
TEST_TIME 35 10662
TEST_TIME 36 10380
TEST_TIME 37 10269
TEST_TIME 38 10153
TEST_TIME 39 10051
TEST_TIME 40 10012
TEST_TIME 41 10004
TEST_TIME 42 10033
TEST_TIME 43 9923
TEST_TIME 44 9931
TEST_TIME 45 9869
TEST_TIME 46 9767
TEST_TIME 47 9625
TEST_TIME 48 9780
TEST_TIME 49 9668
TEST_TIME 50 9638
TEST_TIME 51 9634
TEST_TIME 52 9742
TEST_TIME 53 9663
TEST_TIME 54 9701
TEST_TIME 55 9785
TEST_TIME 56 9805
TEST_TIME 57 9709
TEST_TIME 58 9789
TEST_TIME 59 9905
TEST_TIME 60 9982
TEST_TIME 61 10012
TEST_TIME 62 9876
TEST_TIME 63 9738
TEST_TIME 64 9724
TEST_TIME 65 9665
TEST_TIME 66 9620
TEST_TIME 67 9657
TEST_TIME 68 9594
TEST_TIME 69 9581
TEST_TIME 70 9550
TEST_TIME 71 9578
TEST_TIME 72 9653
TEST_TIME 73 9718
TEST_TIME 74 9685
TEST_TIME 75 9735
TEST_TIME 76 9711
TEST_TIME 77 9756
TEST_TIME 78 9715
TEST_TIME 79 9671
TEST_TIME 80 9639
TEST_TIME 81 9568
TEST_TIME 82 9525
TEST_TIME 83 9558
TEST_TIME 84 9528
TEST_TIME 85 9479
TEST_TIME 86 9446
TEST_TIME 87 9378
TEST_TIME 88 9391
TEST_TIME 89 9417
TEST_TIME 90 9445
TEST_TIME 91 9442
TEST_TIME 92 9530
TEST_TIME 93 9536
TEST_TIME 94 9514
TEST_TIME 95 9563
TEST_TIME 96 9672
TEST_TIME 97 9646
TEST_TIME 98 9665
TEST_TIME 99 9699
TEST_TIME 100 9791
TEST_TIME 101 9758
TEST_TIME 102 9700
TEST_TIME 103 9654
TEST_TIME 104 9599
TEST_TIME 105 9597
TEST_TIME 106 9573
TEST_TIME 107 9526
TEST_TIME 108 9531
TEST_TIME 109 9499
TEST_TIME 110 9473
TEST_TIME 111 9425
TEST_TIME 112 9377
TEST_TIME 113 9349
TEST_TIME 114 9365
TEST_TIME 115 9367
TEST_TIME 116 9402
TEST_TIME 117 9413
TEST_TIME 118 9447
TEST_TIME 119 9454
TEST_TIME 120 9391

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-04-2022 16:39:11 to 14-04-2022 16:39:14.

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 1550543 1550543 0 0.00 0 18167 6 0 0
doc_list_biud 1550543 1550543 0 0.00 0 34496 29 0 0
sp_add_invoice_to_stock 5057 4952 105 2.07 31 176921 16728 0 105
sp_cancel_adding_invoice 5929 5929 0 0.00 5 73660 727 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 11421 300 0 0
sp_cancel_customer_reserve 582156 582156 0 0.00 1 20388 63 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 4732 71 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 1753 64 0 0
sp_cancel_supplier_invoice 6540 6524 16 0.24 1 21330 826 0 16
sp_cancel_supplier_order 382 382 0 0.00 6 1301693 120060 0 0
sp_cancel_write_off 10287 10287 0 0.00 1 8931 108 0 0
sp_client_order 20330 20330 0 0.00 3 20861 310 0 0
sp_customer_reserve 457905 457905 0 0.00 0 47329 166 0 0
sp_fill_shopping_cart 31194 31194 0 0.00 1 13182 37 0 0
sp_fill_shopping_cart_clo_res 457892 457892 0 0.00 0 16994 14 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 27550 283 0 0
sp_kill_cost_storno 17332 17332 0 0.00 0 9817 55 0 0
sp_kill_qstorno_ret_qs2qd 1181505 1181505 0 0.00 0 21310 34 0 0
sp_kill_qty_storno 621779 621779 0 0.00 0 34495 72 0 0
sp_lock_selected_doc 20542 20542 0 0.00 0 6376 5 0 0
sp_make_cost_storno 27596 27596 0 0.00 0 18155 55 0 0
sp_multiply_rows_for_pdistr 27596 27596 0 0.00 0 9272 27 0 0
sp_multiply_rows_for_qdistr 20330 20330 0 0.00 0 20838 145 0 0
sp_pay_from_customer 7116 7116 0 0.00 4 12757 99 0 0
sp_pay_to_supplier 5752 5752 0 0.00 5 24447 130 0 0
sp_payment_common 12868 12868 0 0.00 1 24434 29 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 8563 276 0 0
sp_qd_handle_on_invoice_upd_sts 10868 10868 0 0.00 0 34492 288 0 0
sp_qd_handle_on_reserve_upd_sts 9789 9789 0 0.00 0 10575 53 0 0
sp_reserve_write_off 9789 9789 0 0.00 7 15410 167 0 0
sp_supplier_invoice 5347 5347 0 0.00 6 76887 2956 0 0
sp_supplier_order 5525 5525 0 0.00 5 85920 1259 0 0
srv_aggregate_perf_data 98 98 0 0.00 2692 107461 16380 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1804 119208 49087 0 0
srv_find_qd_qs_mism 1079175 1079175 0 0.00 0 12034 4 0 0
srv_make_invnt_saldo 586 586 0 0.00 18 48936 847 0 0
srv_make_money_saldo 769 769 0 0.00 1 4870 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 144996 449634 255653 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4953 4953 0 0.00 0 19716 154 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 10458 119 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 1237 8 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1630 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 2341 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 87 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 478 9 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 26242 1513 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 539 4 0 0
t$perf-norm:sp_client_order 20330 20330 0 0.00 0 9921 18 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 2920 10 0 0
t$perf-norm:sp_pay_from_customer 7116 7116 0 0.00 0 20641 13 0 0
t$perf-norm:sp_pay_to_supplier 5752 5752 0 0.00 0 3961 7 0 0
t$perf-norm:sp_reserve_write_off 9789 9789 0 0.00 0 18936 10 0 0
t$perf-norm:sp_supplier_invoice 5347 5347 0 0.00 0 7327 22 0 0
t$perf-norm:sp_supplier_order 5525 5525 0 0.00 0 6965 18 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 269 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 45 1 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 14589 40 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 1724 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10082 9872 210 2.08 0 11070 18 0 210
v_all_customers 37364 37364 0 0.00 0 19 0 0 0
v_all_suppliers 30368 30368 0 0.00 0 23 1 0 0
v_all_wares 40660 40660 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 6009 16 0 0
v_cancel_client_order 3546 3546 0 0.00 0 11125 6 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 3315 6 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2391 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1551 5 0 0
v_cancel_supplier_invoice 840 808 32 3.80 0 4237 16 0 32
v_cancel_supplier_order 764 764 0 0.00 0 2513 8 0 0
v_cancel_write_off 2106 2106 0 0.00 0 6063 18 0 0
v_max_id_clo_ord 5523 5523 0 0.00 0 2694 2 0 0
v_max_id_clo_res 986 986 0 0.00 0 3559 16 0 0
v_max_id_ord_sup 5340 5340 0 0.00 0 4657 6 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 1497 4 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 678 3 0 0
v_min_id_clo_ord 5523 5523 0 0.00 0 5018 7 0 0
v_min_id_clo_res 986 986 0 0.00 0 2949 10 0 0
v_min_id_ord_sup 5340 5340 0 0.00 0 4704 6 0 0
v_min_non_paid_invoice 5752 5752 0 0.00 0 24426 23 0 0
v_min_non_paid_realizn 7116 7116 0 0.00 0 9353 24 0 0
v_our_firm 10294 10294 0 0.00 0 12 1 0 0
v_reserve_write_off 19578 19578 0 0.00 0 6053 6 0 0
x_lock_depdocs_on_canc_invoice 12049 12049 0 0.00 0 17046 113 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 8965 449 0 0
x_make_qstorno_1000_1200 5525 5525 0 0.00 2 85914 1180 0 0
x_make_qstorno_1200_2000 5347 5347 0 0.00 2 76872 2826 0 0
x_make_qstorno_2100_3300 457905 457905 0 0.00 0 47328 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-04-2022 16:39:15 to 14-04-2022 16:39:19.

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
14:08:16 13 8192000000 8811374416 8826691584 15763744 0.192 4 4 7 0 16014432 89920 160768
14:09:16 26938 8192000000 9898984064 10206265344 977616608 10.661 100 86 171 1 1006657088 6178432 22862048
14:10:50 23549 8192000000 9960937488 10281398272 1040944880 11.274 100 91 182 0 1072734992 8327328 23462784
14:12:22 2077 8192000000 9983358800 10315350016 1071625648 11.568 100 86 172 0 1102515312 8768304 22121360
14:13:31 15649 8192000000 10047854656 10395930624 1124547168 12.070 100 80 160 0 1153360896 9568944 19244784
14:14:52 9056 8192000000 10091849776 10454601728 1186711104 12.653 100 80 160 0 1217314080 12128336 18474640
14:16:04 4600 8192000000 10129966704 10500935680 1222470016 12.985 100 78 155 1 1252533008 12992560 17070432
14:17:11 3106 8192000000 10162424000 10545098752 1256267792 13.296 100 70 138 2 1283911440 12436080 15207568
14:18:16 1376 8192000000 10206323440 10586038272 1290685712 13.610 100 87 174 0 1324096592 13855280 19555600
14:19:19 1918 8192000000 10220243312 10607280128 1306355760 13.753 100 83 162 1 1336331936 11328896 18647280
14:20:23 2093 8192000000 10235980496 10625519616 1314943568 13.831 100 89 177 0 1347706256 12677024 20085664
14:21:33 5645 8192000000 10205247984 10618159104 1317078464 13.850 100 34 67 2 1332621968 8345104 7198400
14:22:39 18526 8192000000 10210980128 10630488064 1330159792 13.969 100 19 39 1 1339980192 5348096 4472304
14:24:01 7605 8192000000 10209806416 10637369344 1331950736 13.985 100 26 52 1 1345018624 7334720 5733168
14:25:13 58797 8192000000 10242744448 10648240128 1334841888 14.011 100 53 104 4 1352394736 5171456 12381392
14:27:12 4980 8192000000 10224673216 10646810624 1335838064 14.020 100 28 55 1 1348997952 7244128 5915760
14:28:19 7969 8192000000 10214033744 10626330624 1298277296 13.680 98 28 56 0 1310923936 5830848 6815792
14:29:30 14174 8192000000 10165284512 10578038784 1251609568 13.253 97 15 30 1 1260792864 5843168 3340128
14:30:45 47802 8192000000 10102065248 10499104768 1155225968 12.359 91 33 66 1 1172083616 8930960 7926688
14:32:33 8289 8192000000 10122129904 10517483520 1202761824 12.802 97 25 50 1 1213942416 5352032 5828560
14:33:42 7451 8192000000 10158342992 10565652480 1269132656 13.414 100 36 72 0 1283636880 5798400 8705824
14:34:50 12730 8192000000 10161507616 10575167488 1277949760 13.494 100 21 42 1 1288121664 5491504 4680400
14:36:05 38868 8192000000 10181412960 10593267712 1289249952 13.597 100 37 74 0 1304657664 6615376 8792336
14:37:46 8554 8192000000 10188458160 10604023808 1299138272 13.687 100 20 40 0 1309533504 5714160 4681072
14:39:01 34731 8192000000 10237289600 10647474176 1328556672 13.954 100 42 83 1 1349342736 11969776 8816288
14:40:38 6295 8192000000 10267761568 10674450432 1353192128 14.176 100 55 108 2 1381034128 15253376 12588624
14:41:49 92040 8192000000 10272398704 10698465280 1366035760 14.292 100 34 68 0 1388843936 14786736 8021440
14:44:26 26963 8192000000 10306467680 10732343296 1382347280 14.438 100 27 54 1 1419948816 30637616 6963920
14:45:58 3590 8192000000 10326841408 10745835520 1391567344 14.520 100 55 110 0 1437140672 32249664 13323664
14:47:06 6549 8192000000 10333454448 10755284992 1400441664 14.599 100 44 87 0 1441529232 30133552 10954016
14:48:31 6747 8192000000 10352485984 10770178048 1412183216 14.703 100 59 118 2 1462083152 35707824 14192112
14:49:43 5010 8192000000 10370163456 10784071680 1407013808 14.657 99 78 153 2 1469722272 43245616 19462848
14:50:54 5234 8192000000 10369211024 10778181632 1408861744 14.674 100 82 164 1 1475924112 46123152 20939216
14:52:03 4387 8192000000 10380508304 10802368512 1424664864 14.814 100 59 117 0 1487676672 47733200 15278608
14:53:11 6605 8192000000 10411039680 10825764864 1441869232 14.966 100 76 150 1 1511206736 50288352 19049152
14:54:24 1803 8192000000 10413276112 10826104832 1426389120 14.829 99 84 169 4 1506022512 58791232 20842160
14:55:30 10288 8192000000 10390707264 10819788800 1430456624 14.865 99 42 84 0 1492272704 50217840 11598240
14:56:58 4404 8192000000 10367309520 10783526912 1395432704 14.554 100 64 125 1 1471863536 59259488 17171344
14:58:09 16428 8192000000 10385244896 10815102976 1421786304 14.789 100 44 88 2 1487529520 53604832 12138384
14:59:29 12091 8192000000 10370581296 10797887488 1405990048 14.648 99 39 78 1 1467440432 50831392 10618992
15:00:47 11957 8192000000 10372403168 10797797376 1402349712 14.616 100 48 96 3 1482681408 66686944 13644752
15:02:09 15975 8192000000 10359324784 10796097536 1393340320 14.536 99 39 78 1 1460583440 56111056 11132064
15:03:28 13727 8192000000 10343067520 10782158848 1374101792 14.364 99 27 54 0 1437671488 55306912 8262784
15:05:11 48447 8192000000 10305041280 10760085504 1337319040 14.033 98 16 32 0 1396886672 54737472 4830160
15:07:01 9675 8192000000 10266603872 10691559424 1332920032 13.994 99 52 104 0 1353418560 9379040 11119488
15:08:13 72801 8192000000 10227955536 10663084032 1266886736 13.393 95 9 18 0 1276315760 7721584 1707440
15:11:36 45371 8192000000 10256919808 10696130560 1337434528 14.034 99 16 30 0 1348052368 7473216 3144624
15:13:22 99454 8192000000 10260403680 10715586560 1364041216 14.274 100 10 20 1 1376252080 10145136 2065728
15:16:21 16391 8192000000 10270465120 10720002048 1367253888 14.302 100 24 47 0 1380946496 7854928 5837680
15:17:38 60850 8192000000 10287375296 10738692096 1378032112 14.399 100 28 56 1 1396418480 11560432 6825936
15:19:40 74080 8192000000 10282547056 10747703296 1351785632 14.164 98 15 30 0 1372749184 17216496 3747056
15:21:55 56204 8192000000 10290670544 10751143936 1387547344 14.484 100 13 27 0 1410277712 19510528 3219840
15:23:58 99051 8192000000 10281974944 10750078976 1367719424 14.307 98 16 32 0 1381450336 10102272 3628640
15:26:48 99380 8192000000 10275456096 10736173056 1343029616 14.085 97 11 22 0 1361330048 16024368 2276064
15:29:31 83296 8192000000 10277646592 10735341568 1362341296 14.258 99 21 41 1 1378236208 11654560 4240352
15:32:00 36002 8192000000 10283868320 10740383744 1381381328 14.429 100 25 50 1 1400531808 13151408 5999072
15:33:45 68815 8192000000 10281354256 10738126848 1356708144 14.208 98 15 30 0 1369773920 9973680 3092096
15:35:57 66093 8192000000 10302282448 10765463552 1381015424 14.426 99 30 60 0 1410468208 22322080 7130704
15:38:18 72507 8192000000 10310989312 10765266944 1361363520 14.250 97 10 20 0 1374514224 11390912 1759792
15:40:31 90582 8192000000 10293836576 10739998720 1374134336 14.364 99 29 59 0 1392495104 11244768 7116000
15:43:03 74727 8192000000 10286691872 10752008192 1388778736 14.495 100 6 12 0 1396932864 7094448 1059680
15:45:17 87073 8192000000 10277448704 10718212096 1307714672 13.765 94 22 43 1 1326406144 13528832 5162640
15:47:46 34004 8192000000 10270835152 10716008448 1332498096 13.990 97 46 88 1 1349522576 7055440 9969040
15:49:26 66203 8192000000 10285444656 10747879424 1350484608 14.152 98 10 21 1 1376745744 23827008 2434128
15:51:39 119208 8192000000 10283384304 10725949440 1315556640 13.836 96 12 24 0 1328772112 10827888 2387584
15:54:39 66045 8192000000 10278912416 10725990400 1316715088 13.847 96 38 76 0 1339435392 14401328 8318976
15:56:47 23654 8192000000 10280286304 10710224896 1336197712 14.023 98 55 109 0 1358788848 10346208 12244928
15:58:13 97711 8192000000 10262143616 10712281088 1358315120 14.222 100 23 45 1 1373521056 9495184 5710752
16:01:00 49238 8192000000 10227206400 10673266688 1278367472 13.498 94 21 41 0 1295836768 12205008 5264288
16:03:34 77979 8192000000 10246809200 10699472896 1315101344 13.832 97 15 30 0 1329539504 10799088 3639072
16:05:52 59537 8192000000 10256455216 10707521536 1346487184 14.116 100 11 22 0 1363447696 14152800 2807712
16:07:52 51846 8192000000 10275607680 10719031296 1367071072 14.301 100 19 38 0 1384338016 13079072 4187872
16:09:47 101355 8192000000 10277663072 10735190016 1352985568 14.174 98 15 30 0 1365320880 9244928 3090384
16:12:30 64673 8192000000 10303705552 10743578624 1347235488 14.123 97 23 46 3 1367460608 15034208 5190912
16:14:35 65054 8192000000 10304028864 10759716864 1380300336 14.419 100 12 24 0 1403949024 20530944 3117744
16:16:45 77350 8192000000 10294098512 10748858368 1320080576 13.877 95 24 48 0 1346767200 21478032 5208592
16:19:03 65332 8192000000 10284456064 10728644608 1342401632 14.079 97 6 12 0 1361534160 18241488 891040
16:21:09 93991 8192000000 10282688784 10734477312 1357597872 14.216 98 23 46 0 1373906144 10812544 5495728
16:23:44 17377 8192000000 10277093264 10733600768 1368098720 14.310 100 27 53 0 1387047408 13080656 5868032
16:25:07 58376 8192000000 10274969120 10732916736 1366660464 14.297 99 11 22 0 1381392560 12364976 2367120
16:27:05 29969 8192000000 10266470512 10723119104 1357788176 14.217 99 15 32 3 1369812944 8022672 4002096
16:28:38 59125 8192000000 10278490752 10726178816 1358756240 14.226 99 18 35 1 1369124912 6429408 3939264
16:30:38 80264 8192000000 10270197600 10726813696 1340881872 14.065 97 5 10 0 1349558544 7463440 1213232
16:32:59 56884 8192000000 10282686112 10736611328 1365130336 14.283 99 11 22 0 1376251008 8803520 2317152
16:34:56 43567 8192000000 10279018688 10725130240 1360194416 14.239 99 19 38 0 1375405568 11087936 4123216
16:36:41 38793 8192000000 10276229168 10721112064 1354596064 14.189 99 33 66 0 1371184928 8802992 7785872

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-04-2022 16:39:21 to 14-04-2022 16:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-04-2022 16:39:37 to 14-04-2022 16:39:39.

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          235523
MON$OLDEST_ACTIVE               237958
MON$OLDEST_SNAPSHOT             237958
MON$NEXT_TRANSACTION            237958
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                       2009984
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:386: 
:387: mscs-test-161.budzdorov.ru    Thu Apr 14 16:38:19 2022
:388:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:389: 
:390: 
:391: mscs-test-161.budzdorov.ru    Thu Apr 14 16:38:19 2022
:392:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:393: 
:394: 
:395: mscs-test-161.budzdorov.ru    Thu Apr 14 16:38:19 2022
:396:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:397: 
:398: 
:399: mscs-test-161.budzdorov.ru    Thu Apr 14 16:38:23 2022
:400:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:401: 
--- end of diff output ---

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

14.04.22 16:39:40. 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-04-14 16:39:42 No severe PSQL-related problems occured
14.04.22 16:39:43. End of report.