Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2021 10: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15951116 kB
MemAvailable 30897580 kB
Buffers 0 kB
Cached 15785032 kB
SwapCached 4788 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-28 10:37:09
DTS_END                         2021-10-28 10:37:09.8450
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(68:17)
IP                              ::1/35070
TRN_ID                          229328
ATT_ID                          644
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: 8924.59 21379 1070951
customer order: creation 174.65 378 20958
customer order: refuse 14.82 396 1778
order to supplier: creation 45.37 1541 5444
order to supplier: removal 3.56 104273 427
invoice (draft): creation 45.51 3920 5461
invoice (draft): removal 50.60 1012 6072
invoice accept: apply 41.10 19628 4932
invoice accept: cancel 45.73 953 5488
customer reserve: creation 3754.78 195 450574
customer reserve: removal 4457.52 64 534902
realization accept: apply 84.08 208 10089
realization accept: cancel 79.43 128 9531
payment from customer: creation 59.58 121 7149
payment from customer: removal 5.41 81 649
payment to supplier: creation 48.53 143 5824
payment to supplier: removal 3.47 70 416
service: total inventory turnovers 3.45 4480 414
service: total monetary turnovers 6.17 614 740
service: aggregate perf. data 0.83 19850 99
service: refresh index statistics 0.03 269519 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2021 10:39:14 to 28-10-2021 10:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4817
TEST_TIME 2 5517
TEST_TIME 3 6397
TEST_TIME 4 5843
TEST_TIME 5 6033
TEST_TIME 6 5568
TEST_TIME 7 5390
TEST_TIME 8 5619
TEST_TIME 9 5963
TEST_TIME 10 6276
TEST_TIME 11 6143
TEST_TIME 12 6855
TEST_TIME 13 6707
TEST_TIME 14 6812
TEST_TIME 15 7145
TEST_TIME 16 7192
TEST_TIME 17 7239
TEST_TIME 18 7404
TEST_TIME 19 7550
TEST_TIME 20 7548
TEST_TIME 21 7967
TEST_TIME 22 8360
TEST_TIME 23 8760
TEST_TIME 24 8758
TEST_TIME 25 8784
TEST_TIME 26 8695
TEST_TIME 27 8810
TEST_TIME 28 9006
TEST_TIME 29 9143
TEST_TIME 30 9410
TEST_TIME 31 9944
TEST_TIME 32 10100
TEST_TIME 33 9979
TEST_TIME 34 10281
TEST_TIME 35 10374
TEST_TIME 36 10301
TEST_TIME 37 10324
TEST_TIME 38 10069
TEST_TIME 39 10005
TEST_TIME 40 9872
TEST_TIME 41 10027
TEST_TIME 42 9810
TEST_TIME 43 9749
TEST_TIME 44 9747
TEST_TIME 45 9740
TEST_TIME 46 9726
TEST_TIME 47 9700
TEST_TIME 48 9694
TEST_TIME 49 9618
TEST_TIME 50 9511
TEST_TIME 51 9390
TEST_TIME 52 9406
TEST_TIME 53 9349
TEST_TIME 54 9247
TEST_TIME 55 9170
TEST_TIME 56 9173
TEST_TIME 57 9253
TEST_TIME 58 9216
TEST_TIME 59 9237
TEST_TIME 60 9267
TEST_TIME 61 9261
TEST_TIME 62 9424
TEST_TIME 63 9407
TEST_TIME 64 9371
TEST_TIME 65 9529
TEST_TIME 66 9548
TEST_TIME 67 9599
TEST_TIME 68 9553
TEST_TIME 69 9551
TEST_TIME 70 9517
TEST_TIME 71 9543
TEST_TIME 72 9513
TEST_TIME 73 9446
TEST_TIME 74 9482
TEST_TIME 75 9412
TEST_TIME 76 9359
TEST_TIME 77 9302
TEST_TIME 78 9235
TEST_TIME 79 9213
TEST_TIME 80 9165
TEST_TIME 81 9143
TEST_TIME 82 9112
TEST_TIME 83 9110
TEST_TIME 84 9108
TEST_TIME 85 9132
TEST_TIME 86 9124
TEST_TIME 87 9073
TEST_TIME 88 9221
TEST_TIME 89 9304
TEST_TIME 90 9246
TEST_TIME 91 9288
TEST_TIME 92 9299
TEST_TIME 93 9345
TEST_TIME 94 9261
TEST_TIME 95 9230
TEST_TIME 96 9186
TEST_TIME 97 9141
TEST_TIME 98 9156
TEST_TIME 99 9170
TEST_TIME 100 9179
TEST_TIME 101 9167
TEST_TIME 102 9129
TEST_TIME 103 9136
TEST_TIME 104 9127
TEST_TIME 105 9172
TEST_TIME 106 9169
TEST_TIME 107 9174
TEST_TIME 108 9294
TEST_TIME 109 9290
TEST_TIME 110 9230
TEST_TIME 111 9170
TEST_TIME 112 9136
TEST_TIME 113 9120
TEST_TIME 114 9086
TEST_TIME 115 9083
TEST_TIME 116 9047
TEST_TIME 117 9010
TEST_TIME 118 8995
TEST_TIME 119 8998
TEST_TIME 120 8930

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-10-2021 10:39:17 to 28-10-2021 10:39:21.

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 1488272 1488272 0 0.00 0 15675 7 0 0
doc_list_biud 1488272 1488272 0 0.00 0 52564 30 0 0
sp_add_invoice_to_stock 5030 4932 98 1.94 33 210002 19628 0 98
sp_cancel_adding_invoice 5488 5488 0 0.00 5 79394 953 0 0
sp_cancel_client_order 1778 1778 0 0.00 5 10471 396 0 0
sp_cancel_customer_reserve 534902 534902 0 0.00 1 52565 65 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 2820 82 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 1913 71 0 0
sp_cancel_supplier_invoice 6087 6072 15 0.24 3 29930 1012 0 15
sp_cancel_supplier_order 427 427 0 0.00 5 918939 104274 0 0
sp_cancel_write_off 9531 9531 0 0.00 1 15284 128 0 0
sp_client_order 20958 20958 0 0.00 4 62927 379 0 0
sp_customer_reserve 450574 450574 0 0.00 0 104181 196 0 0
sp_fill_shopping_cart 31850 31850 0 0.00 1 17764 46 0 0
sp_fill_shopping_cart_clo_res 450570 450570 0 0.00 0 21985 15 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 28460 368 0 0
sp_kill_cost_storno 16084 16084 0 0.00 0 13785 70 0 0
sp_kill_qstorno_ret_qs2qd 1085834 1085834 0 0.00 0 52549 36 0 0
sp_kill_qty_storno 573211 573211 0 0.00 0 52563 77 0 0
sp_lock_selected_doc 20823 20823 0 0.00 0 9064 6 0 0
sp_make_cost_storno 27986 27986 0 0.00 0 15672 63 0 0
sp_multiply_rows_for_pdistr 27986 27986 0 0.00 0 14818 38 0 0
sp_multiply_rows_for_qdistr 20958 20958 0 0.00 0 62916 181 0 0
sp_pay_from_customer 7149 7149 0 0.00 4 14442 122 0 0
sp_pay_to_supplier 5824 5824 0 0.00 5 15599 144 0 0
sp_payment_common 12973 12973 0 0.00 1 13593 29 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 10465 372 0 0
sp_qd_handle_on_invoice_upd_sts 10412 10412 0 0.00 0 14536 374 0 0
sp_qd_handle_on_reserve_upd_sts 10089 10089 0 0.00 0 23841 57 0 0
sp_reserve_write_off 10089 10089 0 0.00 7 23877 209 0 0
sp_supplier_invoice 5461 5461 0 0.00 7 80378 3920 0 0
sp_supplier_order 5444 5444 0 0.00 5 28246 1541 0 0
srv_aggregate_perf_data 99 99 0 0.00 2786 200058 19851 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 807 160172 46302 0 0
srv_find_qd_qs_mism 1023727 1023727 0 0.00 0 13114 6 0 0
srv_make_invnt_saldo 414 414 0 0.00 33 133810 4481 0 0
srv_make_money_saldo 740 740 0 0.00 1 11729 615 0 0
srv_recalc_idx_stat 4 4 0 0.00 158955 349649 269519 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4932 4932 0 0.00 0 9605 170 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 3853 109 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 3605 20 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 1303 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 359 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 3068 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 6200 40 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 19475 1576 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1529 4 0 0
t$perf-norm:sp_client_order 20958 20958 0 0.00 0 6679 21 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 10455 17 0 0
t$perf-norm:sp_pay_from_customer 7149 7149 0 0.00 0 7953 14 0 0
t$perf-norm:sp_pay_to_supplier 5824 5824 0 0.00 0 5544 11 0 0
t$perf-norm:sp_reserve_write_off 10089 10089 0 0.00 0 5668 10 0 0
t$perf-norm:sp_supplier_invoice 5461 5461 0 0.00 0 13249 25 0 0
t$perf-norm:sp_supplier_order 5444 5444 0 0.00 0 5681 22 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 398 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 263 8 0 0
t$perf-norm:srv_make_invnt_saldo 414 414 0 0.00 0 3448 22 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 1619 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10044 9848 196 1.95 0 5100 17 0 196
v_all_customers 38626 38626 0 0.00 0 35 1 0 0
v_all_suppliers 30438 30438 0 0.00 0 19 1 0 0
v_all_wares 41916 41916 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 676 8 0 0
v_cancel_client_order 3556 3556 0 0.00 0 2428 4 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2766 8 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 3406 10 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 1891 8 0 0
v_cancel_supplier_invoice 816 786 30 3.67 0 2165 16 0 30
v_cancel_supplier_order 854 854 0 0.00 0 2881 9 0 0
v_cancel_write_off 1972 1972 0 0.00 0 6238 22 0 0
v_max_id_clo_ord 5443 5443 0 0.00 0 1063 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 2479 19 0 0
v_max_id_ord_sup 5449 5449 0 0.00 0 3388 5 0 0
v_max_non_paid_invoice 1497 1497 0 0.00 0 103 2 0 0
v_max_non_paid_realizn 3639 3639 0 0.00 0 3714 5 0 0
v_min_id_clo_ord 5443 5443 0 0.00 0 12967 10 0 0
v_min_id_clo_res 989 989 0 0.00 0 2911 15 0 0
v_min_id_ord_sup 5449 5449 0 0.00 0 7852 9 0 0
v_min_non_paid_invoice 5824 5824 0 0.00 0 11213 20 0 0
v_min_non_paid_realizn 7149 7149 0 0.00 0 13587 24 0 0
v_our_firm 10310 10310 0 0.00 0 13 1 0 0
v_reserve_write_off 20178 20178 0 0.00 0 4353 8 0 0
x_lock_depdocs_on_canc_invoice 11167 11167 0 0.00 0 16161 121 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 11623 346 0 0
x_make_qstorno_1000_1200 5444 5444 0 0.00 2 28234 1448 0 0
x_make_qstorno_1200_2000 5461 5461 0 0.00 4 78998 3744 0 0
x_make_qstorno_2100_3300 450574 450574 0 0.00 0 104180 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2021 10:39:22 to 28-10-2021 10:39:26.

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
08:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 17220 8192000000 9701293568 9993326592 777378768 8.667 100 82 164 0 805222160 7078736 20764656
08:09:41 19519 8192000000 9751075200 10053062656 824576816 9.145 100 91 182 1 858045616 11598624 21870176
08:11:06 15657 8192000000 9790123600 10106753024 859279424 9.493 100 86 172 0 904902704 25538720 20084560
08:12:25 12609 8192000000 9848743088 10180329472 906879280 9.966 100 74 148 2 957590016 34421824 16288912
08:13:40 11783 8192000000 9900427104 10240671744 946526640 10.357 100 79 156 1 1007752288 44323472 16902176
08:14:56 7227 8192000000 9938261776 10292363264 981028832 10.694 100 82 162 0 1060995760 62898240 17068688
08:16:07 3364 8192000000 9981120096 10339774464 1019339232 11.066 100 83 165 1 1101762064 65099808 17323024
08:17:13 7876 8192000000 9999891440 10367074304 1035537648 11.222 100 73 146 1 1115590256 65252336 14800272
08:18:24 1670 8192000000 10035080000 10401980416 1052280128 11.383 100 87 173 0 1139477984 68172608 19025248
08:19:28 2414 8192000000 10039034544 10415648768 1067626608 11.529 100 88 176 0 1153519552 67572336 18320608
08:20:33 2063 8192000000 10056213328 10434191360 1075066672 11.600 100 79 158 2 1169364720 78109408 16188640
08:21:36 7957 8192000000 9987562128 10389323776 1077185456 11.621 100 24 49 0 1105042576 22016560 5840560
08:22:46 4142 8192000000 9991985312 10387668992 1082569280 11.672 100 38 76 0 1105180272 14269936 8341056
08:23:53 10943 8192000000 10004901824 10401120256 1084955664 11.695 100 52 106 1 1118185616 22624448 10605504
08:25:06 5581 8192000000 10011426432 10406105088 1094276048 11.783 100 41 82 3 1124766064 22057504 8432512
08:26:12 4590 8192000000 10009054368 10410250240 1098312176 11.822 100 40 80 0 1126073920 19055520 8706224
08:27:18 12026 8192000000 9994213152 10402607104 1100500128 11.842 100 32 65 1 1136998240 29202320 7295792
08:28:31 11676 8192000000 9993002848 10398347264 1044496512 11.308 95 30 58 3 1076788976 26080336 6212128
08:29:48 2763 8192000000 10011560592 10394525696 1071756560 11.569 100 71 140 1 1120280944 33121040 15403344
08:30:54 8282 8192000000 9968802064 10353995776 982983472 10.713 94 32 64 1 1014210544 24124944 7102128
08:32:05 43525 8192000000 9945013440 10313912320 973165472 10.618 94 52 103 4 1002645632 19089408 10390752
08:33:51 4476 8192000000 9968540704 10340610048 1015482544 11.028 98 48 97 1 1069595440 43922592 10190304
08:34:58 9281 8192000000 9961903392 10340048896 1032944672 11.197 100 33 68 2 1064982656 24606176 7431808
08:36:09 5904 8192000000 9972618608 10364329984 1058110000 11.438 100 44 88 0 1083637600 16343120 9184480
08:37:16 82122 8192000000 10007160736 10404966400 1076795520 11.617 100 38 76 2 1114290832 29438768 8056544
08:39:42 7766 8192000000 10063228384 10456633344 1098527856 11.824 100 58 116 0 1175400624 64945184 11927584
08:40:56 24598 8192000000 10059438176 10462408704 1115322144 11.983 100 44 88 0 1161606928 36807584 9477200
08:42:25 32466 8192000000 10087083664 10495557632 1132326912 12.143 100 43 86 2 1200671392 58604224 9740256
08:44:04 14926 8192000000 10124027968 10533982208 1141565520 12.230 100 31 62 0 1219826192 71570464 6690208
08:45:27 3329 8192000000 10147285840 10549645312 1146913264 12.281 100 54 106 2 1253404784 94961488 11530032
08:46:37 1598 8192000000 10202650896 10606755840 1158156928 12.386 100 70 133 2 1307951872 133833184 15961760
08:47:42 12616 8192000000 10194590224 10599616512 1163959200 12.440 100 64 128 0 1300147168 121384208 14803760
08:48:58 8377 8192000000 10251655648 10678841344 1170316816 12.500 100 59 118 0 1362930592 178837104 13776672
08:50:12 23708 8192000000 10261347152 10679242752 1173808128 12.532 100 53 106 0 1361794096 176567296 11418672
08:51:41 7648 8192000000 10239997152 10679128064 1172420272 12.519 100 49 97 1 1342286576 158898752 10967552
08:52:58 4365 8192000000 10263944896 10693259264 1178625264 12.577 100 58 115 0 1371411824 179903792 12882768
08:54:10 2710 8192000000 10277829232 10686988288 1180569600 12.596 100 83 165 4 1375670448 177651936 17448912
08:55:17 4301 8192000000 10286142560 10687688704 1174849856 12.542 100 85 170 0 1379545680 185683072 19012752
08:56:28 1431 8192000000 10360311440 10757812224 1167976384 12.478 99 93 186 2 1453693488 264657280 21059824
08:57:35 806 8192000000 10339384048 10738307072 1155494144 12.361 99 83 167 1 1438265136 263505200 19265792
08:58:41 4437 8192000000 10335211712 10756124672 1143635920 12.250 99 61 119 1 1439237920 281573072 14028928
08:59:50 4501 8192000000 10305145456 10720972800 1167219824 12.471 100 79 158 1 1403176880 216775568 19181488
09:00:58 12470 8192000000 10255472992 10666647552 1140049632 12.216 100 47 95 1 1359181184 208311360 10820192
09:02:20 11513 8192000000 10303384560 10741919744 1143072608 12.244 99 52 104 0 1387313792 232165120 12076064
09:03:57 7314 8192000000 10301656704 10736762880 1130178064 12.123 100 37 74 0 1401703168 261757120 9767984
09:05:29 31152 8192000000 10253170480 10700460032 1117338192 12.002 99 22 44 0 1354487376 230930624 6218560
09:07:06 45781 8192000000 10234189520 10670747648 1103036288 11.866 97 25 50 0 1342169376 232621248 6511840
09:08:54 51146 8192000000 10127264832 10613514240 1104744976 11.883 99 13 26 0 1236718880 129075072 2898832
09:10:52 24350 8192000000 10156405536 10589978624 1121909040 12.045 100 36 72 2 1237789408 108144896 7735472
09:12:49 62049 8192000000 10126061680 10561556480 1131418240 12.135 100 14 28 2 1241676080 106957824 3300016
09:14:52 84441 8192000000 10079956400 10511085568 1134651392 12.165 100 37 74 0 1178758960 35877776 8229792
09:17:28 61095 8192000000 10159561824 10598281216 1137185552 12.189 99 35 70 0 1241061968 96051216 7825200
09:19:36 52694 8192000000 10109600480 10585415680 1142223808 12.236 100 15 30 1 1224568944 79128896 3216240
09:21:34 56229 8192000000 10089673904 10546511872 1134760720 12.166 99 33 66 1 1195906032 53412384 7732928
09:23:32 160171 8192000000 10077703232 10552791040 1109616464 11.929 97 19 38 0 1177442880 63941936 3884480
09:27:17 92705 8192000000 10091458144 10528923648 1121201504 12.038 98 28 56 0 1162837104 35519968 6115632
09:30:09 44187 8192000000 10064547872 10550419456 1142770208 12.242 100 8 16 0 1172974816 28631856 1572752
09:31:54 69439 8192000000 10127630128 10589638656 1153632544 12.344 100 23 46 0 1218937392 60696944 4607904
09:34:34 50473 8192000000 10074154096 10519977984 1148975344 12.300 100 21 42 0 1186396256 33243504 4177408
09:36:24 54851 8192000000 10135058976 10611449856 1152121408 12.329 99 24 49 0 1234763232 77316064 5325760
09:38:24 73741 8192000000 10156618672 10593062912 1093903568 11.780 95 16 33 2 1171565888 73728336 3933984
09:40:42 45385 8192000000 10138966480 10594234368 1134270224 12.162 98 21 42 0 1225953712 87067904 4615584
09:42:28 87079 8192000000 10159922288 10600300544 1133251552 12.152 98 23 46 0 1244332320 106209824 4870944
09:45:07 20552 8192000000 10091873808 10517602304 1118412496 12.012 98 38 77 0 1164672272 38862080 7397696
09:46:36 52215 8192000000 10141036560 10594938880 1081564544 11.662 95 22 44 0 1191046256 104614976 4866736
09:49:41 26377 8192000000 10030526960 10449977344 1088772592 11.731 97 31 62 1 1115428880 19559184 7097104
09:51:09 61743 8192000000 10051821008 10528305152 1119263840 12.020 99 30 60 0 1186269280 60765776 6239664
09:53:13 72037 8192000000 10041625840 10497675264 1078865312 11.637 96 13 27 0 1110031440 28473488 2692640
09:55:26 58596 8192000000 10052536384 10495283200 1107252032 11.906 99 18 36 1 1161144480 49969456 3922992
09:57:45 38651 8192000000 10070255376 10511785984 1109102064 11.924 99 20 39 1 1150429936 37069600 4258272
09:59:26 50702 8192000000 10074057024 10506969088 1084083440 11.686 96 15 30 0 1146265264 59027472 3154352
10:01:17 46347 8192000000 10084287040 10522054656 1114178016 11.972 99 21 42 0 1180390704 61676480 4536208
10:03:14 47502 8192000000 10122295488 10551050240 1118946960 12.017 99 27 54 0 1211377392 86446528 5983904
10:05:09 86996 8192000000 10102908992 10575413248 1108713216 11.920 98 17 29 0 1219768832 107767728 3287888
10:07:36 24896 8192000000 10118392624 10544209920 1138437648 12.201 100 51 100 2 1190975296 42702896 9834752
10:09:06 87962 8192000000 10108756384 10562650112 1121407296 12.040 98 12 24 0 1166392880 42854080 2131504
10:11:34 54247 8192000000 10078737024 10541105152 1125926064 12.083 99 26 52 0 1164346368 33251968 5168336
10:13:30 80141 8192000000 10080437856 10531233792 1122158816 12.047 99 11 22 1 1148559712 24066240 2334656
10:15:54 79165 8192000000 10073408656 10522116096 1122020512 12.046 98 35 67 0 1170215712 41416624 6778576
10:18:19 43761 8192000000 10073123584 10523717632 1140889168 12.224 100 10 20 0 1173626672 30825840 1911664
10:20:07 24775 8192000000 10074966384 10525904896 1128397008 12.106 98 25 50 0 1178961088 44795408 5768672
10:21:33 118345 8192000000 10078299920 10547384320 1103149680 11.868 96 11 22 1 1161941168 56171664 2619824
10:24:31 101565 8192000000 10126521456 10661367808 1138131184 12.198 99 10 20 0 1215297040 75150432 2015424
10:27:13 141104 8192000000 10067591248 10520940544 1100171248 11.839 96 18 35 0 1141451168 37974096 3305824
10:30:35 51887 8192000000 10084846448 10537750528 1121823936 12.044 99 13 25 1 1144426912 19521648 3081328
10:32:38 76626 8192000000 10113561168 10580549632 1090598352 11.748 95 13 26 1 1139888720 46538800 2751568
10:35:47 50763 8192000000 10107064928 10563280896 1143711376 12.250 100 21 41 0 1237962832 88930720 5320736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2021 10:39:27 to 28-10-2021 10:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2021 10:39:43 to 28-10-2021 10:39:45.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          226030
MON$OLDEST_ACTIVE               229828
MON$OLDEST_SNAPSHOT             229828
MON$NEXT_TRANSACTION            229828
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-30 07:30:08.6030 GMT
MON$PAGES                       2056032
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2646 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2646 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Oct 28 10:37:13 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

28.10.21 10:39:46. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-28 10:39:48 No severe PSQL-related problems occured
28.10.21 10:39:50. End of report.