Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.05.2022 04: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 15934220 kB
MemAvailable 31516376 kB
Buffers 20 kB
Cached 15732292 kB
SwapCached 3260 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.2
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 2022-05-03 04:37:17
DTS_END                         2022-05-03 04:37:17.3960
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/39484
TRN_ID                          221501
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: 9010.81 27087 1081297
customer order: creation 171.41 468 20569
customer order: refuse 14.73 457 1767
order to supplier: creation 45.76 1376 5491
order to supplier: removal 3.40 189607 408
invoice (draft): creation 45.85 3234 5502
invoice (draft): removal 48.75 1088 5850
invoice accept: apply 41.65 28297 4998
invoice accept: cancel 44.79 1332 5375
customer reserve: creation 3913.39 267 469607
customer reserve: removal 4390.46 116 526855
realization accept: apply 83.34 281 10001
realization accept: cancel 77.40 175 9288
payment from customer: creation 59.07 188 7088
payment from customer: removal 5.57 133 668
payment to supplier: creation 48.56 210 5827
payment to supplier: removal 3.49 126 419
service: total inventory turnovers 4.11 4618 493
service: total monetary turnovers 8.13 359 976
service: aggregate perf. data 0.92 17838 110
service: refresh index statistics 0.04 291577 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-05-2022 04:39:02 to 03-05-2022 04:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5435
TEST_TIME 2 5946
TEST_TIME 3 5904
TEST_TIME 4 6159
TEST_TIME 5 5816
TEST_TIME 6 5846
TEST_TIME 7 6161
TEST_TIME 8 6457
TEST_TIME 9 6727
TEST_TIME 10 6613
TEST_TIME 11 6644
TEST_TIME 12 6636
TEST_TIME 13 6610
TEST_TIME 14 6774
TEST_TIME 15 6700
TEST_TIME 16 6789
TEST_TIME 17 6793
TEST_TIME 18 7227
TEST_TIME 19 7281
TEST_TIME 20 7477
TEST_TIME 21 7729
TEST_TIME 22 8018
TEST_TIME 23 8081
TEST_TIME 24 7990
TEST_TIME 25 8064
TEST_TIME 26 8403
TEST_TIME 27 8742
TEST_TIME 28 8594
TEST_TIME 29 8657
TEST_TIME 30 8768
TEST_TIME 31 9474
TEST_TIME 32 9908
TEST_TIME 33 10404
TEST_TIME 34 10590
TEST_TIME 35 10738
TEST_TIME 36 10634
TEST_TIME 37 10903
TEST_TIME 38 10824
TEST_TIME 39 10819
TEST_TIME 40 10879
TEST_TIME 41 10919
TEST_TIME 42 10801
TEST_TIME 43 10694
TEST_TIME 44 10674
TEST_TIME 45 10567
TEST_TIME 46 10491
TEST_TIME 47 10489
TEST_TIME 48 10356
TEST_TIME 49 10190
TEST_TIME 50 10155
TEST_TIME 51 10011
TEST_TIME 52 9919
TEST_TIME 53 9878
TEST_TIME 54 9791
TEST_TIME 55 9790
TEST_TIME 56 9750
TEST_TIME 57 9743
TEST_TIME 58 9771
TEST_TIME 59 9725
TEST_TIME 60 9653
TEST_TIME 61 9587
TEST_TIME 62 9574
TEST_TIME 63 9471
TEST_TIME 64 9475
TEST_TIME 65 9409
TEST_TIME 66 9366
TEST_TIME 67 9351
TEST_TIME 68 9337
TEST_TIME 69 9316
TEST_TIME 70 9284
TEST_TIME 71 9296
TEST_TIME 72 9336
TEST_TIME 73 9359
TEST_TIME 74 9326
TEST_TIME 75 9356
TEST_TIME 76 9449
TEST_TIME 77 9452
TEST_TIME 78 9442
TEST_TIME 79 9450
TEST_TIME 80 9403
TEST_TIME 81 9549
TEST_TIME 82 9777
TEST_TIME 83 9736
TEST_TIME 84 9669
TEST_TIME 85 9678
TEST_TIME 86 9628
TEST_TIME 87 9620
TEST_TIME 88 9594
TEST_TIME 89 9610
TEST_TIME 90 9558
TEST_TIME 91 9534
TEST_TIME 92 9545
TEST_TIME 93 9465
TEST_TIME 94 9429
TEST_TIME 95 9448
TEST_TIME 96 9381
TEST_TIME 97 9351
TEST_TIME 98 9309
TEST_TIME 99 9277
TEST_TIME 100 9290
TEST_TIME 101 9307
TEST_TIME 102 9266
TEST_TIME 103 9200
TEST_TIME 104 9217
TEST_TIME 105 9194
TEST_TIME 106 9168
TEST_TIME 107 9146
TEST_TIME 108 9133
TEST_TIME 109 9144
TEST_TIME 110 9151
TEST_TIME 111 9107
TEST_TIME 112 9125
TEST_TIME 113 9109
TEST_TIME 114 9173
TEST_TIME 115 9175
TEST_TIME 116 9195
TEST_TIME 117 9177
TEST_TIME 118 9132
TEST_TIME 119 9103
TEST_TIME 120 9032

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-05-2022 04:39:06 to 03-05-2022 04:39:09.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1512086 1512086 0 0.00 0 23201 15 0 0
doc_list_biud 1512076 1512076 0 0.00 0 33518 46 0 0
sp_add_invoice_to_stock 5203 4998 205 3.94 38 194400 28298 0 205
sp_cancel_adding_invoice 5375 5375 0 0.00 7 63125 1333 0 0
sp_cancel_client_order 1767 1767 0 0.00 6 29222 458 0 0
sp_cancel_customer_reserve 526855 526855 0 0.00 1 33520 117 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 4 6722 133 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 1729 127 0 0
sp_cancel_supplier_invoice 5864 5850 14 0.23 3 15920 1088 0 14
sp_cancel_supplier_order 408 408 0 0.00 17 1710396 189607 0 0
sp_cancel_write_off 9288 9288 0 0.00 2 12470 176 0 0
sp_client_order 20569 20569 0 0.00 6 46203 469 0 0
sp_customer_reserve 469607 469607 0 0.00 1 124469 268 0 0
sp_fill_shopping_cart 31546 31546 0 0.00 2 12543 86 0 0
sp_fill_shopping_cart_clo_res 469583 469583 0 0.00 0 16780 19 0 0
sp_get_clo_for_invoice 4967 4967 0 0.00 0 25200 274 0 0
sp_kill_cost_storno 15750 15750 0 0.00 0 23184 85 0 0
sp_kill_qstorno_ret_qs2qd 1069256 1069256 0 0.00 0 33515 50 0 0
sp_kill_qty_storno 564507 564507 0 0.00 0 33518 115 0 0
sp_lock_selected_doc 20693 20693 0 0.00 0 7875 8 0 0
sp_make_cost_storno 27883 27883 0 0.00 0 21031 80 0 0
sp_multiply_rows_for_pdistr 27879 27879 0 0.00 0 20393 40 0 0
sp_multiply_rows_for_qdistr 20569 20569 0 0.00 0 12577 166 0 0
sp_pay_from_customer 7088 7088 0 0.00 6 15048 189 0 0
sp_pay_to_supplier 5827 5827 0 0.00 7 20406 211 0 0
sp_payment_common 12915 12915 0 0.00 2 10938 54 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 1 29213 382 0 0
sp_qd_handle_on_invoice_upd_sts 10339 10339 0 0.00 0 18258 581 0 0
sp_qd_handle_on_reserve_upd_sts 10000 10000 0 0.00 0 20880 56 0 0
sp_reserve_write_off 10001 10001 0 0.00 9 21057 281 0 0
sp_supplier_invoice 5502 5502 0 0.00 9 95866 3235 0 0
sp_supplier_order 5491 5491 0 0.00 8 42988 1376 0 0
srv_aggregate_perf_data 110 110 0 0.00 2368 286366 17838 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 601 131723 31267 0 0
srv_find_qd_qs_mism 1031303 1031303 0 0.00 0 18531 12 0 0
srv_make_invnt_saldo 493 493 0 0.00 42 155415 4618 0 0
srv_make_money_saldo 976 976 0 0.00 1 8375 359 0 0
srv_recalc_idx_stat 5 5 0 0.00 91984 627964 291577 0 0
t$perf-abend:sp_add_invoice_to_stock 205 205 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5006 5006 0 0.00 1 16821 975 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 2 6342 440 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 3582 33 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 2217 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 798 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 508 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 5006 43 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 1 107397 7280 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 2722 21 0 0
t$perf-norm:sp_client_order 20571 20571 0 0.00 0 9132 37 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 2305 20 0 0
t$perf-norm:sp_pay_from_customer 7088 7088 0 0.00 0 7128 19 0 0
t$perf-norm:sp_pay_to_supplier 5827 5827 0 0.00 0 3537 20 0 0
t$perf-norm:sp_reserve_write_off 10001 10001 0 0.00 0 5612 23 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 1738 28 0 0
t$perf-norm:sp_supplier_order 5491 5491 0 0.00 0 2227 31 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 501 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 3470 58 0 0
t$perf-norm:srv_make_invnt_saldo 493 493 0 0.00 0 1143 18 0 0
t$perf-norm:srv_make_money_saldo 976 976 0 0.00 0 519 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 156 46 0 0
v_add_invoice_to_stock 10334 9924 410 3.96 0 3235 27 0 410
v_all_customers 37882 37882 0 0.00 0 125 4 0 0
v_all_suppliers 30640 30640 0 0.00 0 116 4 0 0
v_all_wares 41130 41130 0 0.00 0 66 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 2098 18 0 0
v_cancel_client_order 3534 3534 0 0.00 0 1749 10 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 1017 10 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 1 296 9 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1665 13 0 0
v_cancel_supplier_invoice 750 722 28 3.73 0 3388 32 0 28
v_cancel_supplier_order 816 816 0 0.00 0 4415 19 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2139 24 0 0
v_max_id_clo_ord 5489 5489 0 0.00 0 4833 7 0 0
v_max_id_clo_res 972 972 0 0.00 0 2761 15 0 0
v_max_id_ord_sup 5490 5490 0 0.00 0 4454 7 0 0
v_max_non_paid_invoice 1486 1486 0 0.00 0 64 8 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 512 7 0 0
v_min_id_clo_ord 5489 5489 0 0.00 0 772 7 0 0
v_min_id_clo_res 972 972 0 0.00 0 8584 21 0 0
v_min_id_ord_sup 5490 5490 0 0.00 0 7262 10 0 0
v_min_non_paid_invoice 5827 5827 0 0.00 0 10902 26 0 0
v_min_non_paid_realizn 7087 7087 0 0.00 0 8229 22 0 0
v_our_firm 10030 10030 0 0.00 0 118 5 0 0
v_reserve_write_off 20000 20000 0 0.00 0 5432 11 0 0
x_lock_depdocs_on_canc_invoice 10864 10864 0 0.00 0 15945 125 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 10672 413 0 0
x_make_qstorno_1000_1200 5491 5491 0 0.00 3 42963 1197 0 0
x_make_qstorno_1200_2000 5502 5502 0 0.00 4 95653 3016 0 0
x_make_qstorno_2100_3300 469607 469607 0 0.00 0 124442 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-05-2022 04:39:10 to 03-05-2022 04:39:14.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 40288 8192000000 9736251264 10039975936 814473776 9.043 100 90 181 0 843960400 7998000 21488624
02:10:04 34493 8192000000 9774090208 10085769216 844436064 9.344 100 88 176 0 878162448 12803424 20922960
02:11:46 16320 8192000000 9819906928 10142941184 886450368 9.764 100 81 162 0 930766528 26133872 18182288
02:13:06 12641 8192000000 9879414560 10214584320 922051296 10.116 100 84 168 1 988189184 47616992 18520896
02:14:22 3095 8192000000 9929898960 10274086912 963076608 10.519 100 92 176 1 1043612704 62010384 18525712
02:15:27 2425 8192000000 9955594704 10310676480 989511136 10.777 100 94 181 1 1074119024 66066320 18541568
02:16:32 1387 8192000000 9988158528 10351968256 1015595472 11.029 100 87 172 0 1106044400 72164288 18284640
02:17:36 1061 8192000000 9997897936 10366447616 1036190416 11.228 100 97 190 1 1119959488 65057728 18711344
02:18:38 1098 8192000000 10042203664 10413641728 1054212352 11.401 100 97 182 2 1163106256 90573008 18320896
02:19:40 1021 8192000000 10054343312 10431987712 1064579792 11.500 100 96 184 1 1173814128 90624352 18609984
02:20:44 1151 8192000000 10036652112 10416832512 1077701760 11.626 100 92 178 0 1159431616 62994192 18735664
02:21:46 1057 8192000000 10042727968 10426781696 1079128528 11.639 100 86 169 0 1159649824 62439664 18081632
02:22:53 927 8192000000 10036690192 10425192448 1082828544 11.674 100 82 155 1 1153025088 53254336 16942208
02:23:55 1190 8192000000 10067120624 10456002560 1088140080 11.725 100 95 185 1 1186104768 79164080 18800608
02:24:57 1312 8192000000 10073189344 10461937664 1091000192 11.752 100 96 184 0 1197296320 87256256 19039872
02:26:00 1080 8192000000 10088488144 10480095232 1095453232 11.794 100 100 196 0 1206514816 91050096 20011488
02:27:02 1048 8192000000 10054109856 10445606912 1092403168 11.766 100 91 172 0 1175534336 65913264 17217904
02:28:03 1116 8192000000 10071335264 10461700096 1089378480 11.737 100 97 179 2 1192245280 84397056 18469744
02:29:06 1372 8192000000 10070131296 10459570176 1093057424 11.772 100 88 167 0 1186736320 76913008 16765888
02:30:08 1219 8192000000 10030443216 10408935424 1071773984 11.569 100 98 183 2 1152075056 60989344 19311728
02:31:11 1399 8192000000 10039274288 10417659904 1051542480 11.375 100 96 176 1 1158888400 88964592 18381328
02:32:14 1303 8192000000 10033522896 10415083520 1067140256 11.525 100 95 189 2 1157168720 69943888 20084576
02:33:17 2834 8192000000 10006857920 10391207936 1048479072 11.346 99 70 129 3 1125723200 62933536 14310592
02:34:21 6316 8192000000 9972780784 10346037248 1029461312 11.163 99 80 149 0 1095354768 51646640 14246816
02:35:31 3585 8192000000 9993710512 10372739072 1034332224 11.210 100 65 120 0 1118910032 72675536 11902272
02:36:37 1491 8192000000 9981344528 10356809728 1028284800 11.152 100 82 153 2 1102677456 58926672 15465984
02:37:44 3330 8192000000 9966977760 10345082880 1046197040 11.324 100 66 122 1 1087770192 29090240 12482912
02:38:57 598 8192000000 10012703568 10393341952 1065760320 11.512 100 77 139 1 1136560608 55348416 15451872
02:40:03 2259 8192000000 10057322336 10437881856 1078280752 11.631 100 78 146 2 1175657008 83660432 13715824
02:41:27 1118 8192000000 10107008576 10488926208 1098741248 11.826 100 98 183 0 1225185456 107153184 19291024
02:42:30 1078 8192000000 10161119440 10546589696 1110727168 11.939 100 99 194 0 1281265728 148183392 22355168
02:43:37 9508 8192000000 10175039504 10567356416 1117431280 12.003 100 75 148 1 1292914144 159899616 15583248
02:44:50 1340 8192000000 10209226192 10614718464 1135786432 12.176 100 92 180 0 1326687232 169839120 21061680
02:45:55 4225 8192000000 10228034624 10626781184 1147545184 12.286 100 87 165 0 1334790656 170138464 17107008
02:47:08 1090 8192000000 10244525136 10638503936 1157322032 12.378 100 87 166 3 1354196144 178973584 17900528
02:48:14 1072 8192000000 10298181248 10695413760 1164964272 12.450 100 88 174 0 1412581296 228944480 18672544
02:49:18 1030 8192000000 10335123280 10732118016 1169604496 12.493 99 94 182 3 1446670288 255356208 21709584
02:50:23 1763 8192000000 10409750800 10806370304 1181010768 12.600 100 98 189 0 1520549392 318692928 20845696
02:51:28 952 8192000000 10431637936 10837803008 1180500352 12.595 100 98 190 0 1541882656 339537056 21845248
02:52:31 2070 8192000000 10457427008 10862284800 1189140080 12.675 100 95 187 3 1571191360 360616208 21435072
02:53:37 1476 8192000000 10488581008 10901860352 1195850448 12.738 100 91 177 0 1599080848 382228512 21001888
02:54:41 1147 8192000000 10517697648 10952511488 1199773440 12.774 100 94 174 2 1623864032 401772624 22317968
02:55:45 856 8192000000 10537238352 10964099072 1206261328 12.834 100 95 181 0 1642684864 415470672 20952864
02:56:50 1105 8192000000 10561394848 10981044224 1204993920 12.823 100 98 190 2 1663603392 435714944 22894528
02:57:55 725 8192000000 10552147472 11000246272 1204357216 12.817 100 97 193 0 1669458832 442602720 22498896
02:58:59 1023 8192000000 10527091472 10979119104 1187531632 12.660 100 81 162 0 1631736944 424944624 19260688
03:00:03 741 8192000000 10566363792 10974605312 1187137920 12.657 100 98 191 1 1669296080 460093216 22064944
03:01:06 1117 8192000000 10586127008 11002056704 1195949584 12.739 100 89 172 1 1690442656 472841808 21651264
03:02:11 845 8192000000 10606735744 11053920256 1198093968 12.759 100 98 192 1 1711322704 488419216 24809520
03:03:15 836 8192000000 10578805984 11065249792 1190967424 12.692 100 96 186 0 1680241232 466942544 22331264
03:04:18 14745 8192000000 10583842016 11028971520 1187374480 12.659 100 60 116 0 1702955760 501231360 14349920
03:05:43 13066 8192000000 10629234272 11081490432 1176412624 12.557 100 53 104 1 1732521168 542332336 13776208
03:06:59 12553 8192000000 10600182016 11089068032 1165878016 12.458 100 35 70 1 1703911216 528012464 10020736
03:08:19 18076 8192000000 10441282416 11024609280 1156429680 12.370 99 35 68 0 1585654608 419146688 10078240
03:09:52 49265 8192000000 10242323264 10741481472 1099513712 11.833 95 25 49 2 1343962064 238162304 6286048
03:11:52 31767 8192000000 10160738944 10657624064 1135403888 12.172 99 18 34 2 1270557504 131165584 3988032
03:13:25 39570 8192000000 10124607920 10618720256 1109675648 11.929 97 23 44 0 1212316944 97549664 5091632
03:15:44 67267 8192000000 10101800320 10579365888 1122640336 12.052 98 10 20 0 1169601296 44884432 2076528
03:17:55 131721 8192000000 10053577664 10504314880 1080742704 11.655 95 12 24 1 1114639888 30692576 3204608
03:21:12 82836 8192000000 10052760608 10519457792 1082756736 11.674 95 22 37 0 1133166832 47005472 3404624
03:23:36 65968 8192000000 10038738272 10499362816 1098956960 11.828 97 16 32 0 1156585296 53993968 3634368
03:25:50 97740 8192000000 10080703200 10538635264 1132389680 12.144 100 30 57 1 1207056880 68676064 5991136
03:28:34 26754 8192000000 10091178832 10539302912 1128874560 12.111 100 37 72 1 1191363632 54880208 7608864
03:30:02 97848 8192000000 10101378400 10547425280 1106052576 11.895 98 33 66 0 1177244912 64205680 6986656
03:32:42 13237 8192000000 10118462800 10545328128 1141477648 12.229 100 64 119 0 1207604592 53663904 12463040
03:33:58 57723 8192000000 10104484528 10567925760 1123551840 12.061 99 31 62 0 1221474352 91209392 6713120
03:35:56 21602 8192000000 10067732928 10541846528 1130758480 12.129 100 22 44 0 1173920032 38176192 4985360
03:37:20 20306 8192000000 10069511904 10527903744 1143298320 12.247 100 17 34 0 1178668160 31100144 4269696
03:38:46 26176 8192000000 10088132016 10565910528 1135893456 12.177 99 11 21 0 1195218864 57335792 1989616
03:40:13 101767 8192000000 10064492640 10567368704 1132287328 12.143 99 10 20 1 1183488800 49458416 1743056
03:42:55 33626 8192000000 10070487760 10525429760 1149508880 12.305 100 24 43 1 1168912272 15340000 4063392
03:44:31 12662 8192000000 10114215904 10548940800 1148093488 12.292 99 43 85 0 1198770880 41416208 9261184
03:45:47 20164 8192000000 10155846016 10593128448 1137806240 12.195 98 42 84 1 1234055216 87494944 8754032
03:47:09 15290 8192000000 10193001552 10629660672 1138275008 12.199 99 43 82 1 1285085744 137038016 9772720
03:48:29 11045 8192000000 10248146384 10702286848 1158068480 12.385 100 39 76 2 1345435008 178604592 8761936
03:49:50 15360 8192000000 10246314624 10681491456 1140471264 12.220 99 42 84 0 1327916096 177517728 9927104
03:51:07 17630 8192000000 10291207488 10722455552 1171773392 12.513 100 54 108 1 1376566848 192984624 11808832
03:52:29 32160 8192000000 10289325248 10748698624 1143894352 12.252 99 39 76 0 1392188352 238532544 9761456
03:54:05 26198 8192000000 10270425664 10724741120 1139555120 12.211 98 34 65 2 1345994592 198371040 8068432
03:55:33 19833 8192000000 10309581936 10760708096 1160218416 12.405 100 38 72 0 1409810016 241296000 8295600
03:56:57 57761 8192000000 10306137936 10761375744 1144350496 12.256 100 30 60 0 1390405136 238989056 7065584
03:58:55 63223 8192000000 10107069824 10721767424 1113491792 11.965 99 10 20 0 1254839248 139395344 1952112
04:00:59 23085 8192000000 10103946464 10524880896 1138701024 12.203 100 55 108 0 1190859744 40084464 12074256
04:02:37 27547 8192000000 10106993408 10545041408 1120386400 12.031 99 50 98 0 1192182096 61901824 9893872
04:04:07 15281 8192000000 10148658208 10578980864 1140505392 12.220 100 50 100 1 1231898224 81629456 9763376
04:05:23 130856 8192000000 10084436224 10516664320 1056595056 11.424 93 32 60 0 1120894000 58430016 5868928
04:08:35 20345 8192000000 10111197696 10542923776 1099393280 11.832 98 50 100 0 1184643952 75517536 9733136
04:09:57 79568 8192000000 10091958304 10530938880 1129103952 12.113 100 32 64 0 1200584320 64768688 6711680
04:12:22 38540 8192000000 10098061936 10555613184 1122978144 12.055 99 38 75 2 1185102576 54659152 7465280
04:14:02 1245 8192000000 10095718768 10525585408 1134997552 12.168 100 64 120 0 1194232208 45994704 13239952
04:15:05 47746 8192000000 10121840944 10571538432 1144287968 12.256 100 25 50 2 1238770144 89196208 5285968
04:16:53 84200 8192000000 10124978528 10639335424 1131873104 12.139 98 19 38 0 1225629184 89670016 4086064
04:19:21 92293 8192000000 10089958192 10544689152 1065705968 11.511 93 14 28 0 1126505248 56763120 4036160
04:22:15 43313 8192000000 10063046992 10526941184 1134646000 12.165 100 15 30 1 1165673616 27552480 3475136
04:23:59 17634 8192000000 10085720720 10522300416 1140744528 12.223 100 56 102 1 1186581552 34607952 11229072
04:25:17 56742 8192000000 10097949584 10564038656 1115635536 11.986 97 28 57 0 1214577936 92823600 6118800
04:27:29 98504 8192000000 10134960624 10615107584 1136171312 12.180 99 22 44 1 1224555872 83697008 4687552
04:30:19 21447 8192000000 10165385008 10603266048 1137448672 12.192 100 45 91 0 1236250096 89207728 9593696
04:31:58 38885 8192000000 10097055632 10543714304 1130211424 12.123 99 29 58 0 1179812592 43463328 6137840
04:33:37 51377 8192000000 10129954768 10584956928 1121927264 12.045 99 51 101 1 1216861520 84667760 10266496
04:35:30 99948 8192000000 10076676464 10541535232 1102652320 11.863 97 31 62 1 1175500784 66662832 6185632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-05-2022 04:39:16 to 03-05-2022 04:39:32.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-05-2022 04:39:33 to 03-05-2022 04:39:35.

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          220348
MON$OLDEST_ACTIVE               221906
MON$OLDEST_SNAPSHOT             221906
MON$NEXT_TRANSACTION            221906
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                       2107968
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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 ---
--- end of diff output ---

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

03.05.22 04:39:36. 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
2022-05-03 04:39:39 No severe PSQL-related problems occured
03.05.22 04:39:40. End of report.