Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.07.2022 22: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 15751088 kB
MemAvailable 31108240 kB
Buffers 0 kB
Cached 15983936 kB
SwapCached 2496 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.11
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-07-18 22:38:23
DTS_END                         2022-07-18 22:38:23.5880
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/36634
TRN_ID                          242204
ATT_ID                          628
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: 9665.95 20280 1159914
customer order: creation 178.31 367 21397
customer order: refuse 14.48 364 1737
order to supplier: creation 47.31 1258 5677
order to supplier: removal 3.53 100346 424
invoice (draft): creation 46.55 2904 5586
invoice (draft): removal 55.75 709 6690
invoice accept: apply 42.72 15839 5126
invoice accept: cancel 51.08 745 6129
customer reserve: creation 3959.44 155 475133
customer reserve: removal 4954.65 56 594558
realization accept: apply 85.74 174 10289
realization accept: cancel 94.19 108 11303
payment from customer: creation 60.98 113 7317
payment from customer: removal 5.88 88 705
payment to supplier: creation 50.12 140 6014
payment to supplier: removal 3.43 120 412
service: total inventory turnovers 4.52 1377 542
service: total monetary turnovers 6.53 56 783
service: aggregate perf. data 0.73 26334 88
service: refresh index statistics 0.03 254340 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-07-2022 22:40:02 to 18-07-2022 22:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2858
TEST_TIME 2 3670
TEST_TIME 3 3871
TEST_TIME 4 4170
TEST_TIME 5 4468
TEST_TIME 6 4268
TEST_TIME 7 4543
TEST_TIME 8 5149
TEST_TIME 9 5422
TEST_TIME 10 5565
TEST_TIME 11 5841
TEST_TIME 12 6066
TEST_TIME 13 6295
TEST_TIME 14 6996
TEST_TIME 15 7159
TEST_TIME 16 7827
TEST_TIME 17 7985
TEST_TIME 18 8445
TEST_TIME 19 8331
TEST_TIME 20 8662
TEST_TIME 21 8772
TEST_TIME 22 8977
TEST_TIME 23 9241
TEST_TIME 24 9286
TEST_TIME 25 9510
TEST_TIME 26 11098
TEST_TIME 27 11005
TEST_TIME 28 11069
TEST_TIME 29 11100
TEST_TIME 30 11288
TEST_TIME 31 11042
TEST_TIME 32 11035
TEST_TIME 33 10936
TEST_TIME 34 10833
TEST_TIME 35 10811
TEST_TIME 36 10624
TEST_TIME 37 10640
TEST_TIME 38 10550
TEST_TIME 39 10500
TEST_TIME 40 10476
TEST_TIME 41 10438
TEST_TIME 42 10334
TEST_TIME 43 10461
TEST_TIME 44 10471
TEST_TIME 45 10483
TEST_TIME 46 10371
TEST_TIME 47 10227
TEST_TIME 48 10185
TEST_TIME 49 10318
TEST_TIME 50 10333
TEST_TIME 51 10196
TEST_TIME 52 10135
TEST_TIME 53 10114
TEST_TIME 54 10138
TEST_TIME 55 10066
TEST_TIME 56 10135
TEST_TIME 57 10147
TEST_TIME 58 10060
TEST_TIME 59 10070
TEST_TIME 60 9957
TEST_TIME 61 10019
TEST_TIME 62 10010
TEST_TIME 63 9944
TEST_TIME 64 9975
TEST_TIME 65 9964
TEST_TIME 66 10006
TEST_TIME 67 9993
TEST_TIME 68 9961
TEST_TIME 69 9936
TEST_TIME 70 10052
TEST_TIME 71 10137
TEST_TIME 72 10082
TEST_TIME 73 10167
TEST_TIME 74 10179
TEST_TIME 75 10287
TEST_TIME 76 10377
TEST_TIME 77 10321
TEST_TIME 78 10191
TEST_TIME 79 10204
TEST_TIME 80 10164
TEST_TIME 81 10130
TEST_TIME 82 10136
TEST_TIME 83 10088
TEST_TIME 84 10040
TEST_TIME 85 9980
TEST_TIME 86 9927
TEST_TIME 87 9954
TEST_TIME 88 9931
TEST_TIME 89 9892
TEST_TIME 90 9903
TEST_TIME 91 9862
TEST_TIME 92 9845
TEST_TIME 93 9875
TEST_TIME 94 9981
TEST_TIME 95 9934
TEST_TIME 96 9931
TEST_TIME 97 9973
TEST_TIME 98 9956
TEST_TIME 99 9937
TEST_TIME 100 10093
TEST_TIME 101 10077
TEST_TIME 102 10077
TEST_TIME 103 10090
TEST_TIME 104 10136
TEST_TIME 105 10062
TEST_TIME 106 9992
TEST_TIME 107 9985
TEST_TIME 108 9945
TEST_TIME 109 9936
TEST_TIME 110 9925
TEST_TIME 111 9880
TEST_TIME 112 9858
TEST_TIME 113 9864
TEST_TIME 114 9847
TEST_TIME 115 9799
TEST_TIME 116 9732
TEST_TIME 117 9774
TEST_TIME 118 9738
TEST_TIME 119 9719
TEST_TIME 120 9669

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-07-2022 22:40:06 to 18-07-2022 22:40:09.

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 1601513 1601513 0 0.00 0 28120 6 0 0
doc_list_biud 1601512 1601512 0 0.00 0 35438 26 0 0
sp_add_invoice_to_stock 5256 5126 130 2.47 29 212496 15839 0 130
sp_cancel_adding_invoice 6129 6129 0 0.00 5 58067 745 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 15135 365 0 0
sp_cancel_customer_reserve 594558 594558 0 0.00 0 22184 56 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 2 5520 89 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 6253 120 0 0
sp_cancel_supplier_invoice 6704 6690 14 0.20 2 35445 709 0 14
sp_cancel_supplier_order 424 424 0 0.00 6 1264674 100347 0 0
sp_cancel_write_off 11303 11303 0 0.00 1 11378 109 0 0
sp_client_order 21397 21397 0 0.00 3 22480 367 0 0
sp_customer_reserve 475133 475133 0 0.00 0 44002 155 0 0
sp_fill_shopping_cart 32653 32653 0 0.00 1 23570 32 0 0
sp_fill_shopping_cart_clo_res 475123 475123 0 0.00 0 12996 13 0 0
sp_get_clo_for_invoice 5113 5113 0 0.00 0 30630 290 0 0
sp_kill_cost_storno 18549 18549 0 0.00 0 12813 54 0 0
sp_kill_qstorno_ret_qs2qd 1207533 1207533 0 0.00 0 35435 30 0 0
sp_kill_qty_storno 636240 636240 0 0.00 0 35437 65 0 0
sp_lock_selected_doc 21253 21253 0 0.00 0 8781 6 0 0
sp_make_cost_storno 28731 28731 0 0.00 0 27927 61 0 0
sp_multiply_rows_for_pdistr 28730 28730 0 0.00 0 9319 26 0 0
sp_multiply_rows_for_qdistr 21397 21397 0 0.00 0 13502 159 0 0
sp_pay_from_customer 7317 7317 0 0.00 4 12782 114 0 0
sp_pay_to_supplier 6014 6014 0 0.00 4 28202 140 0 0
sp_payment_common 13331 13331 0 0.00 1 10940 27 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 15114 329 0 0
sp_qd_handle_on_invoice_upd_sts 11239 11239 0 0.00 0 25362 316 0 0
sp_qd_handle_on_reserve_upd_sts 10289 10289 0 0.00 0 15928 55 0 0
sp_reserve_write_off 10289 10289 0 0.00 5 32392 175 0 0
sp_supplier_invoice 5586 5586 0 0.00 6 142502 2904 0 0
sp_supplier_order 5677 5677 0 0.00 5 50924 1259 0 0
srv_aggregate_perf_data 88 88 0 0.00 2244 430874 26335 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 915 163784 44593 0 0
srv_find_qd_qs_mism 1111658 1111658 0 0.00 0 14017 4 0 0
srv_make_invnt_saldo 542 542 0 0.00 45 133729 1378 0 0
srv_make_money_saldo 783 783 0 0.00 0 19236 57 0 0
srv_recalc_idx_stat 4 4 0 0.00 158469 303919 254340 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5126 5126 0 0.00 0 22796 186 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 13063 93 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 1770 14 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 3213 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 9346 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 2381 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1366 13 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 22321 1817 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 6335 14 0 0
t$perf-norm:sp_client_order 21397 21397 0 0.00 0 9193 23 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 6649 15 0 0
t$perf-norm:sp_pay_from_customer 7317 7317 0 0.00 0 11683 16 0 0
t$perf-norm:sp_pay_to_supplier 6014 6014 0 0.00 0 11649 16 0 0
t$perf-norm:sp_reserve_write_off 10289 10289 0 0.00 0 9218 11 0 0
t$perf-norm:sp_supplier_invoice 5586 5586 0 0.00 0 8820 19 0 0
t$perf-norm:sp_supplier_order 5677 5677 0 0.00 0 3787 15 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 715 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 57 1 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 2048 26 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 2067 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10480 10220 260 2.48 0 6443 21 0 260
v_all_customers 39250 39250 0 0.00 0 19 1 0 0
v_all_suppliers 31372 31372 0 0.00 0 35 1 0 0
v_all_wares 42794 42794 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2709 11 0 0
v_cancel_client_order 3474 3474 0 0.00 0 3163 6 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 547 3 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 6004 14 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2467 12 0 0
v_cancel_supplier_invoice 842 814 28 3.32 0 7418 32 0 28
v_cancel_supplier_order 848 848 0 0.00 0 4601 11 0 0
v_cancel_write_off 2082 2082 0 0.00 0 3131 16 0 0
v_max_id_clo_ord 5675 5675 0 0.00 0 481 1 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 2439 12 0 0
v_max_id_ord_sup 5580 5580 0 0.00 0 3019 3 0 0
v_max_non_paid_invoice 1583 1583 0 0.00 0 505 3 0 0
v_max_non_paid_realizn 3753 3753 0 0.00 0 4967 6 0 0
v_min_id_clo_ord 5675 5675 0 0.00 0 5359 7 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 8421 28 0 0
v_min_id_ord_sup 5580 5580 0 0.00 0 11748 8 0 0
v_min_non_paid_invoice 6014 6014 0 0.00 0 5494 14 0 0
v_min_non_paid_realizn 7317 7317 0 0.00 0 9514 23 0 0
v_our_firm 10672 10672 0 0.00 0 23 1 0 0
v_reserve_write_off 20578 20578 0 0.00 0 4802 8 0 0
x_lock_depdocs_on_canc_invoice 12412 12412 0 0.00 0 21317 125 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 22821 497 0 0
x_make_qstorno_1000_1200 5677 5677 0 0.00 1 50915 1186 0 0
x_make_qstorno_1200_2000 5586 5586 0 0.00 2 142356 2797 0 0
x_make_qstorno_2100_3300 475133 475133 0 0.00 0 43960 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-07-2022 22:40:10 to 18-07-2022 22:40:14.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:27 13 8192000000 8808470304 8823017472 12218352 0.148 5 2 3 0 12363456 70160 74944
20:09:28 26909 8192000000 9903964912 10215059456 986210896 10.745 100 91 182 0 1016807552 6186320 24410336
20:10:58 19157 8192000000 9941494080 10264403968 1025503056 11.125 100 88 176 0 1056564656 8192592 22869008
20:12:23 10386 8192000000 9979815760 10309734400 1061739792 11.473 100 89 178 0 1093645840 9103824 22802224
20:13:42 14810 8192000000 10019919040 10366914560 1110487856 11.937 100 82 162 0 1139569552 9624864 19456832
20:14:59 5797 8192000000 10070289968 10428780544 1165077552 12.451 100 80 160 0 1195214896 11995520 18141824
20:16:06 4485 8192000000 10121602864 10487664640 1206383264 12.836 100 87 172 1 1240223312 14231296 19608752
20:17:13 2804 8192000000 10158596720 10534404096 1249728448 13.236 100 80 157 0 1278469040 12122416 16618176
20:18:17 939 8192000000 10189562608 10571149312 1275097616 13.468 100 88 175 1 1308834784 14565024 19172144
20:19:20 1157 8192000000 10201189408 10589655040 1292590928 13.628 100 76 154 1 1320434128 10947328 16895872
20:20:23 1874 8192000000 10210988112 10608005120 1303407984 13.726 100 65 129 2 1329039056 11466032 14165040
20:21:25 2824 8192000000 10208874512 10617847808 1312920416 13.813 100 40 80 1 1334766944 13235008 8611520
20:22:30 8150 8192000000 10207378192 10621968384 1320415584 13.880 100 19 38 0 1330651168 6147888 4087696
20:23:44 42124 8192000000 10229173296 10635255808 1324876096 13.921 100 46 91 1 1340903936 4603840 11424000
20:25:27 5018 8192000000 10219700560 10639749120 1317136992 13.851 99 25 50 0 1329181376 6738256 5306128
20:26:37 13866 8192000000 10228167536 10652053504 1337526928 14.035 100 23 43 0 1347254768 5480528 4247312
20:27:54 29277 8192000000 10212348368 10636701696 1299599664 13.692 97 28 55 0 1311588464 5475888 6512912
20:29:23 5579 8192000000 10193846656 10611933184 1286728864 13.574 98 20 39 1 1297090704 5424384 4937456
20:30:30 12262 8192000000 10139214784 10544214016 1211163152 12.880 96 27 55 0 1222802720 5932848 5706720
20:31:44 17794 8192000000 10140656608 10539741184 1208662192 12.857 96 29 56 0 1220457600 5386528 6408880
20:33:05 717 8192000000 10151547200 10540797952 1251646976 13.253 100 76 152 0 1276176992 6420096 18109920
20:34:07 5897 8192000000 10164782880 10571280384 1276327968 13.479 100 36 72 1 1290614096 6493408 7792720
20:35:13 5486 8192000000 10184819072 10596163584 1295978368 13.659 100 28 57 0 1308552864 6393808 6180688
20:36:20 11031 8192000000 10201574640 10616438784 1311394736 13.799 100 13 27 0 1319654832 5490112 2769984
20:37:34 33095 8192000000 10207148896 10627706880 1316563472 13.846 100 26 53 3 1328475744 5760608 6151664
20:39:16 17247 8192000000 10244830096 10653786112 1340019072 14.058 100 47 94 0 1359099280 8598688 10481520
20:40:40 19953 8192000000 10268478976 10684874752 1358498416 14.224 100 49 98 1 1383358912 13438128 11422368
20:42:20 15346 8192000000 10279103104 10701996032 1374064304 14.363 100 28 56 0 1394626544 13378960 7183280
20:43:42 53531 8192000000 10313623488 10735288320 1388432432 14.492 100 42 84 0 1417163216 18495472 10235312
20:45:40 6032 8192000000 10331128208 10753056768 1407584704 14.662 100 56 112 0 1447902928 25448240 14869984
20:46:51 7860 8192000000 10333881760 10761142272 1409756432 14.682 100 39 77 0 1447054080 27685872 9611776
20:48:04 1685 8192000000 10357227936 10775478272 1420675904 14.779 100 74 148 0 1467964256 29955376 17332976
20:49:13 2917 8192000000 10371077824 10791292928 1428904128 14.852 100 69 136 2 1480970800 35240224 16826448
20:50:19 4743 8192000000 10369703152 10792136704 1423706064 14.806 100 64 126 0 1475931888 36356528 15869296
20:51:28 903 8192000000 10385913824 10803380224 1427338688 14.838 100 92 183 0 1490154944 40302400 22513856
20:52:32 3678 8192000000 10348085152 10757853184 1388042624 14.488 99 89 176 0 1453693136 44115232 21535280
20:53:42 3714 8192000000 10349684704 10759708672 1387153520 14.480 100 71 142 0 1452409152 47370688 17884944
20:54:55 1650 8192000000 10350473520 10757128192 1386964896 14.479 100 87 174 1 1456164624 48455696 20744032
20:56:03 2004 8192000000 10369161440 10775863296 1392631888 14.529 100 87 168 1 1466684752 51979648 22073216
20:57:08 13831 8192000000 10334120384 10759065600 1370577184 14.332 99 33 66 0 1431041168 50993488 9470496
20:58:32 13259 8192000000 10341391264 10770055168 1370702688 14.333 98 40 78 0 1426799072 45189536 10906848
20:59:54 16527 8192000000 10337034608 10761601024 1360339360 14.240 98 37 73 0 1419381552 48996672 10045520
21:01:14 17823 8192000000 10337254816 10763829248 1379156528 14.409 100 33 66 1 1438335232 49783888 9394816
21:02:33 22250 8192000000 10315770352 10747817984 1342246160 14.078 98 27 54 0 1404658544 54363920 8048464
21:03:56 29937 8192000000 10301227008 10749808640 1351409488 14.160 99 21 42 0 1426427248 68864720 6153040
21:05:28 32033 8192000000 10294946288 10732019712 1363616256 14.270 99 34 64 0 1387459584 16080592 7762736
21:07:07 56530 8192000000 10291803072 10752241664 1357723152 14.217 99 11 22 0 1380171968 19871904 2576912
21:09:11 53025 8192000000 10289873184 10720931840 1316416864 13.844 95 18 36 1 1327278816 7149104 3712848
21:11:11 68835 8192000000 10290026992 10734706688 1355298032 14.195 98 15 28 0 1370782448 12965952 2518464
21:13:20 86678 8192000000 10273058560 10714279936 1334719360 14.010 97 7 14 0 1347121920 10983792 1418768
21:15:51 45429 8192000000 10281892672 10728472576 1361722448 14.253 99 5 10 0 1372651440 9977152 951840
21:17:37 21662 8192000000 10275302432 10710982656 1349734720 14.145 98 25 50 0 1365291344 10154656 5401968
21:19:02 62900 8192000000 10293039888 10741272576 1370994656 14.336 99 12 24 0 1381431728 7734304 2702768
21:21:06 44647 8192000000 10298629856 10757353472 1378745088 14.405 99 16 33 0 1397180160 14535024 3900048
21:22:55 90132 8192000000 10314089696 10753679360 1396832000 14.567 100 20 40 0 1409821184 8276160 4713024
21:25:30 117953 8192000000 10331894256 10783457280 1393037008 14.533 99 26 52 0 1417273056 17802672 6433376
21:28:30 78695 8192000000 10309681632 10770124800 1394662320 14.547 99 13 27 0 1407240208 9463424 3114464
21:31:02 37525 8192000000 10300680096 10752540672 1345669712 14.108 96 16 32 0 1365404096 16080096 3654288
21:32:52 32955 8192000000 10335273312 10776117248 1400367648 14.598 100 44 89 0 1427174128 16423280 10383200
21:34:25 50697 8192000000 10298616416 10775949312 1395315776 14.553 100 10 20 0 1419785680 22081280 2388624
21:36:16 39830 8192000000 10310909616 10764652544 1384808944 14.460 99 18 36 1 1401789264 13116384 3863936
21:37:56 50956 8192000000 10293896480 10749456384 1368812784 14.316 98 18 36 0 1382323904 9785344 3725776
21:39:54 33528 8192000000 10285185616 10758172672 1388815104 14.495 100 15 30 0 1402395136 10191216 3388816
21:41:30 50334 8192000000 10271655552 10725437440 1328717392 13.956 96 18 36 0 1340649408 8154544 3777472
21:43:21 15255 8192000000 10286262272 10730192896 1352530880 14.170 98 30 61 0 1367317488 8137424 6649184
21:44:42 60666 8192000000 10247886768 10692845568 1253719200 13.272 92 18 36 0 1270148000 12649168 3779632
21:46:44 57099 8192000000 10267260976 10715639808 1335430336 14.016 98 18 34 0 1355026800 15612016 3984448
21:48:44 47585 8192000000 10257489296 10708791296 1336899648 14.029 99 12 22 1 1350015360 10698928 2416784
21:50:31 43086 8192000000 10293905136 10737844224 1368121328 14.310 100 40 80 0 1390930208 13702928 9105952
21:52:19 104495 8192000000 10290993216 10759831552 1333331632 13.997 97 5 10 0 1353239264 18834160 1073472
21:55:36 95095 8192000000 10272153584 10723196928 1339888576 14.056 98 32 64 1 1360803088 13176928 7737584
21:58:22 30787 8192000000 10274731344 10723610624 1351767952 14.163 99 38 76 0 1377528384 17789856 7970576
21:59:59 16586 8192000000 10269836064 10716577792 1361770208 14.253 100 46 93 0 1385280464 13307680 10202576
22:01:31 57079 8192000000 10258592624 10718437376 1338479888 14.044 98 11 22 0 1348732768 7780096 2472784
22:03:28 36174 8192000000 10256884064 10703224832 1338016992 14.040 98 14 28 0 1353716336 12643920 3055424
22:05:05 96793 8192000000 10261390928 10722848768 1335741856 14.019 98 12 24 0 1358508288 19573392 3193040
22:07:43 44559 8192000000 10265537104 10713956352 1346036592 14.112 99 23 46 2 1364635392 13121776 5477024
22:09:31 62471 8192000000 10275893344 10741895168 1363167088 14.266 99 14 24 0 1373753920 8177200 2409632
22:11:35 88129 8192000000 10284505600 10756640768 1360014384 14.237 98 15 30 0 1381858912 18363696 3480832
22:14:15 65338 8192000000 10297472624 10758590464 1376670224 14.387 99 22 44 0 1397183008 15521328 4991456
22:16:34 64129 8192000000 10314717600 10767155200 1378147920 14.400 99 17 34 0 1393526432 11774816 3603696
22:19:25 163784 8192000000 10284359776 10739978240 1236379456 13.113 89 6 10 1 1251483936 14039152 1065328
22:23:09 78163 8192000000 10291097648 10743508992 1362469568 14.260 99 16 32 1 1379179040 13586672 3122800
22:25:44 45216 8192000000 10273523664 10729021440 1360822304 14.245 99 17 34 0 1373269552 8413760 4033488
22:27:50 44750 8192000000 10276177040 10734850048 1340267152 14.060 97 19 38 1 1351166416 6834224 4065040
22:29:41 36163 8192000000 10286522496 10745110528 1381378608 14.429 100 10 20 0 1393300048 9596992 2324448
22:31:17 55272 8192000000 10278579920 10749669376 1381650688 14.431 100 16 32 0 1399349088 14274576 3423824
22:33:13 92978 8192000000 10286769312 10752032768 1385341264 14.464 100 17 34 0 1398511696 8653360 4517072
22:37:00 42971 8192000000 10289166000 10746355712 1358605136 14.225 98 20 40 0 1376377344 13480352 4291856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-07-2022 22:40:16 to 18-07-2022 22:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-07-2022 22:40:32 to 18-07-2022 22:40:34.

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          239341
MON$OLDEST_ACTIVE               242613
MON$OLDEST_SNAPSHOT             242613
MON$NEXT_TRANSACTION            242613
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                       2071264
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Mon Jul 18 22:38:30 2022
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Mon Jul 18 22:38:31 2022
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Mon Jul 18 22:38:31 2022
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:163: 
--- end of diff output ---

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

18.07.22 22:40:35. 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-07-18 22:40:37 No severe PSQL-related problems occured
18.07.22 22:40:39. End of report.