Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2021 16:42
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 15902464 kB
MemAvailable 30602064 kB
Buffers 0 kB
Cached 15769684 kB
SwapCached 3704 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-21 16:41:56
DTS_END                         2021-10-21 16:41:56.0670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36376
TRN_ID                          245647
ATT_ID                          682
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: 9165.92 22878 1099910
customer order: creation 179.28 339 21513
customer order: refuse 15.50 366 1860
order to supplier: creation 46.65 1240 5598
order to supplier: removal 3.20 104065 384
invoice (draft): creation 47.95 3397 5754
invoice (draft): removal 52.13 710 6255
invoice accept: apply 42.57 18050 5108
invoice accept: cancel 47.08 738 5650
customer reserve: creation 3808.23 184 456988
customer reserve: removal 4624.37 56 554924
realization accept: apply 85.61 241 10273
realization accept: cancel 78.21 113 9385
payment from customer: creation 61.23 159 7347
payment from customer: removal 5.84 193 701
payment to supplier: creation 51.20 175 6144
payment to supplier: removal 3.89 158 467
service: total inventory turnovers 5.28 1005 634
service: total monetary turnovers 6.81 27 817
service: aggregate perf. data 0.87 19384 104
service: refresh index statistics 0.03 306952 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2021 16:42:55 to 21-10-2021 16:42:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1769
TEST_TIME 2 3379
TEST_TIME 3 3572
TEST_TIME 4 3789
TEST_TIME 5 4004
TEST_TIME 6 4270
TEST_TIME 7 4575
TEST_TIME 8 4933
TEST_TIME 9 5042
TEST_TIME 10 5355
TEST_TIME 11 5546
TEST_TIME 12 5703
TEST_TIME 13 5939
TEST_TIME 14 6245
TEST_TIME 15 6520
TEST_TIME 16 6510
TEST_TIME 17 6784
TEST_TIME 18 7243
TEST_TIME 19 7580
TEST_TIME 20 7741
TEST_TIME 21 7787
TEST_TIME 22 7608
TEST_TIME 23 8062
TEST_TIME 24 8500
TEST_TIME 25 8441
TEST_TIME 26 8886
TEST_TIME 27 9909
TEST_TIME 28 10470
TEST_TIME 29 10413
TEST_TIME 30 10245
TEST_TIME 31 10074
TEST_TIME 32 9860
TEST_TIME 33 9733
TEST_TIME 34 9569
TEST_TIME 35 9696
TEST_TIME 36 9687
TEST_TIME 37 9575
TEST_TIME 38 9557
TEST_TIME 39 9424
TEST_TIME 40 9363
TEST_TIME 41 9292
TEST_TIME 42 9175
TEST_TIME 43 9045
TEST_TIME 44 9000
TEST_TIME 45 8884
TEST_TIME 46 8945
TEST_TIME 47 8878
TEST_TIME 48 8969
TEST_TIME 49 8965
TEST_TIME 50 9003
TEST_TIME 51 8933
TEST_TIME 52 9082
TEST_TIME 53 9030
TEST_TIME 54 9127
TEST_TIME 55 9125
TEST_TIME 56 9184
TEST_TIME 57 9180
TEST_TIME 58 9247
TEST_TIME 59 9212
TEST_TIME 60 9099
TEST_TIME 61 9023
TEST_TIME 62 8963
TEST_TIME 63 8990
TEST_TIME 64 8990
TEST_TIME 65 8957
TEST_TIME 66 8898
TEST_TIME 67 8913
TEST_TIME 68 8907
TEST_TIME 69 8983
TEST_TIME 70 9037
TEST_TIME 71 9057
TEST_TIME 72 8986
TEST_TIME 73 9136
TEST_TIME 74 9092
TEST_TIME 75 9175
TEST_TIME 76 9224
TEST_TIME 77 9186
TEST_TIME 78 9203
TEST_TIME 79 9222
TEST_TIME 80 9145
TEST_TIME 81 9071
TEST_TIME 82 9087
TEST_TIME 83 9116
TEST_TIME 84 9068
TEST_TIME 85 9011
TEST_TIME 86 8989
TEST_TIME 87 9067
TEST_TIME 88 9054
TEST_TIME 89 9037
TEST_TIME 90 8996
TEST_TIME 91 9061
TEST_TIME 92 9051
TEST_TIME 93 9106
TEST_TIME 94 9185
TEST_TIME 95 9220
TEST_TIME 96 9259
TEST_TIME 97 9270
TEST_TIME 98 9257
TEST_TIME 99 9323
TEST_TIME 100 9344
TEST_TIME 101 9340
TEST_TIME 102 9415
TEST_TIME 103 9338
TEST_TIME 104 9285
TEST_TIME 105 9241
TEST_TIME 106 9233
TEST_TIME 107 9197
TEST_TIME 108 9151
TEST_TIME 109 9135
TEST_TIME 110 9088
TEST_TIME 111 9127
TEST_TIME 112 9122
TEST_TIME 113 9123
TEST_TIME 114 9117
TEST_TIME 115 9085
TEST_TIME 116 9182
TEST_TIME 117 9158
TEST_TIME 118 9185
TEST_TIME 119 9158
TEST_TIME 120 9170

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2021 16:42:59 to 21-10-2021 16:43: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 1525855 1525855 0 0.00 0 43988 7 0 0
doc_list_biud 1525855 1525855 0 0.00 0 31262 26 0 0
sp_add_invoice_to_stock 5217 5108 109 2.08 24 207818 18050 0 109
sp_cancel_adding_invoice 5650 5650 0 0.00 3 46336 738 0 0
sp_cancel_client_order 1860 1860 0 0.00 4 20659 367 0 0
sp_cancel_customer_reserve 554924 554924 0 0.00 1 29026 57 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 25094 193 0 0
sp_cancel_pay_to_supplier 467 467 0 0.00 2 23312 158 0 0
sp_cancel_supplier_invoice 6263 6255 8 0.12 1 28341 711 0 8
sp_cancel_supplier_order 384 384 0 0.00 3 1110423 104066 0 0
sp_cancel_write_off 9385 9385 0 0.00 1 14625 114 0 0
sp_client_order 21513 21513 0 0.00 4 76294 340 0 0
sp_customer_reserve 456988 456988 0 0.00 0 88494 184 0 0
sp_fill_shopping_cart 32864 32864 0 0.00 1 12844 42 0 0
sp_fill_shopping_cart_clo_res 456985 456985 0 0.00 0 21079 14 0 0
sp_get_clo_for_invoice 5103 5103 0 0.00 0 27883 287 0 0
sp_kill_cost_storno 16203 16203 0 0.00 0 23915 63 0 0
sp_kill_qstorno_ret_qs2qd 1125872 1125872 0 0.00 0 28985 30 0 0
sp_kill_qty_storno 593833 593833 0 0.00 0 31259 66 0 0
sp_lock_selected_doc 21364 21364 0 0.00 0 9083 6 0 0
sp_make_cost_storno 28866 28866 0 0.00 0 41290 74 0 0
sp_multiply_rows_for_pdistr 28866 28866 0 0.00 0 29419 43 0 0
sp_multiply_rows_for_qdistr 21513 21513 0 0.00 0 76284 162 0 0
sp_pay_from_customer 7347 7347 0 0.00 4 39020 160 0 0
sp_pay_to_supplier 6144 6144 0 0.00 4 42644 175 0 0
sp_payment_common 13491 13491 0 0.00 1 30759 62 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 20651 335 0 0
sp_qd_handle_on_invoice_upd_sts 10752 10752 0 0.00 0 31253 329 0 0
sp_qd_handle_on_reserve_upd_sts 10273 10273 0 0.00 0 19790 61 0 0
sp_reserve_write_off 10273 10273 0 0.00 6 44006 242 0 0
sp_supplier_invoice 5754 5754 0 0.00 7 62624 3397 0 0
sp_supplier_order 5598 5598 0 0.00 5 29555 1240 0 0
srv_aggregate_perf_data 104 104 0 0.00 2348 198638 19385 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2085 149658 47231 0 0
srv_find_qd_qs_mism 1052516 1052516 0 0.00 0 28087 6 0 0
srv_make_invnt_saldo 634 634 0 0.00 38 63938 1006 0 0
srv_make_money_saldo 817 817 0 0.00 0 3538 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 160814 492410 306953 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5109 5109 0 0.00 0 18763 186 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 4529 103 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 8081 20 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2034 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 2061 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 467 467 0 0.00 0 446 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 785 14 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 26577 1637 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 1660 6 0 0
t$perf-norm:sp_client_order 21513 21513 0 0.00 0 17800 20 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 8568 12 0 0
t$perf-norm:sp_pay_from_customer 7347 7347 0 0.00 0 17776 13 0 0
t$perf-norm:sp_pay_to_supplier 6144 6144 0 0.00 0 9436 13 0 0
t$perf-norm:sp_reserve_write_off 10273 10273 0 0.00 0 16667 17 0 0
t$perf-norm:sp_supplier_invoice 5754 5754 0 0.00 0 9266 19 0 0
t$perf-norm:sp_supplier_order 5598 5598 0 0.00 0 8205 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 507 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 188 8 0 0
t$perf-norm:srv_make_invnt_saldo 634 634 0 0.00 0 1191 20 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 882 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 207 54 0 0
v_add_invoice_to_stock 10422 10204 218 2.09 0 8058 18 0 218
v_all_customers 38984 38984 0 0.00 0 15 1 0 0
v_all_suppliers 31368 31368 0 0.00 0 14 1 0 0
v_all_wares 43026 43026 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 9033 26 0 0
v_cancel_client_order 3720 3720 0 0.00 0 7993 7 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 2692 9 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 566 4 0 0
v_cancel_payment_to_supplier 934 934 0 0.00 0 2273 9 0 0
v_cancel_supplier_invoice 888 872 16 1.80 0 3416 22 0 16
v_cancel_supplier_order 768 768 0 0.00 0 870 6 0 0
v_cancel_write_off 2056 2056 0 0.00 0 1722 12 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 1675 2 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 1857 10 0 0
v_max_id_ord_sup 5753 5753 0 0.00 0 7001 6 0 0
v_max_non_paid_invoice 1811 1811 0 0.00 0 78 2 0 0
v_max_non_paid_realizn 3858 3858 0 0.00 0 14751 10 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 7021 11 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 2858 9 0 0
v_min_id_ord_sup 5753 5753 0 0.00 0 6041 9 0 0
v_min_non_paid_invoice 6144 6144 0 0.00 0 29399 51 0 0
v_min_non_paid_realizn 7347 7347 0 0.00 0 29748 54 0 0
v_our_firm 11020 11020 0 0.00 0 17 1 0 0
v_reserve_write_off 20546 20546 0 0.00 0 8058 7 0 0
x_lock_depdocs_on_canc_invoice 11469 11469 0 0.00 0 19188 124 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 6958 350 0 0
x_make_qstorno_1000_1200 5598 5598 0 0.00 2 29548 1144 0 0
x_make_qstorno_1200_2000 5754 5754 0 0.00 3 61632 3254 0 0
x_make_qstorno_2100_3300 456988 456988 0 0.00 0 88494 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2021 16:43:03 to 21-10-2021 16:43: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
14:11:59 2 8192000000 8797542112 8808267776 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:00 27746 8192000000 9923402944 10239606784 1000747488 10.886 100 83 165 1 1027410736 5534624 21128624
14:14:33 16634 8192000000 9959662912 10284265472 1037548320 11.241 100 93 186 0 1073300080 12193280 23558480
14:15:56 18584 8192000000 9993474416 10327494656 1072286768 11.574 100 85 170 0 1102994928 9660096 21048064
14:17:21 8345 8192000000 10043315920 10393755648 1129577280 12.117 100 84 168 0 1160420352 11121840 19721232
14:18:36 5613 8192000000 10090725152 10452488192 1180590592 12.596 100 80 159 3 1210828224 11710592 18527040
14:19:48 6923 8192000000 10137825232 10511130624 1227448672 13.031 100 76 151 0 1256708336 12789616 16470048
14:20:58 3966 8192000000 10171610176 10548256768 1249297648 13.232 100 79 159 0 1281147024 14507056 17342320
14:22:03 1939 8192000000 10201335232 10580848640 1282181328 13.533 100 88 176 1 1315686272 14126288 19378656
14:23:06 2777 8192000000 10201640896 10589810688 1296048720 13.659 100 83 165 0 1327427136 13557744 17820672
14:24:10 15876 8192000000 10197244752 10601566208 1301077456 13.705 100 52 103 2 1320249344 6475616 12696272
14:25:27 3032 8192000000 10209585552 10613420032 1310975776 13.795 100 52 105 2 1330945728 8610832 11359120
14:26:34 10072 8192000000 10204639616 10619707392 1316965648 13.849 100 23 46 1 1329029616 7061440 5002528
14:27:49 9197 8192000000 10208737312 10629804032 1324755520 13.920 100 23 46 1 1336522624 6864016 4903088
14:29:04 45922 8192000000 10216990160 10647289856 1334925216 14.012 100 31 62 1 1347901936 5189168 7787552
14:30:50 5896 8192000000 10227026528 10653958144 1337702864 14.037 100 40 74 2 1353571152 6259808 9608480
14:31:59 10770 8192000000 10203759776 10623676416 1287072336 13.578 97 16 32 0 1296279360 5095504 4111520
14:33:10 12470 8192000000 10161599696 10559578112 1193848192 12.719 93 27 54 0 1205238944 4959504 6431248
14:34:28 10779 8192000000 10121626288 10530922496 1208613024 12.856 96 35 70 1 1224244304 7938096 7693184
14:35:40 8233 8192000000 10143533280 10539958272 1214169504 12.908 97 20 41 0 1224496800 5638576 4688720
14:36:49 9190 8192000000 10165264064 10566893568 1268451088 13.407 100 25 50 1 1279832320 4870368 6510864
14:38:02 41478 8192000000 10202999168 10593157120 1286519136 13.572 100 73 146 3 1311443328 6026784 18897408
14:39:49 9999 8192000000 10188339952 10603122688 1298639120 13.683 100 21 42 0 1309583808 5346288 5598400
14:41:02 10678 8192000000 10208166368 10625957888 1318666720 13.865 100 12 22 1 1326246896 4582368 2997808
14:42:16 88914 8192000000 10246966096 10661199872 1341009408 14.067 100 45 88 0 1360683552 9269120 10405024
14:44:48 14653 8192000000 10265309984 10686636032 1360290272 14.240 100 39 77 1 1379535696 10402512 8842912
14:46:07 23268 8192000000 10288812000 10706280448 1374052704 14.363 100 52 102 3 1401421216 15403072 11965440
14:47:52 14775 8192000000 10311405584 10732290048 1392166928 14.525 100 46 92 1 1419815888 16807616 10841344
14:49:16 7629 8192000000 10323016208 10746863616 1404937696 14.639 100 63 126 0 1439675392 18728752 16008944
14:50:28 4382 8192000000 10330945680 10747953152 1408442432 14.670 100 54 107 1 1442568800 22236368 11890000
14:51:37 5681 8192000000 10339252256 10755739648 1404564544 14.636 100 73 147 1 1450054912 28342448 17147920
14:52:46 9987 8192000000 10355729152 10778427392 1413145840 14.712 100 49 98 0 1458933200 33692864 12094496
14:54:01 4378 8192000000 10364521840 10777309184 1407992080 14.666 100 74 148 1 1462661328 36071728 18597520
14:55:12 6550 8192000000 10348574224 10759335936 1393991952 14.541 100 71 143 0 1455848928 44121968 17735008
14:56:23 4094 8192000000 10317629296 10728120320 1362521584 14.260 100 62 124 0 1426172672 47686384 15964704
14:57:33 4071 8192000000 10324377856 10729177088 1352137328 14.167 99 74 146 1 1420619856 50390848 18091680
14:58:42 4270 8192000000 10353141728 10757820416 1365586160 14.287 99 82 162 1 1444865552 59441392 19838000
14:59:52 5808 8192000000 10342601136 10741334016 1353300304 14.177 99 80 156 2 1432939440 60179744 19459392
15:01:02 4725 8192000000 10353727072 10760855552 1372712240 14.351 99 67 134 1 1442845296 53770672 16362384
15:02:19 23578 8192000000 10343789600 10764943360 1360671552 14.243 98 25 50 1 1422410176 54636560 7102064
15:03:47 2083 8192000000 10355818880 10767388672 1376978816 14.390 100 58 116 0 1455056384 62848208 15229360
15:04:55 11628 8192000000 10349002672 10780213248 1381679216 14.432 100 35 70 1 1455698128 64192064 9826848
15:06:17 17575 8192000000 10349253472 10777653248 1389349632 14.500 100 29 58 0 1451578400 53628160 8600608
15:07:37 28290 8192000000 10320702816 10764689408 1363883792 14.272 100 20 40 1 1425546640 55277696 6385152
15:09:32 52760 8192000000 10255438080 10714157056 1332351376 13.988 98 12 23 1 1352176416 17086864 2738176
15:11:43 46829 8192000000 10280202448 10729295872 1361580400 14.252 99 20 40 0 1379163312 13348400 4234512
15:13:33 133464 8192000000 10299956288 10744537088 1339037776 14.049 97 24 48 0 1358586608 13713632 5835200
15:16:47 109774 8192000000 10281900880 10743996416 1371664560 14.342 99 20 42 0 1389021504 11889552 5467392
15:20:04 38646 8192000000 10285315120 10740600832 1367593648 14.305 99 20 40 0 1379941552 8114832 4233072
15:21:44 49547 8192000000 10274423040 10723975168 1360366720 14.241 99 23 46 3 1373324128 6755392 6202016
15:23:37 53737 8192000000 10280115904 10739281920 1379736128 14.414 100 18 35 1 1396186016 12289328 4160560
15:25:31 96935 8192000000 10290727056 10745135104 1370554288 14.332 99 20 40 0 1388751664 13533360 4664016
15:28:15 37662 8192000000 10279992800 10737778688 1378763456 14.405 100 29 58 1 1396753424 11360784 6629184
15:29:53 101628 8192000000 10281127520 10741145600 1349361728 14.142 97 14 28 0 1364010848 11376032 3273088
15:32:53 40555 8192000000 10293912176 10751877120 1384072112 14.453 100 44 88 0 1408450592 14413472 9965008
15:34:39 61342 8192000000 10277492816 10736390144 1344420800 14.097 97 23 46 0 1361851440 11960160 5470480
15:36:41 54489 8192000000 10258570624 10700558336 1307659264 13.765 96 25 50 0 1327941392 14308176 5973952
15:38:45 81098 8192000000 10268994432 10715881472 1336444320 14.025 98 19 38 2 1352599920 11743088 4412512
15:41:07 91478 8192000000 10283325424 10723368960 1365859216 14.290 100 45 90 1 1385801488 10171488 9770784
15:43:48 28725 8192000000 10290671552 10740645888 1377174592 14.391 100 30 61 1 1402414064 18090720 7148752
15:46:00 34744 8192000000 10233601408 10695688192 1304700288 13.738 97 3 6 0 1323285936 18131536 454112
15:47:35 60103 8192000000 10247610672 10695385088 1315335200 13.834 97 21 43 1 1330200304 9953760 4911344
15:49:36 59635 8192000000 10251144464 10708533248 1292924640 13.631 95 14 28 2 1309208224 12807200 3476384
15:51:44 59327 8192000000 10246244832 10694574080 1317564016 13.855 98 19 38 0 1334958784 12936176 4458592
15:53:57 61945 8192000000 10244914464 10704637952 1327429088 13.944 98 22 44 0 1348957120 16183248 5344784
15:56:00 56617 8192000000 10278720096 10727817216 1346036384 14.112 99 16 32 0 1365840176 15954432 3849360
15:58:01 46280 8192000000 10268513616 10702901248 1325517216 13.927 97 37 74 2 1344041920 10625936 7898768
15:59:50 30829 8192000000 10276398144 10724478976 1350517904 14.152 99 21 42 3 1364958512 10226960 4213648
16:01:23 92558 8192000000 10293486720 10728103936 1370215936 14.329 100 43 86 2 1390027264 9922128 9889200
16:04:05 38882 8192000000 10266728304 10723336192 1350744848 14.154 99 29 56 1 1371841088 15079872 6016368
16:05:55 34363 8192000000 10275359472 10737295360 1376027008 14.381 100 9 18 0 1394487376 16459440 2000928
16:07:29 31244 8192000000 10283930480 10752512000 1384489968 14.457 100 7 14 0 1403358672 17082032 1786672
16:09:05 52866 8192000000 10274442640 10728333312 1349022752 14.139 98 8 16 1 1361792928 11344000 1426176
16:11:02 43658 8192000000 10286662832 10737467392 1373803792 14.361 99 25 48 0 1388670080 9276000 5590288
16:12:46 33433 8192000000 10292435040 10744782848 1368162688 14.311 98 23 46 0 1382748672 9847200 4738784
16:14:22 90888 8192000000 10310430000 10784157696 1372004496 14.345 98 14 28 0 1390953424 15597392 3351536
16:16:54 90123 8192000000 10297871392 10745241600 1327333728 13.943 95 16 31 1 1340527808 9598032 3596048
16:19:28 49832 8192000000 10294703264 10752024576 1356044272 14.202 98 12 24 0 1375338368 16119040 3175056
16:21:19 108609 8192000000 10298299536 10761449472 1316542224 13.845 95 6 12 1 1335577696 17678192 1357280
16:24:08 149658 8192000000 10283861088 10728243200 1328021904 13.949 96 25 50 0 1346729792 13406768 5301120
16:27:39 31473 8192000000 10272636064 10730831872 1371249504 14.338 100 15 30 0 1380869968 6676000 2944464
16:29:17 82983 8192000000 10267751488 10725122048 1327025280 13.940 96 8 16 0 1340395200 11435792 1934128
16:31:41 95331 8192000000 10259446032 10728239104 1342451712 14.080 98 13 26 1 1359054544 13230896 3371936
16:34:23 64492 8192000000 10263756736 10722705408 1317999440 13.859 96 11 22 0 1332538896 12431712 2107744
16:36:28 6025 8192000000 10266293728 10714263552 1361389696 14.250 100 34 67 0 1380918896 11909552 7619648
16:37:37 107093 8192000000 10263579136 10697314304 1277876672 13.494 94 16 32 0 1293801376 12550928 3373776
16:40:28 18150 8192000000 10231571296 10658729984 1274163808 13.460 96 41 82 1 1294093056 10733184 9196064
16:41:49 26554 8192000000 9190996256 9445158912 344446608 4.035 27 3 4 1 354278080 9453424 378048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-10-2021 16:43:09 to 21-10-2021 16:43:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2021 16:43:25 to 21-10-2021 16:43:27.

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          245952
MON$OLDEST_ACTIVE               245953
MON$OLDEST_SNAPSHOT             245953
MON$NEXT_TRANSACTION            245953
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                       2024128
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.33522 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33522 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33522 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33522 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 ---
--- end of diff output ---

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

21.10.21 16:43:28. 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-21 16:43:30 No severe PSQL-related problems occured
21.10.21 16:43:32. End of report.