Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2021 01: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 15826976 kB
MemAvailable 30703848 kB
Buffers 0 kB
Cached 15843304 kB
SwapCached 4684 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.8
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 2021-10-05 01:37:50
DTS_END                         2021-10-05 01:37:50.3510
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/59472
TRN_ID                          239896
ATT_ID                          703
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: 9132.06 22477 1095847
customer order: creation 169.45 358 20334
customer order: refuse 14.17 375 1700
order to supplier: creation 45.83 1414 5500
order to supplier: removal 3.23 104262 387
invoice (draft): creation 45.94 3557 5513
invoice (draft): removal 51.97 807 6236
invoice accept: apply 41.92 18255 5030
invoice accept: cancel 46.71 844 5605
customer reserve: creation 3855.74 181 462689
customer reserve: removal 4560.19 58 547223
realization accept: apply 84.73 181 10168
realization accept: cancel 82.60 109 9912
payment from customer: creation 58.66 121 7039
payment from customer: removal 5.60 87 672
payment to supplier: creation 49.85 145 5982
payment to supplier: removal 3.40 74 408
service: total inventory turnovers 4.48 1369 538
service: total monetary turnovers 6.68 50 802
service: aggregate perf. data 0.88 18165 105
service: refresh index statistics 0.03 299133 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-10-2021 01:38:55 to 05-10-2021 01:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4858
TEST_TIME 2 5769
TEST_TIME 3 4992
TEST_TIME 4 5506
TEST_TIME 5 5924
TEST_TIME 6 5790
TEST_TIME 7 6329
TEST_TIME 8 6262
TEST_TIME 9 6312
TEST_TIME 10 6188
TEST_TIME 11 6148
TEST_TIME 12 6141
TEST_TIME 13 6414
TEST_TIME 14 6551
TEST_TIME 15 6817
TEST_TIME 16 7390
TEST_TIME 17 7710
TEST_TIME 18 8012
TEST_TIME 19 8676
TEST_TIME 20 8742
TEST_TIME 21 8841
TEST_TIME 22 9496
TEST_TIME 23 9398
TEST_TIME 24 9809
TEST_TIME 25 9701
TEST_TIME 26 9608
TEST_TIME 27 9602
TEST_TIME 28 9959
TEST_TIME 29 10429
TEST_TIME 30 11326
TEST_TIME 31 11218
TEST_TIME 32 11033
TEST_TIME 33 10986
TEST_TIME 34 10684
TEST_TIME 35 10793
TEST_TIME 36 10802
TEST_TIME 37 10619
TEST_TIME 38 10485
TEST_TIME 39 10335
TEST_TIME 40 10362
TEST_TIME 41 10195
TEST_TIME 42 10179
TEST_TIME 43 10148
TEST_TIME 44 10020
TEST_TIME 45 10096
TEST_TIME 46 10084
TEST_TIME 47 10050
TEST_TIME 48 9968
TEST_TIME 49 9953
TEST_TIME 50 9795
TEST_TIME 51 9668
TEST_TIME 52 9617
TEST_TIME 53 9657
TEST_TIME 54 9615
TEST_TIME 55 9610
TEST_TIME 56 9547
TEST_TIME 57 9465
TEST_TIME 58 9419
TEST_TIME 59 9316
TEST_TIME 60 9370
TEST_TIME 61 9359
TEST_TIME 62 9292
TEST_TIME 63 9328
TEST_TIME 64 9328
TEST_TIME 65 9300
TEST_TIME 66 9351
TEST_TIME 67 9307
TEST_TIME 68 9239
TEST_TIME 69 9215
TEST_TIME 70 9350
TEST_TIME 71 9416
TEST_TIME 72 9391
TEST_TIME 73 9466
TEST_TIME 74 9429
TEST_TIME 75 9391
TEST_TIME 76 9455
TEST_TIME 77 9521
TEST_TIME 78 9570
TEST_TIME 79 9462
TEST_TIME 80 9437
TEST_TIME 81 9390
TEST_TIME 82 9356
TEST_TIME 83 9368
TEST_TIME 84 9337
TEST_TIME 85 9281
TEST_TIME 86 9244
TEST_TIME 87 9216
TEST_TIME 88 9204
TEST_TIME 89 9183
TEST_TIME 90 9128
TEST_TIME 91 9128
TEST_TIME 92 9089
TEST_TIME 93 9097
TEST_TIME 94 9171
TEST_TIME 95 9207
TEST_TIME 96 9179
TEST_TIME 97 9227
TEST_TIME 98 9266
TEST_TIME 99 9226
TEST_TIME 100 9259
TEST_TIME 101 9251
TEST_TIME 102 9238
TEST_TIME 103 9332
TEST_TIME 104 9374
TEST_TIME 105 9368
TEST_TIME 106 9328
TEST_TIME 107 9267
TEST_TIME 108 9249
TEST_TIME 109 9238
TEST_TIME 110 9233
TEST_TIME 111 9199
TEST_TIME 112 9208
TEST_TIME 113 9198
TEST_TIME 114 9176
TEST_TIME 115 9132
TEST_TIME 116 9131
TEST_TIME 117 9109
TEST_TIME 118 9105
TEST_TIME 119 9154
TEST_TIME 120 9134

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-10-2021 01:38:58 to 05-10-2021 01:39:02.

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 1523616 1523616 0 0.00 0 39404 7 0 0
doc_list_biud 1523616 1523616 0 0.00 0 39968 27 0 0
sp_add_invoice_to_stock 5137 5030 107 2.08 24 179104 18255 0 107
sp_cancel_adding_invoice 5605 5605 0 0.00 5 98134 845 0 0
sp_cancel_client_order 1700 1700 0 0.00 4 10709 376 0 0
sp_cancel_customer_reserve 547223 547223 0 0.00 1 42727 58 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 2 3449 87 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 2081 74 0 0
sp_cancel_supplier_invoice 6246 6236 10 0.16 2 40034 807 0 10
sp_cancel_supplier_order 387 387 0 0.00 7 1103137 104262 0 0
sp_cancel_write_off 9912 9912 0 0.00 1 7763 110 0 0
sp_client_order 20334 20334 0 0.00 3 47557 359 0 0
sp_customer_reserve 462689 462689 0 0.00 0 57944 181 0 0
sp_fill_shopping_cart 31347 31347 0 0.00 1 14197 45 0 0
sp_fill_shopping_cart_clo_res 462680 462680 0 0.00 0 11491 13 0 0
sp_get_clo_for_invoice 5020 5020 0 0.00 0 20459 343 0 0
sp_kill_cost_storno 16597 16597 0 0.00 0 39398 62 0 0
sp_kill_qstorno_ret_qs2qd 1110981 1110981 0 0.00 0 39966 31 0 0
sp_kill_qty_storno 586251 586251 0 0.00 0 39967 69 0 0
sp_lock_selected_doc 20862 20862 0 0.00 0 9510 5 0 0
sp_make_cost_storno 28209 28209 0 0.00 0 37875 52 0 0
sp_multiply_rows_for_pdistr 28209 28209 0 0.00 0 14691 31 0 0
sp_multiply_rows_for_qdistr 20334 20334 0 0.00 0 14047 146 0 0
sp_pay_from_customer 7039 7039 0 0.00 4 32865 122 0 0
sp_pay_to_supplier 5982 5982 0 0.00 5 35594 145 0 0
sp_payment_common 13021 13021 0 0.00 1 32857 46 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 10272 342 0 0
sp_qd_handle_on_invoice_upd_sts 10625 10625 0 0.00 0 26567 340 0 0
sp_qd_handle_on_reserve_upd_sts 10168 10168 0 0.00 0 20269 66 0 0
sp_reserve_write_off 10168 10168 0 0.00 6 20364 182 0 0
sp_supplier_invoice 5513 5513 0 0.00 6 87117 3557 0 0
sp_supplier_order 5500 5500 0 0.00 5 69981 1414 0 0
srv_aggregate_perf_data 105 105 0 0.00 2782 172851 18166 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1247 135667 43077 0 0
srv_find_qd_qs_mism 1047885 1047885 0 0.00 0 36944 6 0 0
srv_make_invnt_saldo 538 538 0 0.00 39 143787 1370 0 0
srv_make_money_saldo 802 802 0 0.00 1 14928 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 139581 424479 299133 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5030 5030 0 0.00 0 23515 196 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 23166 182 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 2203 12 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 3578 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 4655 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 756 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 450 12 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 27493 1714 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 1735 6 0 0
t$perf-norm:sp_client_order 20334 20334 0 0.00 0 19260 22 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 10280 31 0 0
t$perf-norm:sp_pay_from_customer 7039 7039 0 0.00 0 6877 11 0 0
t$perf-norm:sp_pay_to_supplier 5982 5982 0 0.00 0 15594 16 0 0
t$perf-norm:sp_reserve_write_off 10168 10168 0 0.00 0 20341 15 0 0
t$perf-norm:sp_supplier_invoice 5513 5513 0 0.00 0 12999 22 0 0
t$perf-norm:sp_supplier_order 5500 5500 0 0.00 0 6826 21 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 380 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 303 5 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 1104 29 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 2179 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10252 10038 214 2.08 0 5718 20 0 214
v_all_customers 37098 37098 0 0.00 0 19 1 0 0
v_all_suppliers 30730 30730 0 0.00 0 84 1 0 0
v_all_wares 40668 40668 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2343 14 0 0
v_cancel_client_order 3400 3400 0 0.00 0 2795 5 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 3430 8 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 4261 7 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 555 5 0 0
v_cancel_supplier_invoice 828 808 20 2.41 0 1600 18 0 20
v_cancel_supplier_order 774 774 0 0.00 0 917 5 0 0
v_cancel_write_off 2034 2034 0 0.00 0 1188 13 0 0
v_max_id_clo_ord 5500 5500 0 0.00 0 2416 2 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 1387 8 0 0
v_max_id_ord_sup 5513 5513 0 0.00 0 4900 5 0 0
v_max_non_paid_invoice 1630 1630 0 0.00 0 1209 3 0 0
v_max_non_paid_realizn 3737 3737 0 0.00 0 7768 6 0 0
v_min_id_clo_ord 5500 5500 0 0.00 0 9446 8 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 3012 9 0 0
v_min_id_ord_sup 5513 5513 0 0.00 0 6488 4 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 21241 31 0 0
v_min_non_paid_realizn 7039 7039 0 0.00 0 9469 28 0 0
v_our_firm 10174 10174 0 0.00 0 73 1 0 0
v_reserve_write_off 20336 20336 0 0.00 0 4380 6 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 14609 116 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 7026 364 0 0
x_make_qstorno_1000_1200 5500 5500 0 0.00 2 69972 1323 0 0
x_make_qstorno_1200_2000 5513 5513 0 0.00 2 84970 3402 0 0
x_make_qstorno_2100_3300 462689 462689 0 0.00 0 57513 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2021 01:39:03 to 05-10-2021 01:39:07.

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
23:07:54 166 8192000000 8871243472 8898789376 51673360 0.626 7 7 8 0 52155760 121392 361008
23:08:56 28421 8192000000 10374019216 10694184960 1431961248 14.879 100 88 176 0 1466582304 5831104 28789952
23:10:27 16974 8192000000 10453904096 10790121472 1501445904 15.489 100 83 166 0 1535474288 7925728 26102656
23:11:49 21624 8192000000 10505186400 10849083392 1555300000 15.956 100 88 176 1 1593589520 10438688 27850832
23:13:20 14915 8192000000 10586045968 10948255744 1643853488 16.712 100 82 164 1 1680739312 12214992 24670832
23:14:38 7751 8192000000 10641888208 11014885376 1702910864 17.209 100 82 164 1 1740623072 13238032 24474176
23:15:55 5208 8192000000 10711053056 11087785984 1771097856 17.776 100 77 153 1 1804886960 12404816 21384288
23:17:03 3569 8192000000 10746853504 11131772928 1812443472 18.116 100 90 179 2 1851476512 13182928 25850112
23:18:08 1215 8192000000 10787588016 11179667456 1854470448 18.458 100 96 189 0 1895821632 14232048 27119136
23:19:10 42467 8192000000 10816531744 11218432000 1880678096 18.671 100 48 96 0 1903406912 8236048 14492768
23:20:53 5626 8192000000 10802083968 11230560256 1889756032 18.744 100 28 56 0 1905290240 8014560 7519648
23:22:00 6137 8192000000 10809851440 11241328640 1899760784 18.824 100 22 44 2 1912228448 5490592 6977072
23:23:06 48652 8192000000 10821521648 11257085952 1911620848 18.920 100 37 76 0 1928144096 6193872 10329376
23:25:13 7816 8192000000 10824855024 11263016960 1914094256 18.940 100 31 60 3 1929207632 6607936 8505440
23:26:21 4195 8192000000 10816914624 11254325248 1906542624 18.879 100 22 45 1 1919410672 5605072 7262976
23:27:25 10274 8192000000 10820315168 11255517184 1892520768 18.766 99 28 56 1 1906719408 5845360 8353280
23:28:39 25347 8192000000 10770163776 11201417216 1769870992 17.766 94 11 22 0 1777954912 5031824 3052096
23:30:08 6775 8192000000 10749383264 11169103872 1803587024 18.043 98 38 76 2 1821234752 5892992 11754736
23:31:17 9248 8192000000 10730899664 11145838592 1799244272 18.008 99 19 38 1 1811078608 6570240 5264096
23:32:28 61911 8192000000 10724333264 11151298560 1765360640 17.729 96 28 55 0 1779082704 6317472 7404592
23:34:30 7673 8192000000 10786667232 11211788288 1866410112 18.555 100 29 56 0 1880404656 5545264 8449280
23:35:38 8128 8192000000 10778884096 11216609280 1874358832 18.620 100 25 50 1 1887205040 5293680 7552528
23:36:47 60247 8192000000 10802352112 11237335040 1889684368 18.743 100 28 55 2 1906134144 8917584 7532192
23:38:54 12948 8192000000 10871550224 11287736320 1935640256 19.112 100 70 141 1 1969815024 15644144 18530624
23:40:11 25169 8192000000 10888331536 11319930880 1948339328 19.213 100 48 96 1 1970759616 9323616 13096672
23:41:39 13119 8192000000 10902271056 11337748480 1969361072 19.380 100 56 112 3 1997450640 12250944 15838624
23:42:57 22579 8192000000 10900170976 11339255808 1966741200 19.360 100 44 87 1 1997845072 17905088 13198784
23:44:24 16968 8192000000 10929403520 11360604160 1973262960 19.411 99 66 130 3 2012134960 19576000 19296000
23:45:45 8799 8192000000 10937914736 11373617152 1990935408 19.551 100 39 78 1 2025283088 22466432 11881248
23:47:03 23996 8192000000 10926936208 11370020864 1971833872 19.400 99 40 79 0 2005709680 22076928 11798880
23:48:28 6971 8192000000 10936969248 11350622208 1964939824 19.345 99 53 103 0 2012015504 31401616 15674064
23:49:38 1243 8192000000 10966319504 11375796224 1993003424 19.568 100 86 168 0 2048552720 31064208 24485088
23:50:45 1508 8192000000 10923768896 11332837376 1942107440 19.164 99 89 180 0 2000973728 32406576 26459712
23:51:52 3579 8192000000 10946090336 11370782720 1970461536 19.389 100 66 132 1 2028914624 38374064 20079024
23:53:18 1950 8192000000 10953533920 11363368960 1962142912 19.323 100 95 188 0 2036104736 44848480 29113344
23:54:24 2707 8192000000 10969476720 11381272576 1974136784 19.418 100 90 180 4 2049970320 47367616 28465920
23:55:30 1867 8192000000 10938046896 11341021184 1933560416 19.095 99 80 161 2 2004229664 45980592 24688656
23:56:39 4709 8192000000 10923540384 11353657344 1952487312 19.246 100 60 120 0 2013637200 42751168 18398720
23:57:49 10179 8192000000 10948837088 11379183616 1967271632 19.364 100 45 90 2 2023974288 42262112 14440544
23:59:09 9112 8192000000 10922741536 11359793152 1942321952 19.165 99 50 98 3 2001354272 43170000 15862320
00:00:21 8211 8192000000 10910934752 11352715264 1930724080 19.073 99 28 56 1 1981380624 41292864 9363680
00:01:32 18545 8192000000 10934156064 11383844864 1952428096 19.246 99 26 52 1 1998011632 37280000 8303536
00:03:03 20276 8192000000 10931569920 11381288960 1942821536 19.169 99 25 50 0 1997030512 46024672 8184304
00:04:36 18490 8192000000 10953723312 11396784128 1963883136 19.337 100 26 53 0 2031144752 57661120 9600496
00:06:19 24167 8192000000 10926917168 11396419584 1957533760 19.286 100 15 30 2 2016292976 52870864 5888352
00:07:45 32164 8192000000 10887044096 11338973184 1919077008 18.979 98 12 25 1 1946305696 23506240 3722448
00:09:17 41767 8192000000 10919098512 11366031360 1969046640 19.378 100 19 40 1 1995241024 20184368 6010016
00:10:59 55535 8192000000 10941233760 11368923136 1970946000 19.393 99 76 148 6 2003722160 10911168 21864992
00:12:56 18905 8192000000 10911723312 11380944896 1987747664 19.526 100 24 47 2 2003735344 9433440 6554240
00:14:17 57106 8192000000 10937246912 11397849088 1986126880 19.513 99 10 20 0 1999844096 10883808 2833408
00:16:15 83354 8192000000 10945093600 11419123712 2012636208 19.722 100 22 43 4 2034998656 16297168 6065280
00:18:40 36047 8192000000 10965948192 11417427968 2018107008 19.765 100 27 54 1 2037894608 11784672 8002928
00:20:17 73537 8192000000 10953005552 11422068736 2014868784 19.740 100 24 49 0 2039345104 17416720 7059600
00:22:50 61607 8192000000 10935401088 11431821312 2005352704 19.665 100 10 20 0 2030389792 21736576 3300512
00:24:52 123357 8192000000 10923549312 11410952192 1998304448 19.609 99 21 42 0 2015757056 11323152 6129456
00:27:59 27275 8192000000 10939816096 11406127104 1991925616 19.559 99 23 47 0 2009031936 10693648 6412672
00:29:27 45269 8192000000 10959033584 11417296896 2018411872 19.768 100 33 66 1 2040468112 12581568 9474672
00:31:13 40558 8192000000 10934516528 11414519808 2005324848 19.665 100 19 39 0 2019326208 8977696 5023664
00:33:15 60083 8192000000 10916832256 11395772416 1985279296 19.506 99 13 26 0 2003228144 14262928 3685920
00:35:19 61780 8192000000 10895253712 11373604864 1966761984 19.360 98 16 32 1 1988188304 16936208 4490112
00:37:21 74228 8192000000 10885414032 11368882176 1944038560 19.179 97 27 54 1 1972400960 19959952 8402448
00:39:40 58967 8192000000 10887696992 11378298880 1923065328 19.011 97 6 12 0 1938494608 14608560 820720
00:41:42 68070 8192000000 10843403680 11319205888 1802238848 18.032 92 7 14 0 1813881568 9462144 2180576
00:43:53 35493 8192000000 10841633696 11302535168 1811645168 18.109 93 21 42 0 1824581440 6674848 6261424
00:45:31 18547 8192000000 10910777792 11355353088 1953324208 19.253 100 59 117 0 1985178176 14921888 16932080
00:46:52 88497 8192000000 10877939840 11349663744 1925900576 19.034 99 9 16 1 1939596688 11637232 2058880
00:49:31 57015 8192000000 10896382560 11367079936 1959731152 19.304 100 15 29 3 1975230640 10948576 4550912
00:51:33 56583 8192000000 10864395440 11333267456 1904165392 18.860 98 27 54 0 1922674560 11088320 7420848
00:53:41 39382 8192000000 10861400464 11329183744 1895885040 18.793 98 28 55 1 1918266736 15011296 7370400
00:55:26 69112 8192000000 10823574512 11297247232 1889046304 18.738 98 5 10 0 1910554784 20413360 1095120
00:58:33 19462 8192000000 10844654592 11313590272 1919920400 18.986 100 16 32 0 1935396544 11052240 4423904
00:59:53 75304 8192000000 10863708832 11352342528 1939476704 19.143 100 7 14 0 1958044560 16544784 2023072
01:02:09 91664 8192000000 10874887632 11347210240 1932290688 19.085 99 13 26 0 1943475072 7617920 3566464
01:04:44 67390 8192000000 10873927632 11353022464 1942072000 19.163 99 21 42 1 1955942320 7732848 6137472
01:07:00 80872 8192000000 10868200512 11349446656 1909050288 18.899 97 10 20 1 1923026592 11537120 2439184
01:09:22 30803 8192000000 10886914384 11354816512 1928941072 19.058 98 23 46 1 1946954896 11648832 6364992
01:10:57 87270 8192000000 10895035952 11382632448 1952761440 19.248 99 12 24 0 1976553984 20226352 3566192
01:13:25 40914 8192000000 10903982144 11369635840 1974403472 19.420 100 30 60 1 1993659872 10726768 8529632
01:15:16 24173 8192000000 10919659344 11389140992 1983322096 19.491 100 21 39 0 2001174208 11823504 6028608
01:16:42 34490 8192000000 10915494608 11376140288 1962334144 19.325 99 42 84 1 1987006096 12875104 11796848
01:19:00 47075 8192000000 10939984480 11399720960 1969847712 19.384 99 40 80 0 1996245440 15425600 10972128
01:20:47 135666 8192000000 10877398784 11345481728 1805516784 18.059 91 12 24 1 1827262288 18363984 3381520
01:24:04 85382 8192000000 10873046032 11344900096 1922851776 19.010 98 24 48 1 1940019168 9850960 7316432
01:26:52 65065 8192000000 10883521616 11372273664 1946830352 19.201 99 9 18 1 1963836160 14428912 2576896
01:28:57 77922 8192000000 10876575216 11360366592 1933274336 19.093 98 11 22 0 1948404624 11701440 3428848
01:31:15 66784 8192000000 10861682144 11347902464 1902776896 18.849 97 6 12 0 1911084048 6719296 1587856
01:33:26 65167 8192000000 10859695152 11327787008 1845266912 18.384 95 9 16 1 1854112144 6975360 1869872
01:35:38 63122 8192000000 10847201232 11300380672 1816164176 18.146 93 15 30 0 1829614112 9087120 4362816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-10-2021 01:39:09 to 05-10-2021 01:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 05-10-2021 01:39:25 to 05-10-2021 01:39:26.

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          238797
MON$OLDEST_ACTIVE               240505
MON$OLDEST_SNAPSHOT             240505
MON$NEXT_TRANSACTION            240505
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                       2047328
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:213: 
:214: mscs-test-161.budzdorov.ru    Tue Oct  5 01:37:55 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Tue Oct  5 01:37:56 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
--- end of diff output ---

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

05.10.21 01:39:27. 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
2021-10-05 01:39:30 No severe PSQL-related problems occured
05.10.21 01:39:31. End of report.