Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.08.2021 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16221292 kB
MemAvailable 30914000 kB
Buffers 0 kB
Cached 15438356 kB
SwapCached 1324 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-08-27 19:37:49
DTS_END                         2021-08-27 19:37:49.2670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53148
TRN_ID                          236604
ATT_ID                          651
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: 9234.88 19378 1108185
customer order: creation 167.16 364 20059
customer order: refuse 14.42 358 1730
order to supplier: creation 45.92 1250 5510
order to supplier: removal 3.35 110621 402
invoice (draft): creation 44.83 3257 5380
invoice (draft): removal 54.83 913 6580
invoice accept: apply 40.56 18518 4867
invoice accept: cancel 48.87 813 5864
customer reserve: creation 3756.01 182 450721
customer reserve: removal 4765.93 59 571911
realization accept: apply 81.28 205 9753
realization accept: cancel 85.03 103 10203
payment from customer: creation 57.81 139 6937
payment from customer: removal 5.58 82 670
payment to supplier: creation 47.83 146 5740
payment to supplier: removal 3.06 108 367
service: total inventory turnovers 4.97 903 596
service: total monetary turnovers 6.58 24 789
service: aggregate perf. data 0.85 15440 102
service: refresh index statistics 0.03 234067 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-08-2021 19:39:05 to 27-08-2021 19:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2297
TEST_TIME 2 4300
TEST_TIME 3 4396
TEST_TIME 4 5173
TEST_TIME 5 6154
TEST_TIME 6 5987
TEST_TIME 7 6127
TEST_TIME 8 6803
TEST_TIME 9 6708
TEST_TIME 10 6510
TEST_TIME 11 6414
TEST_TIME 12 6538
TEST_TIME 13 6697
TEST_TIME 14 6773
TEST_TIME 15 6792
TEST_TIME 16 7025
TEST_TIME 17 7024
TEST_TIME 18 7121
TEST_TIME 19 7247
TEST_TIME 20 7536
TEST_TIME 21 8075
TEST_TIME 22 8578
TEST_TIME 23 8491
TEST_TIME 24 8523
TEST_TIME 25 8601
TEST_TIME 26 8926
TEST_TIME 27 9523
TEST_TIME 28 9636
TEST_TIME 29 10004
TEST_TIME 30 10454
TEST_TIME 31 10760
TEST_TIME 32 10438
TEST_TIME 33 10226
TEST_TIME 34 10324
TEST_TIME 35 10225
TEST_TIME 36 10105
TEST_TIME 37 10024
TEST_TIME 38 9861
TEST_TIME 39 9813
TEST_TIME 40 9838
TEST_TIME 41 9819
TEST_TIME 42 9697
TEST_TIME 43 9801
TEST_TIME 44 9691
TEST_TIME 45 9659
TEST_TIME 46 9524
TEST_TIME 47 9598
TEST_TIME 48 9474
TEST_TIME 49 9408
TEST_TIME 50 9351
TEST_TIME 51 9302
TEST_TIME 52 9292
TEST_TIME 53 9333
TEST_TIME 54 9330
TEST_TIME 55 9252
TEST_TIME 56 9195
TEST_TIME 57 9161
TEST_TIME 58 9049
TEST_TIME 59 9172
TEST_TIME 60 9139
TEST_TIME 61 9135
TEST_TIME 62 9121
TEST_TIME 63 9158
TEST_TIME 64 9175
TEST_TIME 65 9158
TEST_TIME 66 9219
TEST_TIME 67 9268
TEST_TIME 68 9310
TEST_TIME 69 9268
TEST_TIME 70 9331
TEST_TIME 71 9356
TEST_TIME 72 9389
TEST_TIME 73 9361
TEST_TIME 74 9361
TEST_TIME 75 9347
TEST_TIME 76 9248
TEST_TIME 77 9271
TEST_TIME 78 9224
TEST_TIME 79 9184
TEST_TIME 80 9157
TEST_TIME 81 9172
TEST_TIME 82 9124
TEST_TIME 83 9136
TEST_TIME 84 9109
TEST_TIME 85 9161
TEST_TIME 86 9167
TEST_TIME 87 9138
TEST_TIME 88 9229
TEST_TIME 89 9208
TEST_TIME 90 9186
TEST_TIME 91 9221
TEST_TIME 92 9251
TEST_TIME 93 9251
TEST_TIME 94 9278
TEST_TIME 95 9308
TEST_TIME 96 9341
TEST_TIME 97 9321
TEST_TIME 98 9361
TEST_TIME 99 9387
TEST_TIME 100 9308
TEST_TIME 101 9252
TEST_TIME 102 9304
TEST_TIME 103 9292
TEST_TIME 104 9267
TEST_TIME 105 9275
TEST_TIME 106 9272
TEST_TIME 107 9246
TEST_TIME 108 9204
TEST_TIME 109 9186
TEST_TIME 110 9161
TEST_TIME 111 9154
TEST_TIME 112 9166
TEST_TIME 113 9162
TEST_TIME 114 9176
TEST_TIME 115 9176
TEST_TIME 116 9163
TEST_TIME 117 9230
TEST_TIME 118 9214
TEST_TIME 119 9211
TEST_TIME 120 9236

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-08-2021 19:39:08 to 27-08-2021 19:39:12.

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 1525959 1525959 0 0.00 0 24212 6 0 0
doc_list_biud 1525959 1525959 0 0.00 0 62577 29 0 0
sp_add_invoice_to_stock 4966 4867 99 1.99 40 202815 18518 0 99
sp_cancel_adding_invoice 5864 5864 0 0.00 5 64707 814 0 0
sp_cancel_client_order 1730 1730 0 0.00 4 28090 359 0 0
sp_cancel_customer_reserve 571911 571911 0 0.00 1 62579 60 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 2771 82 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 4932 109 0 0
sp_cancel_supplier_invoice 6589 6580 9 0.13 2 27906 913 0 9
sp_cancel_supplier_order 402 402 0 0.00 10 1109670 110622 0 0
sp_cancel_write_off 10203 10203 0 0.00 1 29703 104 0 0
sp_client_order 20059 20059 0 0.00 3 30356 365 0 0
sp_customer_reserve 450721 450721 0 0.00 0 48909 183 0 0
sp_fill_shopping_cart 30945 30945 0 0.00 1 7931 37 0 0
sp_fill_shopping_cart_clo_res 450714 450714 0 0.00 0 19030 13 0 0
sp_get_clo_for_invoice 4860 4860 0 0.00 0 21452 295 0 0
sp_kill_cost_storno 17104 17104 0 0.00 0 23726 58 0 0
sp_kill_qstorno_ret_qs2qd 1161007 1161007 0 0.00 0 62575 33 0 0
sp_kill_qty_storno 611300 611300 0 0.00 0 62577 71 0 0
sp_lock_selected_doc 20269 20269 0 0.00 0 6548 3 0 0
sp_make_cost_storno 27287 27287 0 0.00 0 24189 62 0 0
sp_multiply_rows_for_pdistr 27287 27287 0 0.00 0 13286 26 0 0
sp_multiply_rows_for_qdistr 20059 20059 0 0.00 0 27807 153 0 0
sp_pay_from_customer 6937 6937 0 0.00 4 41072 139 0 0
sp_pay_to_supplier 5740 5740 0 0.00 4 24221 146 0 0
sp_payment_common 12677 12677 0 0.00 1 41061 33 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 28083 330 0 0
sp_qd_handle_on_invoice_upd_sts 10721 10721 0 0.00 0 41252 348 0 0
sp_qd_handle_on_reserve_upd_sts 9753 9753 0 0.00 0 29562 77 0 0
sp_reserve_write_off 9753 9753 0 0.00 6 29786 206 0 0
sp_supplier_invoice 5380 5380 0 0.00 6 83864 3257 0 0
sp_supplier_order 5510 5510 0 0.00 5 42114 1250 0 0
srv_aggregate_perf_data 102 102 0 0.00 2759 85329 15441 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1083 123805 48183 0 0
srv_find_qd_qs_mism 1061778 1061778 0 0.00 0 10362 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 53 22791 903 0 0
srv_make_money_saldo 789 789 0 0.00 1 512 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 125752 419640 234068 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4867 4867 0 0.00 0 14347 187 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 3422 98 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 10456 29 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1217 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 3530 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 148 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2453 24 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 29098 1959 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 3010 8 0 0
t$perf-norm:sp_client_order 20059 20059 0 0.00 0 12504 21 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 4790 12 0 0
t$perf-norm:sp_pay_from_customer 6937 6937 0 0.00 0 44735 18 0 0
t$perf-norm:sp_pay_to_supplier 5740 5740 0 0.00 0 6593 14 0 0
t$perf-norm:sp_reserve_write_off 9753 9753 0 0.00 0 10251 13 0 0
t$perf-norm:sp_supplier_invoice 5380 5380 0 0.00 0 5662 17 0 0
t$perf-norm:sp_supplier_order 5510 5510 0 0.00 0 6224 20 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 441 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 297 6 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 3174 30 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 2949 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 6 0 0
v_add_invoice_to_stock 9912 9714 198 1.99 0 8585 23 0 198
v_all_customers 36368 36368 0 0.00 0 111 1 0 0
v_all_suppliers 30198 30198 0 0.00 0 111 1 0 0
v_all_wares 40118 40118 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 5514 16 0 0
v_cancel_client_order 3460 3460 0 0.00 0 6593 7 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2761 14 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2445 7 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 4145 18 0 0
v_cancel_supplier_invoice 862 844 18 2.08 0 4497 26 0 18
v_cancel_supplier_order 804 804 0 0.00 0 1842 8 0 0
v_cancel_write_off 1988 1988 0 0.00 0 5084 24 0 0
v_max_id_clo_ord 5510 5510 0 0.00 0 1128 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 9288 24 0 0
v_max_id_ord_sup 5376 5376 0 0.00 0 4255 7 0 0
v_max_non_paid_invoice 1527 1527 0 0.00 0 4048 7 0 0
v_max_non_paid_realizn 3702 3702 0 0.00 0 41058 16 0 0
v_min_id_clo_ord 5510 5510 0 0.00 0 4116 6 0 0
v_min_id_clo_res 981 981 0 0.00 0 2363 11 0 0
v_min_id_ord_sup 5376 5376 0 0.00 0 2704 3 0 0
v_min_non_paid_invoice 5740 5740 0 0.00 0 5545 15 0 0
v_min_non_paid_realizn 6937 6937 0 0.00 0 14854 21 0 0
v_our_firm 10220 10220 0 0.00 0 13 1 0 0
v_reserve_write_off 19506 19506 0 0.00 0 6502 10 0 0
x_lock_depdocs_on_canc_invoice 12022 12022 0 0.00 0 20188 124 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 9147 419 0 0
x_make_qstorno_1000_1200 5510 5510 0 0.00 2 42106 1163 0 0
x_make_qstorno_1200_2000 5380 5380 0 0.00 3 77866 3138 0 0
x_make_qstorno_2100_3300 450721 450721 0 0.00 0 48907 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-08-2021 19:39:13 to 27-08-2021 19:39:17.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:54 29213 8192000000 10388093584 10712403968 1436286544 14.917 100 86 172 1 1470539920 6323200 27930176
17:10:27 25420 8192000000 10471003184 10806431744 1515797728 15.614 100 88 177 2 1552466992 8667712 28001552
17:12:00 17955 8192000000 10521867568 10866479104 1574154160 16.118 100 89 176 3 1611615936 10013472 27448304
17:13:21 15587 8192000000 10578586608 10940375040 1633509168 16.625 100 79 158 1 1667834320 10718992 23606160
17:14:40 10140 8192000000 10644277520 11014311936 1702632864 17.207 100 79 158 1 1737668656 11944544 23091248
17:15:53 6543 8192000000 10711666656 11100798976 1782409536 17.869 100 69 138 0 1812700320 11122560 19168224
17:17:02 4252 8192000000 10772798160 11160461312 1825775952 18.225 100 82 164 1 1861570576 12776048 23018576
17:18:08 2681 8192000000 10803829328 11196719104 1866787728 18.558 100 88 175 2 1903905584 11932400 25185456
17:19:12 1311 8192000000 10820667856 11214086144 1878932960 18.656 100 81 164 0 1913929248 12744272 22252016
17:20:14 5416 8192000000 10784451968 11207950336 1875169520 18.626 100 24 50 0 1888116720 6286336 6660864
17:21:20 11481 8192000000 10780896320 11218800640 1883272256 18.692 100 20 40 1 1894771984 6301232 5198496
17:22:33 10804 8192000000 10792802240 11229384704 1892111616 18.763 100 22 44 0 1905261360 7300576 5849168
17:23:44 72874 8192000000 10799759936 11241607168 1895005232 18.786 100 27 54 0 1908458688 5357152 8096304
17:25:58 5490 8192000000 10818026624 11256532992 1908834464 18.897 100 35 70 2 1926215472 6657920 10723088
17:27:03 4651 8192000000 10815666576 11254599680 1892842208 18.769 99 28 56 0 1912241840 11586352 7813280
17:28:08 8830 8192000000 10776103504 11204460544 1857471392 18.483 99 15 30 2 1867285440 5739968 4074080
17:29:18 31923 8192000000 10760329680 11159678976 1762558896 17.706 94 47 93 2 1781480576 4527760 14393920
17:30:52 5440 8192000000 10699145728 11115724800 1766303216 17.736 98 34 69 0 1782518384 6297744 9917424
17:31:58 6264 8192000000 10702499168 11116838912 1759089776 17.677 98 26 52 0 1772176976 5609776 7477424
17:33:09 12686 8192000000 10745580448 11164954624 1820630064 18.183 100 20 41 2 1832047856 5323952 6093840
17:34:25 33841 8192000000 10755306240 11184615424 1841923616 18.356 100 21 42 1 1853584000 5936640 5723744
17:35:58 5943 8192000000 10763098720 11194966016 1856128768 18.472 100 29 58 2 1870114800 5250800 8735232
17:37:06 11420 8192000000 10797477744 11226165248 1882857552 18.688 100 19 38 1 1893111792 5328496 4925744
17:38:22 4243 8192000000 10844394864 11266355200 1916629568 18.960 100 45 88 2 1936231072 7387440 12214064
17:39:33 32173 8192000000 10857648256 11282817024 1932163776 19.084 100 40 75 2 1951069024 7075632 11829616
17:42:03 17360 8192000000 10904032320 11332898816 1965517136 19.350 100 56 112 0 1996114944 14218480 16379328
17:43:22 9380 8192000000 10921432336 11348082688 1978899520 19.456 100 63 126 0 2012029584 14198000 18932064
17:44:35 1078 8192000000 10943642752 11360645120 1988592480 19.533 100 88 175 0 2032606192 17950400 26063312
17:45:40 12698 8192000000 10919746224 11358519296 1977060752 19.441 100 56 111 1 2009443360 16153888 16228720
17:47:08 16693 8192000000 10924489616 11369938944 1988112352 19.529 100 42 79 2 2015799904 16063264 11624288
17:48:31 10586 8192000000 10939150864 11382657024 1993986064 19.575 100 47 94 1 2031319216 23039568 14293584
17:50:14 9416 8192000000 10940697408 11374665728 1984762560 19.502 100 60 120 2 2028323520 25702672 17858288
17:51:29 2726 8192000000 10960535552 11379884032 1992494880 19.564 100 79 158 1 2042294528 26072032 23727616
17:52:34 3420 8192000000 10926859104 11346784256 1957623472 19.287 99 71 142 1 2007976048 28010064 22342512
17:53:44 2325 8192000000 10933972224 11344330752 1957231328 19.284 100 88 174 2 2014627488 29898496 27497664
17:54:49 1618 8192000000 10936595184 11347898368 1951152304 19.236 100 78 157 0 2012541248 37275600 24113344
17:55:56 3847 8192000000 10939354544 11339653120 1938762080 19.137 99 84 167 4 2004650656 41031840 24856736
17:57:07 1473 8192000000 10951655936 11359989760 1954678432 19.264 100 87 174 1 2031581648 48961168 27942048
17:58:13 3852 8192000000 10946142016 11368169472 1958337040 19.293 100 60 121 0 2029639520 51257888 20044592
17:59:22 16032 8192000000 10911769248 11345174528 1936785504 19.121 100 42 82 4 1996337712 45195120 14357088
18:00:41 6012 8192000000 10923336336 11350503424 1949538048 19.223 100 47 95 0 2006825760 41515328 15772384
18:01:54 8724 8192000000 10944932048 11376648192 1961471616 19.318 100 47 95 1 2024784592 46869120 16443856
18:03:09 30901 8192000000 10934651904 11381121024 1952172432 19.244 100 34 68 1 2024290656 59261104 12857120
18:04:57 17688 8192000000 10900590864 11354759168 1938094944 19.132 100 17 34 0 1982084144 37772416 6216784
18:06:36 37832 8192000000 10891904704 11365130240 1910582400 18.911 98 16 32 0 1960034560 44060016 5392144
18:08:18 107196 8192000000 10907152256 11361095680 1963905376 19.337 100 18 36 0 1984353712 15579600 4868736
18:11:11 5448 8192000000 10890676736 11350429696 1946753296 19.201 99 20 40 1 1966580688 14163200 5664192
18:12:31 10877 8192000000 10897598672 11350290432 1940888176 19.154 98 47 93 1 1965815232 12365920 12561136
18:13:43 73340 8192000000 10900250384 11365945344 1971198976 19.395 100 26 52 1 1992898016 14010288 7688752
18:16:16 102771 8192000000 10909875152 11380408320 1962296560 19.324 99 15 29 2 1980866224 14123488 4446176
18:19:06 87657 8192000000 10924796112 11399610368 1996536976 19.595 100 20 39 2 2018259136 15697280 6024880
18:21:35 70924 8192000000 10931722400 11396935680 2002266928 19.641 100 15 30 2 2020624800 13845584 4512288
18:23:47 98046 8192000000 10917754208 11387273216 1947715328 19.208 97 31 62 1 1969175680 12468016 8992336
18:26:29 70424 8192000000 10931973856 11403706368 1998391968 19.610 100 16 32 0 2016779312 14150512 4236832
18:28:41 46889 8192000000 10941155168 11426639872 2014783024 19.739 100 31 60 2 2041087440 17674928 8629488
18:30:31 68815 8192000000 10927447264 11410784256 1970903424 19.393 98 15 30 0 1994425888 18473168 5049296
18:32:48 81297 8192000000 10927868224 11400855552 1984854192 19.503 99 15 30 0 2000523088 11543376 4125520
18:37:04 67933 8192000000 10925008064 11406745600 1996488928 19.595 100 13 26 1 2017727136 18289120 2949088
18:39:14 40447 8192000000 10900425872 11352129536 1926250496 19.037 97 21 42 0 1940096160 7684608 6161056
18:40:55 78439 8192000000 10884753472 11332526080 1816426496 18.148 92 23 46 0 1835857296 13110448 6320352
18:43:14 65412 8192000000 10867987024 11340918784 1897864192 18.809 97 23 45 2 1918383792 13766688 6752912
18:45:24 50737 8192000000 10883347872 11329695744 1879510944 18.661 96 29 58 0 1896091552 7622928 8957680
18:47:17 10764 8192000000 10894790176 11345297408 1947825344 19.209 100 53 106 1 1978808080 15755184 15227552
18:48:38 123805 8192000000 10852456208 11307741184 1795280352 17.975 93 17 34 0 1814041424 13746384 5014688
18:51:42 81814 8192000000 10842561376 11309617152 1888722576 18.735 98 19 38 1 1905066160 11376912 4966672
18:54:48 68852 8192000000 10857666896 11338268672 1921991120 19.003 99 10 20 0 1941197632 16497136 2709376
18:56:57 64187 8192000000 10855874736 11328856064 1922492000 19.007 99 21 42 0 1948823088 19909392 6421696
18:59:13 63337 8192000000 10861100336 11335815168 1905310672 18.869 98 19 38 1 1927595120 16295968 5988480
19:01:17 62806 8192000000 10851453504 11318157312 1892231408 18.764 98 15 30 0 1910085648 13013520 4840720
19:03:25 33582 8192000000 10857215504 11324141568 1930441232 19.070 100 18 36 2 1949245456 13561552 5242672
19:04:58 80486 8192000000 10857050576 11323502592 1902754576 18.848 98 15 29 0 1922635376 15250784 4630016
19:07:25 59667 8192000000 10890182400 11362832384 1967133200 19.363 100 40 79 3 1989276560 11483296 10660064
19:09:28 91661 8192000000 10903389952 11380178944 1942112784 19.164 98 15 30 0 1956246960 10388784 3745392
19:12:01 118546 8192000000 10931713632 11414114304 1968141472 19.371 99 13 26 0 1993169648 21698512 3329664
19:15:02 117139 8192000000 10935345632 11410182144 1957494608 19.286 98 16 32 0 1979376976 17514640 4367728
19:18:00 87011 8192000000 10933202720 11408449536 1981625552 19.478 99 22 44 1 2003665456 15669120 6370784
19:21:40 66060 8192000000 10911834784 11404238848 1992126240 19.561 100 8 16 0 2010835216 16211472 2497504
19:23:47 25115 8192000000 10894384432 11358670848 1944733328 19.185 98 22 44 0 1957111888 5961504 6417056
19:25:16 83177 8192000000 10890627792 11360632832 1921069664 18.995 97 17 35 1 1939254784 12793744 5391376
19:27:46 79713 8192000000 10885307888 11360882688 1945963984 19.194 99 16 32 2 1960977120 10287184 4725952
19:30:09 79594 8192000000 10886378992 11358121984 1888666864 18.735 96 10 20 0 1900079328 8630192 2782272
19:32:56 63691 8192000000 10879637664 11349368832 1900343968 18.829 97 29 58 1 1920562672 12188976 8029728
19:35:20 80411 8192000000 10868166304 11337510912 1837964528 18.324 94 15 30 1 1861695312 19453424 4277360
19:37:42 20957 8192000000 9419344688 9726062592 557127456 6.367 30 3 5 0 568171168 10243056 800656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 27-08-2021 19:39:19 to 27-08-2021 19:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-08-2021 19:39:34 to 27-08-2021 19:39:36.

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          237921
MON$OLDEST_ACTIVE               237922
MON$OLDEST_SNAPSHOT             237922
MON$NEXT_TRANSACTION            237922
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                       2027744
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:607: 
:608: mscs-test-161.budzdorov.ru    Fri Aug 27 19:38:26 2021
:609:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:610: 
--- end of diff output ---

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

27.08.21 19:39:37. 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-08-27 19:39:40 No severe PSQL-related problems occured
27.08.21 19:39:41. End of report.