Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2022 19:40
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 16483852 kB
MemAvailable 31321668 kB
Buffers 0 kB
Cached 15223872 kB
SwapCached 1204 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 2022-01-09 19:38:07
DTS_END                         2022-01-09 19:38:07.1590
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/59888
TRN_ID                          232276
ATT_ID                          643
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: 9309.24 28493 1117109
customer order: creation 162.28 394 19473
customer order: refuse 14.22 355 1706
order to supplier: creation 43.18 1312 5182
order to supplier: removal 3.51 112221 421
invoice (draft): creation 43.70 3398 5244
invoice (draft): removal 55.08 797 6610
invoice accept: apply 39.55 17393 4746
invoice accept: cancel 49.69 811 5963
customer reserve: creation 3725.19 167 447023
customer reserve: removal 4888.89 64 586667
realization accept: apply 76.92 197 9230
realization accept: cancel 86.74 106 10409
payment from customer: creation 54.71 138 6565
payment from customer: removal 5.23 110 627
payment to supplier: creation 45.25 159 5430
payment to supplier: removal 3.15 68 378
service: total inventory turnovers 4.48 1121 538
service: total monetary turnovers 6.48 44 777
service: aggregate perf. data 0.97 18936 116
service: refresh index statistics 0.03 412069 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2022 19:40:22 to 09-01-2022 19:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5252
TEST_TIME 2 4869
TEST_TIME 3 4502
TEST_TIME 4 3728
TEST_TIME 5 3585
TEST_TIME 6 4193
TEST_TIME 7 4005
TEST_TIME 8 4181
TEST_TIME 9 4436
TEST_TIME 10 4643
TEST_TIME 11 4941
TEST_TIME 12 6014
TEST_TIME 13 6298
TEST_TIME 14 6776
TEST_TIME 15 6772
TEST_TIME 16 7122
TEST_TIME 17 7628
TEST_TIME 18 8057
TEST_TIME 19 8247
TEST_TIME 20 8227
TEST_TIME 21 8877
TEST_TIME 22 8756
TEST_TIME 23 9408
TEST_TIME 24 9465
TEST_TIME 25 10036
TEST_TIME 26 10241
TEST_TIME 27 10234
TEST_TIME 28 10909
TEST_TIME 29 11530
TEST_TIME 30 11217
TEST_TIME 31 11163
TEST_TIME 32 10948
TEST_TIME 33 10691
TEST_TIME 34 10568
TEST_TIME 35 10400
TEST_TIME 36 10690
TEST_TIME 37 10512
TEST_TIME 38 10500
TEST_TIME 39 10363
TEST_TIME 40 10379
TEST_TIME 41 10258
TEST_TIME 42 10120
TEST_TIME 43 10032
TEST_TIME 44 9979
TEST_TIME 45 10081
TEST_TIME 46 10060
TEST_TIME 47 9917
TEST_TIME 48 9958
TEST_TIME 49 9824
TEST_TIME 50 9811
TEST_TIME 51 9700
TEST_TIME 52 9684
TEST_TIME 53 9659
TEST_TIME 54 9574
TEST_TIME 55 9548
TEST_TIME 56 9528
TEST_TIME 57 9688
TEST_TIME 58 9618
TEST_TIME 59 9668
TEST_TIME 60 9700
TEST_TIME 61 9693
TEST_TIME 62 9811
TEST_TIME 63 9787
TEST_TIME 64 9836
TEST_TIME 65 9937
TEST_TIME 66 9878
TEST_TIME 67 9889
TEST_TIME 68 9813
TEST_TIME 69 9702
TEST_TIME 70 9657
TEST_TIME 71 9705
TEST_TIME 72 9684
TEST_TIME 73 9649
TEST_TIME 74 9575
TEST_TIME 75 9546
TEST_TIME 76 9476
TEST_TIME 77 9427
TEST_TIME 78 9432
TEST_TIME 79 9388
TEST_TIME 80 9480
TEST_TIME 81 9412
TEST_TIME 82 9517
TEST_TIME 83 9540
TEST_TIME 84 9532
TEST_TIME 85 9532
TEST_TIME 86 9513
TEST_TIME 87 9535
TEST_TIME 88 9669
TEST_TIME 89 9638
TEST_TIME 90 9719
TEST_TIME 91 9624
TEST_TIME 92 9543
TEST_TIME 93 9568
TEST_TIME 94 9518
TEST_TIME 95 9450
TEST_TIME 96 9443
TEST_TIME 97 9450
TEST_TIME 98 9455
TEST_TIME 99 9425
TEST_TIME 100 9425
TEST_TIME 101 9458
TEST_TIME 102 9493
TEST_TIME 103 9484
TEST_TIME 104 9500
TEST_TIME 105 9483
TEST_TIME 106 9535
TEST_TIME 107 9546
TEST_TIME 108 9568
TEST_TIME 109 9572
TEST_TIME 110 9565
TEST_TIME 111 9523
TEST_TIME 112 9512
TEST_TIME 113 9516
TEST_TIME 114 9470
TEST_TIME 115 9456
TEST_TIME 116 9412
TEST_TIME 117 9417
TEST_TIME 118 9392
TEST_TIME 119 9353
TEST_TIME 120 9314

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-01-2022 19:40:25 to 09-01-2022 19:40:29.

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 1530718 1530718 0 0.00 0 34626 6 0 0
doc_list_biud 1530715 1530715 0 0.00 0 36481 30 0 0
sp_add_invoice_to_stock 4891 4746 145 2.96 35 166804 17394 0 145
sp_cancel_adding_invoice 5963 5963 0 0.00 5 68495 811 0 0
sp_cancel_client_order 1706 1706 0 0.00 4 13806 356 0 0
sp_cancel_customer_reserve 586667 586667 0 0.00 1 36483 64 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 3 8755 111 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 6461 68 0 0
sp_cancel_supplier_invoice 6623 6610 13 0.19 1 13575 797 0 13
sp_cancel_supplier_order 421 421 0 0.00 8 1148809 112222 0 0
sp_cancel_write_off 10409 10409 0 0.00 1 19634 107 0 0
sp_client_order 19473 19473 0 0.00 3 22143 394 0 0
sp_customer_reserve 447023 447023 0 0.00 0 83112 168 0 0
sp_fill_shopping_cart 29892 29892 0 0.00 1 15576 38 0 0
sp_fill_shopping_cart_clo_res 447015 447015 0 0.00 0 19127 15 0 0
sp_get_clo_for_invoice 4737 4737 0 0.00 0 35719 312 0 0
sp_kill_cost_storno 17377 17377 0 0.00 0 19599 61 0 0
sp_kill_qstorno_ret_qs2qd 1190774 1190774 0 0.00 0 36480 34 0 0
sp_kill_qty_storno 625742 625742 0 0.00 0 36481 74 0 0
sp_lock_selected_doc 19454 19454 0 0.00 0 9298 8 0 0
sp_make_cost_storno 25961 25961 0 0.00 0 34619 63 0 0
sp_multiply_rows_for_pdistr 25961 25961 0 0.00 0 15522 36 0 0
sp_multiply_rows_for_qdistr 19473 19473 0 0.00 0 20078 194 0 0
sp_pay_from_customer 6565 6565 0 0.00 4 16784 138 0 0
sp_pay_to_supplier 5430 5430 0 0.00 5 17474 159 0 0
sp_payment_common 11995 11995 0 0.00 2 15505 46 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 9985 322 0 0
sp_qd_handle_on_invoice_upd_sts 10699 10699 0 0.00 0 32858 341 0 0
sp_qd_handle_on_reserve_upd_sts 9230 9230 0 0.00 0 15763 53 0 0
sp_reserve_write_off 9230 9230 0 0.00 6 15782 198 0 0
sp_supplier_invoice 5244 5244 0 0.00 8 93193 3398 0 0
sp_supplier_order 5182 5182 0 0.00 5 47004 1313 0 0
srv_aggregate_perf_data 116 116 0 0.00 2701 209838 18936 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2022 156669 48791 0 0
srv_find_qd_qs_mism 1071736 1071736 0 0.00 0 10093 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 35 75123 1122 0 0
srv_make_money_saldo 777 777 0 0.00 1 15481 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 184562 523478 412069 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4746 4746 0 0.00 0 25002 171 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 21799 283 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 26826 45 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 15735 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 1536 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 1052 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 533 12 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 25596 1782 0 0
t$perf-norm:sp_cancel_write_off 911 911 0 0.00 0 2814 10 0 0
t$perf-norm:sp_client_order 19474 19474 0 0.00 0 22761 21 0 0
t$perf-norm:sp_customer_reserve 899 899 0 0.00 0 7970 14 0 0
t$perf-norm:sp_pay_from_customer 6565 6565 0 0.00 0 12361 12 0 0
t$perf-norm:sp_pay_to_supplier 5430 5430 0 0.00 0 14743 16 0 0
t$perf-norm:sp_reserve_write_off 9230 9230 0 0.00 0 42519 18 0 0
t$perf-norm:sp_supplier_invoice 5244 5244 0 0.00 0 7909 21 0 0
t$perf-norm:sp_supplier_order 5182 5182 0 0.00 0 3511 16 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 45 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 26 1 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 8218 38 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 1088 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9760 9470 290 2.97 0 15268 27 0 290
v_all_customers 35784 35784 0 0.00 0 151 1 0 0
v_all_suppliers 28910 28910 0 0.00 0 175 1 0 0
v_all_wares 38944 38944 0 0.00 0 148 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 5431 13 0 0
v_cancel_client_order 3412 3412 0 0.00 0 13545 7 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 597 5 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 384 3 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 604 4 0 0
v_cancel_supplier_invoice 810 784 26 3.20 0 2063 16 0 26
v_cancel_supplier_order 842 842 0 0.00 0 2607 10 0 0
v_cancel_write_off 1822 1822 0 0.00 0 14617 21 0 0
v_max_id_clo_ord 5181 5181 0 0.00 0 492 1 0 0
v_max_id_clo_res 899 899 0 0.00 0 2053 13 0 0
v_max_id_ord_sup 5239 5239 0 0.00 0 3512 4 0 0
v_max_non_paid_invoice 1395 1395 0 0.00 0 1835 4 0 0
v_max_non_paid_realizn 3311 3311 0 0.00 0 1361 3 0 0
v_min_id_clo_ord 5181 5181 0 0.00 0 5934 8 0 0
v_min_id_clo_res 899 899 0 0.00 0 1452 4 0 0
v_min_id_ord_sup 5239 5239 0 0.00 0 4437 5 0 0
v_min_non_paid_invoice 5430 5430 0 0.00 0 15383 32 0 0
v_min_non_paid_realizn 6565 6565 0 0.00 0 15420 43 0 0
v_our_firm 9668 9668 0 0.00 0 17 1 0 0
v_reserve_write_off 18460 18460 0 0.00 0 14587 8 0 0
x_lock_depdocs_on_canc_invoice 12181 12181 0 0.00 0 9747 118 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 9871 371 0 0
x_make_qstorno_1000_1200 5182 5182 0 0.00 2 46519 1231 0 0
x_make_qstorno_1200_2000 5244 5244 0 0.00 4 93182 3263 0 0
x_make_qstorno_2100_3300 447023 447023 0 0.00 0 83107 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2022 19:40:30 to 09-01-2022 19:40:34.

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:10 3 8192000000 8798680400 8809971712 4622400 0.056 2 1 2 0 4721888 56704 42784
17:09:12 20547 8192000000 9894786864 10202652672 964218640 10.530 100 86 172 1 992555968 5278832 23058496
17:10:41 16761 8192000000 9940447424 10260365312 1013482048 11.009 100 90 178 1 1044375008 8216448 22676512
17:12:04 11170 8192000000 9979943136 10312413184 1051431968 11.374 100 80 160 0 1080239184 8869264 19937952
17:13:21 10511 8192000000 10042002832 10388164608 1111392096 11.946 100 87 174 0 1142691776 10495680 20804000
17:14:40 11229 8192000000 10095998832 10458988544 1168723472 12.485 100 83 166 2 1199824656 12223856 18877328
17:15:56 6458 8192000000 10139192720 10510299136 1212251456 12.890 100 85 169 1 1243168208 12102672 18814080
17:17:06 4101 8192000000 10169367152 10548318208 1245051200 13.193 100 84 166 0 1277155152 13825184 18278768
17:18:11 1473 8192000000 10209229152 10591715328 1279310896 13.507 100 88 176 0 1313839536 14781392 19747248
17:19:14 1884 8192000000 10227380016 10615660544 1294044064 13.641 100 93 186 2 1328660976 13278288 21338624
17:20:16 25929 8192000000 10230349152 10623582208 1299377792 13.690 100 71 144 1 1322792960 6069184 17345984
17:21:42 9201 8192000000 10201663504 10620305408 1300408864 13.699 100 29 57 1 1314294144 7126832 6758448
17:22:54 4581 8192000000 10207294128 10626781184 1306139168 13.751 100 30 60 1 1319572464 6823856 6609440
17:23:59 11584 8192000000 10211740256 10636509184 1314565904 13.827 100 20 40 0 1325852976 6192192 5094880
17:25:12 5439 8192000000 10219626160 10640035840 1322117888 13.896 100 18 36 0 1331430352 5500576 3811888
17:26:19 41316 8192000000 10250441440 10655502336 1323830304 13.911 100 70 135 2 1345403200 5842608 15730288
17:28:01 4195 8192000000 10191638096 10606768128 1287822784 13.584 100 28 56 0 1301079808 6874672 6382352
17:29:07 6743 8192000000 10164648240 10578165760 1270247264 13.424 99 16 31 0 1278977376 4961168 3768944
17:30:15 19880 8192000000 10147009856 10556047360 1239232592 13.139 97 20 39 0 1249607344 5655952 4718800
17:31:51 10963 8192000000 10121870544 10513772544 1198690672 12.764 96 24 48 1 1209436784 5371424 5374688
17:33:05 4974 8192000000 10152935552 10554511360 1259168368 13.322 100 39 78 2 1274942608 6003984 9770256
17:34:14 7419 8192000000 10178594704 10582896640 1281797056 13.529 100 39 78 1 1296481680 4998832 9685792
17:35:24 48204 8192000000 10178534784 10590973952 1287188544 13.579 100 16 32 0 1296211488 5536416 3486528
17:37:14 4681 8192000000 10191932144 10601889792 1294212720 13.643 100 35 70 1 1309434128 6979952 8241456
17:38:23 27650 8192000000 10214745712 10624462848 1312202784 13.806 100 36 73 0 1326278448 5930064 8145600
17:39:53 22892 8192000000 10244663616 10659389440 1337271648 14.033 100 39 75 3 1357557392 11890528 8395216
17:41:18 63589 8192000000 10274836752 10696069120 1360321952 14.240 100 38 76 3 1385103920 15432304 9349664
17:43:52 18905 8192000000 10291711104 10717552640 1378148608 14.400 100 33 66 2 1409800448 24007248 7644592
17:45:35 2372 8192000000 10312287664 10733473792 1389008864 14.497 100 60 120 2 1428185680 24071376 15105440
17:46:44 6701 8192000000 10327929792 10750918656 1400613824 14.600 100 50 97 0 1440691616 28361248 11716544
17:47:55 10266 8192000000 10358170768 10772197376 1402942704 14.621 99 58 116 2 1452623072 35259728 14420640
17:49:13 7263 8192000000 10354192656 10771234816 1405662112 14.645 100 74 148 2 1465441472 41480384 18298976
17:50:27 2022 8192000000 10368743776 10785153024 1411436864 14.697 100 76 149 2 1475570848 45229216 18904768
17:51:34 6999 8192000000 10362325120 10777001984 1404966480 14.639 100 78 156 2 1470376912 46290752 19119680
17:52:48 3583 8192000000 10347790880 10766454784 1388197792 14.490 99 61 121 1 1447401104 43719120 15484192
17:53:58 4870 8192000000 10360968256 10771427328 1386458864 14.474 99 81 158 2 1456781344 49702640 20619840
17:55:11 2641 8192000000 10359361648 10766020608 1383027824 14.444 99 86 173 1 1458317520 53775008 21514688
17:56:27 3488 8192000000 10358576352 10771746816 1391863696 14.522 100 69 137 0 1456742576 46802880 18076000
17:57:38 5787 8192000000 10359477824 10776580096 1388849872 14.496 99 57 113 2 1453718256 50180992 14687392
17:58:48 6726 8192000000 10349472944 10773188608 1382440608 14.438 100 47 94 1 1457737632 61943280 13353744
18:00:00 10995 8192000000 10353098944 10779635712 1393025600 14.533 100 38 75 2 1456199440 52383936 10789904
18:01:15 7278 8192000000 10361122816 10790629376 1386119536 14.471 99 48 96 0 1462072320 62685296 13267488
18:02:29 23513 8192000000 10352739712 10789007360 1390698592 14.512 100 39 77 1 1462569872 60963696 10907584
18:03:55 16371 8192000000 10343812528 10777309184 1384784192 14.459 100 24 47 2 1441637008 50238896 6613920
18:05:22 29494 8192000000 10327298688 10778869760 1362232624 14.257 99 19 38 0 1425567056 57568368 5766064
18:06:53 49522 8192000000 10292948496 10739339264 1373483744 14.358 100 25 50 0 1397928320 18594928 5849648
18:08:49 81892 8192000000 10289883760 10737328128 1349763680 14.145 97 24 48 1 1361846144 7349296 4733168
18:11:20 126302 8192000000 10298061408 10742226944 1356636464 14.207 97 34 66 0 1380760832 16503968 7620400
18:14:42 32713 8192000000 10276439840 10734067712 1361629184 14.252 99 7 14 0 1379564416 16636432 1298800
18:16:17 78986 8192000000 10288283232 10742210560 1377135024 14.391 99 17 34 2 1389425424 8319664 3970736
18:18:36 65335 8192000000 10287554624 10745143296 1378481856 14.403 99 20 39 1 1392824896 10153888 4189152
18:20:49 86901 8192000000 10291534176 10758647808 1379955264 14.416 99 20 39 0 1404565392 19967296 4642832
18:23:16 82016 8192000000 10287404016 10757136384 1393494784 14.537 100 13 26 0 1416623760 19718464 3410512
18:25:41 50528 8192000000 10284759408 10747379712 1385625456 14.467 100 30 60 0 1409390528 17065760 6699312
18:27:37 46690 8192000000 10293976992 10752765952 1385868080 14.469 99 19 38 0 1402530768 12177680 4485008
18:30:50 61356 8192000000 10289735504 10755637248 1389462480 14.501 99 19 38 1 1407752208 13342592 4947136
18:33:12 38821 8192000000 10298894736 10758942720 1383456496 14.447 99 27 54 0 1401531760 12238416 5836848
18:34:54 84719 8192000000 10307100192 10773938176 1371843312 14.344 98 9 17 0 1390389312 16529584 2016416
18:37:24 124537 8192000000 10306880496 10763628544 1301501776 13.709 92 17 34 0 1318387904 13444160 3441968
18:40:39 66650 8192000000 10314003600 10749751296 1337261168 14.033 95 26 52 1 1351825888 7979312 6585408
18:42:51 156669 8192000000 10308895536 10767147008 1334749296 14.010 95 6 12 0 1352551120 16655472 1146352
18:46:28 45851 8192000000 10320967072 10761154560 1393507920 14.537 99 50 100 1 1416064992 11341664 11215408
18:48:15 51507 8192000000 10286623728 10736455680 1357921888 14.219 97 49 98 2 1383734896 14802992 11010016
18:50:11 8149 8192000000 10295376736 10769301504 1395347456 14.554 100 14 28 1 1416299840 17408800 3543584
18:51:20 35377 8192000000 10284571968 10753609728 1390914960 14.514 100 10 20 0 1403252112 10027552 2309600
18:52:56 65416 8192000000 10262812192 10723184640 1333912048 14.002 97 13 26 0 1345643696 8671584 3060064
18:55:01 89493 8192000000 10254432608 10718109696 1285036960 13.559 93 15 30 0 1298849312 10573072 3239280
18:57:33 58287 8192000000 10268022112 10723139584 1338551424 14.044 97 19 38 2 1354433392 10689968 5192000
18:59:31 46266 8192000000 10274060976 10729422848 1345869296 14.110 98 31 62 1 1366655488 13494576 7291616
19:01:48 47886 8192000000 10273418752 10715439104 1354049328 14.184 98 17 34 0 1368905648 11464704 3391616
19:03:36 55080 8192000000 10291972064 10736910336 1346650832 14.117 98 27 54 1 1373064224 20445856 5967536
19:05:33 73210 8192000000 10263387632 10724700160 1315583408 13.837 96 11 22 0 1339326400 21332512 2410480
19:07:46 64923 8192000000 10270654704 10732355584 1332794912 13.992 97 10 20 0 1348234704 13078976 2360816
19:10:02 82715 8192000000 10289730336 10743316480 1377927216 14.398 100 25 48 2 1393486496 10274416 5284864
19:12:25 57761 8192000000 10283432544 10739494912 1377595296 14.395 99 8 16 0 1390531312 11443840 1492176
19:14:35 82962 8192000000 10290149088 10752495616 1370294016 14.330 98 8 16 1 1380611824 8815072 1502736
19:17:18 64083 8192000000 10312360080 10760364032 1377605920 14.395 98 36 72 1 1394415008 8814704 7994384
19:19:35 72828 8192000000 10305422384 10776109056 1377636080 14.395 98 15 30 0 1396896816 15729136 3531600
19:21:49 31450 8192000000 10294811072 10742693888 1372410560 14.349 98 20 40 2 1384275008 7781504 4082944
19:23:22 28846 8192000000 10297077728 10735280128 1357467680 14.215 98 28 56 1 1372636352 9116896 6051776
19:24:52 137382 8192000000 10304791024 10765524992 1347836032 14.128 97 19 38 0 1364306032 12441936 4028064
19:28:11 16704 8192000000 10305481248 10749038592 1390378048 14.509 100 42 83 1 1409078016 9598016 9101952
19:29:29 110297 8192000000 10288465536 10741714944 1357956496 14.219 97 21 42 0 1373675456 10559632 5159328
19:32:24 43014 8192000000 10267943040 10717540352 1346461360 14.116 97 14 28 0 1361078832 11234400 3383072
19:34:08 61643 8192000000 10268691840 10723495936 1331928960 13.985 97 12 24 0 1347262512 12448688 2884864
19:36:11 76813 8192000000 10276991088 10738507776 1373896256 14.362 100 11 22 1 1390598752 14133072 2569424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-01-2022 19:40:36 to 09-01-2022 19:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2022 19:40:51 to 09-01-2022 19:40:53.

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          230482
MON$OLDEST_ACTIVE               232469
MON$OLDEST_SNAPSHOT             232469
MON$NEXT_TRANSACTION            232469
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                       2029088
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 ---
:707: 
:708: mscs-test-161.budzdorov.ru    Sun Jan  9 19:38:10 2022
:709:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:710: 
:711: 
:712: mscs-test-161.budzdorov.ru    Sun Jan  9 19:38:30 2022
:713:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:714: 
--- end of diff output ---

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

09.01.22 19:40:54. 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-01-09 19:40:56 No severe PSQL-related problems occured
09.01.22 19:40:58. End of report.