Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.07.2022 19: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 16323408 kB
MemAvailable 31114580 kB
Buffers 0 kB
Cached 15368980 kB
SwapCached 2544 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-07 19:38:22
DTS_END                         2022-07-07 19:38:22.5290
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/37988
TRN_ID                          247620
ATT_ID                          711
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: 9324.29 19093 1118915
customer order: creation 179.08 379 21490
customer order: refuse 14.75 337 1770
order to supplier: creation 46.93 1164 5631
order to supplier: removal 3.38 100402 406
invoice (draft): creation 47.73 3314 5727
invoice (draft): removal 54.75 713 6570
invoice accept: apply 42.61 17140 5113
invoice accept: cancel 48.99 788 5879
customer reserve: creation 3842.41 174 461089
customer reserve: removal 4741.30 57 568956
realization accept: apply 86.34 157 10361
realization accept: cancel 82.42 103 9890
payment from customer: creation 61.81 120 7417
payment from customer: removal 5.65 73 678
payment to supplier: creation 49.99 112 5999
payment to supplier: removal 3.33 79 400
service: total inventory turnovers 4.85 1011 582
service: total monetary turnovers 7.05 25 846
service: aggregate perf. data 0.89 14399 107
service: refresh index statistics 0.03 241306 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 19:39:17 to 07-07-2022 19:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5369
TEST_TIME 2 4881
TEST_TIME 3 3837
TEST_TIME 4 3922
TEST_TIME 5 4773
TEST_TIME 6 5055
TEST_TIME 7 5021
TEST_TIME 8 5144
TEST_TIME 9 5168
TEST_TIME 10 4979
TEST_TIME 11 5111
TEST_TIME 12 5100
TEST_TIME 13 5181
TEST_TIME 14 5423
TEST_TIME 15 5576
TEST_TIME 16 5640
TEST_TIME 17 5910
TEST_TIME 18 6364
TEST_TIME 19 7767
TEST_TIME 20 8435
TEST_TIME 21 8565
TEST_TIME 22 8750
TEST_TIME 23 9285
TEST_TIME 24 9421
TEST_TIME 25 10177
TEST_TIME 26 10169
TEST_TIME 27 10199
TEST_TIME 28 10168
TEST_TIME 29 9855
TEST_TIME 30 9897
TEST_TIME 31 9742
TEST_TIME 32 9647
TEST_TIME 33 9537
TEST_TIME 34 9754
TEST_TIME 35 9625
TEST_TIME 36 9564
TEST_TIME 37 9564
TEST_TIME 38 9389
TEST_TIME 39 9281
TEST_TIME 40 9215
TEST_TIME 41 9217
TEST_TIME 42 9350
TEST_TIME 43 9451
TEST_TIME 44 9522
TEST_TIME 45 9441
TEST_TIME 46 9489
TEST_TIME 47 9516
TEST_TIME 48 9535
TEST_TIME 49 9548
TEST_TIME 50 9625
TEST_TIME 51 9798
TEST_TIME 52 9843
TEST_TIME 53 9838
TEST_TIME 54 9802
TEST_TIME 55 9696
TEST_TIME 56 9590
TEST_TIME 57 9490
TEST_TIME 58 9471
TEST_TIME 59 9507
TEST_TIME 60 9428
TEST_TIME 61 9340
TEST_TIME 62 9402
TEST_TIME 63 9349
TEST_TIME 64 9430
TEST_TIME 65 9410
TEST_TIME 66 9453
TEST_TIME 67 9489
TEST_TIME 68 9458
TEST_TIME 69 9428
TEST_TIME 70 9487
TEST_TIME 71 9458
TEST_TIME 72 9427
TEST_TIME 73 9559
TEST_TIME 74 9577
TEST_TIME 75 9515
TEST_TIME 76 9606
TEST_TIME 77 9537
TEST_TIME 78 9448
TEST_TIME 79 9411
TEST_TIME 80 9360
TEST_TIME 81 9335
TEST_TIME 82 9297
TEST_TIME 83 9319
TEST_TIME 84 9296
TEST_TIME 85 9226
TEST_TIME 86 9206
TEST_TIME 87 9162
TEST_TIME 88 9185
TEST_TIME 89 9178
TEST_TIME 90 9190
TEST_TIME 91 9198
TEST_TIME 92 9265
TEST_TIME 93 9213
TEST_TIME 94 9358
TEST_TIME 95 9355
TEST_TIME 96 9416
TEST_TIME 97 9359
TEST_TIME 98 9393
TEST_TIME 99 9412
TEST_TIME 100 9434
TEST_TIME 101 9440
TEST_TIME 102 9368
TEST_TIME 103 9304
TEST_TIME 104 9312
TEST_TIME 105 9294
TEST_TIME 106 9251
TEST_TIME 107 9201
TEST_TIME 108 9175
TEST_TIME 109 9145
TEST_TIME 110 9242
TEST_TIME 111 9227
TEST_TIME 112 9234
TEST_TIME 113 9201
TEST_TIME 114 9223
TEST_TIME 115 9295
TEST_TIME 116 9354
TEST_TIME 117 9362
TEST_TIME 118 9326
TEST_TIME 119 9339
TEST_TIME 120 9325

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-07-2022 19:39:20 to 07-07-2022 19:39:24.

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 1546362 1546362 0 0.00 0 15733 6 0 0
doc_list_biud 1546328 1546328 0 0.00 0 44507 27 0 0
sp_add_invoice_to_stock 5225 5113 112 2.14 31 211281 17141 0 112
sp_cancel_adding_invoice 5879 5879 0 0.00 4 61010 789 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 16352 338 0 0
sp_cancel_customer_reserve 568956 568956 0 0.00 0 44508 57 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 7379 73 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 2 8888 80 0 0
sp_cancel_supplier_invoice 6577 6570 7 0.10 1 14946 714 0 7
sp_cancel_supplier_order 406 406 0 0.00 5 1070688 100402 0 0
sp_cancel_write_off 9890 9890 0 0.00 1 9664 104 0 0
sp_client_order 21490 21490 0 0.00 3 44000 380 0 0
sp_customer_reserve 461089 461089 0 0.00 0 46186 175 0 0
sp_fill_shopping_cart 32842 32842 0 0.00 1 32798 39 0 0
sp_fill_shopping_cart_clo_res 461088 461088 0 0.00 0 17780 15 0 0
sp_get_clo_for_invoice 5111 5111 0 0.00 0 20086 273 0 0
sp_kill_cost_storno 16847 16847 0 0.00 0 15724 50 0 0
sp_kill_qstorno_ret_qs2qd 1154778 1154778 0 0.00 0 44474 31 0 0
sp_kill_qty_storno 608923 608923 0 0.00 0 44507 67 0 0
sp_lock_selected_doc 21307 21307 0 0.00 0 7932 6 0 0
sp_make_cost_storno 28888 28888 0 0.00 0 10840 44 0 0
sp_multiply_rows_for_pdistr 28855 28855 0 0.00 0 11917 28 0 0
sp_multiply_rows_for_qdistr 21490 21490 0 0.00 0 43993 190 0 0
sp_pay_from_customer 7417 7417 0 0.00 4 17629 121 0 0
sp_pay_to_supplier 5999 5999 0 0.00 5 10354 112 0 0
sp_payment_common 13403 13403 0 0.00 1 13354 32 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 16344 302 0 0
sp_qd_handle_on_invoice_upd_sts 10978 10978 0 0.00 0 39790 327 0 0
sp_qd_handle_on_reserve_upd_sts 10353 10353 0 0.00 0 23763 49 0 0
sp_reserve_write_off 10361 10361 0 0.00 5 23773 158 0 0
sp_supplier_invoice 5727 5727 0 0.00 6 114530 3314 0 0
sp_supplier_order 5631 5631 0 0.00 5 41598 1164 0 0
srv_aggregate_perf_data 107 107 0 0.00 2921 119394 14399 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1351 135876 46207 0 0
srv_find_qd_qs_mism 1070389 1070389 0 0.00 0 14360 6 0 0
srv_make_invnt_saldo 582 582 0 0.00 33 69830 1011 0 0
srv_make_money_saldo 846 846 0 0.00 0 6259 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 164366 321591 241307 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5113 5113 0 0.00 0 12171 142 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 2978 74 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 11270 20 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 2066 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 3456 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1074 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 575 5 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 27466 1393 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 7739 11 0 0
t$perf-norm:sp_client_order 21490 21490 0 0.00 0 11469 14 0 0
t$perf-norm:sp_customer_reserve 1094 1094 0 0.00 0 5278 10 0 0
t$perf-norm:sp_pay_from_customer 7417 7417 0 0.00 0 6593 10 0 0
t$perf-norm:sp_pay_to_supplier 5999 5999 0 0.00 0 4710 7 0 0
t$perf-norm:sp_reserve_write_off 10361 10361 0 0.00 0 7239 7 0 0
t$perf-norm:sp_supplier_invoice 5727 5727 0 0.00 0 5433 14 0 0
t$perf-norm:sp_supplier_order 5631 5631 0 0.00 0 6575 12 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 287 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 95 2 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 1125 15 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 1637 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 10420 10196 224 2.14 0 4593 16 0 224
v_all_customers 39034 39034 0 0.00 0 29 0 0 0
v_all_suppliers 31146 31146 0 0.00 0 24 0 0 0
v_all_wares 42980 42980 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 1017 7 0 0
v_cancel_client_order 3540 3540 0 0.00 0 1419 4 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 1754 7 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 3620 10 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 5987 11 0 0
v_cancel_supplier_invoice 864 850 14 1.62 0 1123 14 0 14
v_cancel_supplier_order 812 812 0 0.00 0 967 5 0 0
v_cancel_write_off 1986 1986 0 0.00 0 1595 13 0 0
v_max_id_clo_ord 5630 5630 0 0.00 0 1805 2 0 0
v_max_id_clo_res 1094 1094 0 0.00 0 3163 22 0 0
v_max_id_ord_sup 5722 5722 0 0.00 0 5792 6 0 0
v_max_non_paid_invoice 1772 1772 0 0.00 0 251 2 0 0
v_max_non_paid_realizn 4022 4022 0 0.00 0 4373 5 0 0
v_min_id_clo_ord 5630 5630 0 0.00 0 4123 10 0 0
v_min_id_clo_res 1094 1094 0 0.00 0 1849 3 0 0
v_min_id_ord_sup 5722 5722 0 0.00 0 32795 15 0 0
v_min_non_paid_invoice 5993 5993 0 0.00 0 10305 19 0 0
v_min_non_paid_realizn 7410 7410 0 0.00 0 13345 30 0 0
v_our_firm 10722 10722 0 0.00 0 15 1 0 0
v_reserve_write_off 20706 20706 0 0.00 0 19869 9 0 0
x_lock_depdocs_on_canc_invoice 12024 12024 0 0.00 0 12172 100 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 15645 370 0 0
x_make_qstorno_1000_1200 5631 5631 0 0.00 1 41588 1099 0 0
x_make_qstorno_1200_2000 5727 5727 0 0.00 2 114084 3158 0 0
x_make_qstorno_2100_3300 461089 461089 0 0.00 0 46184 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-07-2022 19:39:25 to 07-07-2022 19:39:29.

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:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:27 16343 8192000000 9903177856 10211794944 985152064 10.734 100 88 176 1 1014434544 6461392 22821088
17:10:46 15049 8192000000 9951544448 10270597120 1029737872 11.166 100 92 184 0 1061721296 8282096 23701328
17:12:06 17818 8192000000 9986156432 10314764288 1066662272 11.520 100 83 164 0 1096237680 9233888 20341520
17:13:30 10767 8192000000 10025875088 10368000000 1108956400 11.923 100 83 166 0 1139471504 10501824 20013280
17:14:47 7406 8192000000 10073825120 10428329984 1158958944 12.394 100 84 168 0 1191188144 12494336 19734864
17:15:58 4094 8192000000 10123830432 10483097600 1198085600 12.759 100 88 176 0 1236452880 18621136 19746144
17:17:03 2526 8192000000 10152228560 10524033024 1237578208 13.124 100 81 162 1 1268379952 12132112 18669632
17:18:08 1825 8192000000 10181154080 10559696896 1267494032 13.399 100 86 170 1 1299572368 13069392 19008944
17:19:15 1301 8192000000 10208517360 10590892032 1293600464 13.637 100 86 170 0 1325537152 12788320 19148368
17:20:16 12484 8192000000 10187605776 10598182912 1299028736 13.686 100 43 86 0 1316286784 6733952 10524096
17:21:36 9809 8192000000 10195100176 10608599040 1310120000 13.787 100 11 22 0 1318661008 5424800 3116208
17:22:51 11074 8192000000 10205580224 10622660608 1318426144 13.862 100 16 30 1 1328149808 6069776 3653888
17:24:02 43873 8192000000 10211805600 10634649600 1322305456 13.898 100 28 56 0 1334405408 4661760 7438192
17:25:46 6469 8192000000 10224044528 10645344256 1332340704 13.988 100 27 54 1 1344951296 6424384 6186208
17:26:53 8169 8192000000 10234521824 10657955840 1339592736 14.054 100 15 30 0 1347867312 5200240 3074336
17:28:04 9592 8192000000 10205135024 10625867776 1296731808 13.666 98 13 26 0 1305069216 5555328 2782080
17:29:21 29628 8192000000 10162223120 10575331328 1200429824 12.780 92 15 30 0 1208986864 4755280 3801760
17:30:51 4662 8192000000 10162421040 10564214784 1265439136 13.380 99 19 36 0 1276024432 6361040 4224256
17:31:56 7277 8192000000 10148647056 10557030400 1242293248 13.167 98 14 29 1 1250446592 4776592 3376752
17:33:05 23816 8192000000 10149300432 10563096576 1240674976 13.152 97 26 53 1 1252163552 5158944 6329632
17:34:52 3703 8192000000 10192355360 10599145472 1291415808 13.617 100 42 84 0 1308112144 7167024 9529312
17:35:56 5968 8192000000 10197589008 10611740672 1305658560 13.747 100 23 46 0 1316093120 4875200 5559360
17:37:08 9768 8192000000 10214441200 10624471040 1309014336 13.777 100 27 55 0 1324746128 9704144 6027648
17:38:18 23147 8192000000 10233711184 10642128896 1318381952 13.862 99 39 76 0 1332990896 5879616 8729328
17:39:46 95761 8192000000 10258253392 10684764160 1348048144 14.130 100 35 70 0 1369003088 12066864 8888080
17:42:23 16504 8192000000 10295077584 10711941120 1372638112 14.351 100 46 91 1 1398661408 15586800 10436496
17:43:52 10813 8192000000 10300211152 10728239104 1383690608 14.450 100 46 91 0 1415466720 20282096 11494016
17:45:09 16321 8192000000 10317178368 10738229248 1394782384 14.549 100 46 91 1 1423353008 18097200 10473424
17:46:29 31435 8192000000 10326505104 10754564096 1402535104 14.618 100 45 90 1 1441250016 28288672 10426240
17:48:04 8615 8192000000 10336276672 10762842112 1410055088 14.684 100 40 77 0 1444462208 24269312 10137808
17:49:19 3411 8192000000 10356116608 10774126592 1420439056 14.777 100 65 130 0 1468198992 32150064 15609872
17:50:28 3586 8192000000 10373024512 10787225600 1424885360 14.816 100 70 140 2 1480355856 38090912 17379584
17:51:34 3386 8192000000 10381825616 10792988672 1424636624 14.814 100 74 148 0 1486368256 42507856 19223776
17:52:41 2479 8192000000 10390386400 10796261376 1425228224 14.819 100 81 161 0 1492408544 46373280 20807040
17:53:49 8028 8192000000 10384594160 10801573888 1416415792 14.741 99 50 100 1 1481948544 51789568 13743184
17:54:59 15106 8192000000 10383194240 10797252608 1411344864 14.696 100 61 121 0 1487148496 59166000 16637632
17:56:28 1351 8192000000 10378260416 10792632320 1396473440 14.564 99 66 132 1 1477029008 63010384 17545184
17:57:34 11846 8192000000 10358060432 10781618176 1388383792 14.491 100 46 90 0 1459072240 57935984 12752464
17:58:49 26237 8192000000 10307239568 10736222208 1354796384 14.191 99 29 59 0 1406800624 43595792 8408448
18:00:20 22843 8192000000 10291814128 10719989760 1329702224 13.964 98 21 42 1 1384625088 48852224 6070640
18:01:52 22814 8192000000 10252385104 10690084864 1289589952 13.600 96 18 36 0 1330914640 36128896 5195792
18:03:22 46749 8192000000 10218621088 10654412800 1273237136 13.451 96 13 24 0 1292365712 16390016 2738560
18:05:12 49929 8192000000 10257951792 10671656960 1307304416 13.762 98 39 79 0 1330108528 14019744 8784368
18:07:12 93211 8192000000 10265375696 10703888384 1336713392 14.028 99 37 73 0 1355961120 11602976 7644752
18:09:59 17187 8192000000 10273073568 10715123712 1365064096 14.283 100 33 67 1 1383447456 10536512 7846848
18:11:27 64944 8192000000 10266342096 10717270016 1332370224 13.989 97 21 40 0 1351613344 13898528 5344592
18:13:38 47399 8192000000 10282672944 10714046464 1346762048 14.118 98 20 39 0 1359345504 7889280 4694176
18:15:35 24001 8192000000 10278921456 10738462720 1379702032 14.414 100 10 20 0 1393461712 11249360 2510320
18:17:04 69888 8192000000 10292578576 10750930944 1388260896 14.490 100 22 44 0 1408366368 14873680 5231792
18:19:46 51723 8192000000 10288033216 10752032768 1386810592 14.477 100 22 43 1 1409476064 17339568 5325904
18:21:39 59376 8192000000 10290788336 10751819776 1385231488 14.463 100 11 22 0 1395569008 8035648 2301872
18:23:38 15813 8192000000 10326164992 10767302656 1399863088 14.594 100 59 115 0 1422366144 9486624 13016432
18:24:58 81252 8192000000 10305790256 10771636224 1406385664 14.652 100 24 46 1 1421049872 9369648 5294560
18:27:20 92031 8192000000 10333907648 10790150144 1400618288 14.601 99 14 28 0 1422013296 17961952 3433056
18:31:06 71378 8192000000 10294286832 10741293056 1332480464 13.990 95 29 58 0 1350431232 11292320 6658448
18:33:18 78120 8192000000 10306374736 10751823872 1382455088 14.438 99 40 79 0 1399989856 8805168 8729600
18:35:37 22620 8192000000 10280093968 10727313408 1367320960 14.303 100 41 82 1 1392102848 14987360 9794528
18:37:01 24910 8192000000 10246577136 10696806400 1333198368 13.996 98 36 72 0 1349911696 8430336 8282992
18:39:04 23834 8192000000 10260876880 10725740544 1365356400 14.285 100 5 10 0 1373499600 7193328 949872
18:40:28 39594 8192000000 10260130560 10717622272 1340735856 14.064 97 9 18 0 1351493616 9010256 1747504
18:42:07 80467 8192000000 10259052448 10711674880 1303434080 13.726 95 12 24 1 1313814256 7777488 2602688
18:44:50 78396 8192000000 10271041776 10700505088 1325584032 13.927 98 30 56 1 1343159808 10188256 7387520
18:47:10 26695 8192000000 10260008528 10688614400 1327370320 13.943 99 51 102 0 1350302000 11687792 11243888
18:48:38 58189 8192000000 10249334832 10694963200 1308075200 13.769 97 36 72 0 1328620480 12575600 7969680
18:50:37 47128 8192000000 10250234608 10700419072 1308405280 13.772 97 30 61 1 1334608128 19628624 6574224
18:52:25 78497 8192000000 10276917936 10706624512 1305099248 13.742 96 19 38 0 1321064432 11889920 4075264
18:54:44 112846 8192000000 10282134736 10723803136 1366394544 14.295 100 32 64 0 1385323456 11369440 7559472
18:59:25 60408 8192000000 10265326544 10730184704 1366149904 14.293 100 7 14 0 1381953232 14086688 1716640
19:02:21 64582 8192000000 10260819936 10724331520 1360678576 14.243 99 28 56 0 1375266576 8396896 6191104
19:04:25 88597 8192000000 10263036288 10723110912 1346846880 14.119 98 10 18 2 1362262576 13451600 1964096
19:07:01 48059 8192000000 10276107488 10730037248 1363367920 14.268 99 18 36 0 1378879264 11324928 4186416
19:08:53 55016 8192000000 10293074960 10746241024 1359670416 14.234 98 24 48 0 1377983744 13030352 5282976
19:10:48 59676 8192000000 10313829168 10764480512 1386024832 14.470 99 20 40 0 1407051024 16467568 4558624
19:12:49 62168 8192000000 10293480656 10752417792 1339375328 14.052 96 6 12 0 1356553360 15873504 1304528
19:14:52 37616 8192000000 10301157856 10746216448 1383635168 14.449 100 29 59 0 1397546448 6469456 7441824
19:16:35 89376 8192000000 10285385488 10736877568 1315227088 13.833 94 13 26 0 1330883712 13186320 2470304
19:19:05 135875 8192000000 10296748000 10741141504 1364335312 14.276 99 20 38 0 1382166704 13457136 4374256
19:22:33 26696 8192000000 10265628432 10719170560 1332542320 13.990 96 17 34 0 1344333904 7973376 3818208
19:24:03 80010 8192000000 10267602480 10725855232 1328190128 13.951 96 11 22 0 1341883152 11318016 2375008
19:26:38 60088 8192000000 10273910192 10727854080 1359698992 14.235 99 14 28 0 1379349984 16148368 3502624
19:29:47 44286 8192000000 10250515376 10705461248 1312279376 13.807 96 19 36 0 1329212288 12418704 4514208
19:31:34 31546 8192000000 10290029472 10734428160 1345491792 14.107 98 30 61 0 1370109200 17637184 6980224
19:33:12 77151 8192000000 10259487296 10720481280 1329591632 13.963 98 17 35 1 1354531120 20777296 4162192
19:35:38 50150 8192000000 10259816656 10707931136 1316235824 13.843 97 19 38 0 1334018880 13713248 4069808
19:37:33 13573 8192000000 10287047984 10722357248 1362935280 14.264 100 43 86 0 1383830256 11436512 9458464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-07-2022 19:39:30 to 07-07-2022 19:39:45.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-07-2022 19:39:46 to 07-07-2022 19:39:48.

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          244690
MON$OLDEST_ACTIVE               248015
MON$OLDEST_SNAPSHOT             248015
MON$NEXT_TRANSACTION            248015
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                       2010944
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:93: 
:94: mscs-test-161.budzdorov.ru    Thu Jul  7 19:38:40 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
--- end of diff output ---

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

07.07.22 19:39:49. 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-07 19:39:52 No severe PSQL-related problems occured
07.07.22 19:39:53. End of report.