Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2021 07:47
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 15707492 kB
MemAvailable 30816468 kB
Buffers 0 kB
Cached 15976612 kB
SwapCached 2472 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-03 07:46:51
DTS_END                         2021-11-03 07:46:51.2100
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/42562
TRN_ID                          237958
ATT_ID                          659
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: 8919.91 24822 1070389
customer order: creation 169.37 407 20324
customer order: refuse 15.09 403 1811
order to supplier: creation 46.28 1593 5554
order to supplier: removal 3.40 109760 408
invoice (draft): creation 46.45 3981 5574
invoice (draft): removal 49.83 966 5979
invoice accept: apply 42.76 19776 5131
invoice accept: cancel 44.55 995 5346
customer reserve: creation 3810.86 200 457303
customer reserve: removal 4395.85 66 527502
realization accept: apply 83.42 237 10010
realization accept: cancel 83.72 121 10046
payment from customer: creation 59.37 127 7124
payment from customer: removal 5.87 84 704
payment to supplier: creation 49.50 166 5940
payment to supplier: removal 3.48 83 418
service: total inventory turnovers 3.35 5009 402
service: total monetary turnovers 5.94 668 713
service: aggregate perf. data 0.80 20362 96
service: refresh index statistics 0.03 331434 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-11-2021 07:48:02 to 03-11-2021 07:48:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3585
TEST_TIME 2 4955
TEST_TIME 3 6257
TEST_TIME 4 6356
TEST_TIME 5 5696
TEST_TIME 6 5466
TEST_TIME 7 5380
TEST_TIME 8 5339
TEST_TIME 9 5224
TEST_TIME 10 5493
TEST_TIME 11 5567
TEST_TIME 12 6069
TEST_TIME 13 6217
TEST_TIME 14 6722
TEST_TIME 15 6620
TEST_TIME 16 6778
TEST_TIME 17 6905
TEST_TIME 18 6926
TEST_TIME 19 7160
TEST_TIME 20 7372
TEST_TIME 21 7831
TEST_TIME 22 7899
TEST_TIME 23 8249
TEST_TIME 24 8377
TEST_TIME 25 8550
TEST_TIME 26 8685
TEST_TIME 27 8757
TEST_TIME 28 9227
TEST_TIME 29 9196
TEST_TIME 30 9684
TEST_TIME 31 9883
TEST_TIME 32 9816
TEST_TIME 33 9743
TEST_TIME 34 9856
TEST_TIME 35 9836
TEST_TIME 36 9729
TEST_TIME 37 9691
TEST_TIME 38 9546
TEST_TIME 39 9576
TEST_TIME 40 9679
TEST_TIME 41 9627
TEST_TIME 42 9456
TEST_TIME 43 9363
TEST_TIME 44 9363
TEST_TIME 45 9342
TEST_TIME 46 9221
TEST_TIME 47 9294
TEST_TIME 48 9231
TEST_TIME 49 9161
TEST_TIME 50 9193
TEST_TIME 51 9327
TEST_TIME 52 9290
TEST_TIME 53 9187
TEST_TIME 54 9168
TEST_TIME 55 9234
TEST_TIME 56 9186
TEST_TIME 57 9073
TEST_TIME 58 9177
TEST_TIME 59 9320
TEST_TIME 60 9276
TEST_TIME 61 9259
TEST_TIME 62 9462
TEST_TIME 63 9557
TEST_TIME 64 9571
TEST_TIME 65 9624
TEST_TIME 66 9616
TEST_TIME 67 9521
TEST_TIME 68 9411
TEST_TIME 69 9378
TEST_TIME 70 9452
TEST_TIME 71 9357
TEST_TIME 72 9266
TEST_TIME 73 9258
TEST_TIME 74 9296
TEST_TIME 75 9295
TEST_TIME 76 9244
TEST_TIME 77 9279
TEST_TIME 78 9225
TEST_TIME 79 9160
TEST_TIME 80 9336
TEST_TIME 81 9301
TEST_TIME 82 9344
TEST_TIME 83 9311
TEST_TIME 84 9302
TEST_TIME 85 9402
TEST_TIME 86 9349
TEST_TIME 87 9259
TEST_TIME 88 9193
TEST_TIME 89 9198
TEST_TIME 90 9245
TEST_TIME 91 9192
TEST_TIME 92 9169
TEST_TIME 93 9143
TEST_TIME 94 9076
TEST_TIME 95 9065
TEST_TIME 96 9124
TEST_TIME 97 9109
TEST_TIME 98 9101
TEST_TIME 99 9138
TEST_TIME 100 9156
TEST_TIME 101 9154
TEST_TIME 102 9185
TEST_TIME 103 9133
TEST_TIME 104 9153
TEST_TIME 105 9178
TEST_TIME 106 9282
TEST_TIME 107 9245
TEST_TIME 108 9268
TEST_TIME 109 9213
TEST_TIME 110 9156
TEST_TIME 111 9142
TEST_TIME 112 9088
TEST_TIME 113 9090
TEST_TIME 114 9059
TEST_TIME 115 9019
TEST_TIME 116 8975
TEST_TIME 117 8942
TEST_TIME 118 8969
TEST_TIME 119 8955
TEST_TIME 120 8921

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2021 07:48:06 to 03-11-2021 07:48: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 1494445 1494445 0 0.00 0 31742 8 0 0
doc_list_biud 1494445 1494445 0 0.00 0 33852 30 0 0
sp_add_invoice_to_stock 5247 5131 116 2.21 16 161516 19776 0 116
sp_cancel_adding_invoice 5346 5346 0 0.00 4 79502 996 0 0
sp_cancel_client_order 1811 1811 0 0.00 4 14339 404 0 0
sp_cancel_customer_reserve 527502 527502 0 0.00 1 33855 66 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 3822 84 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 1962 83 0 0
sp_cancel_supplier_invoice 5989 5979 10 0.16 1 13764 967 0 10
sp_cancel_supplier_order 408 408 0 0.00 8 1060105 109760 0 0
sp_cancel_write_off 10046 10046 0 0.00 1 24947 121 0 0
sp_client_order 20324 20324 0 0.00 4 37094 408 0 0
sp_customer_reserve 457303 457303 0 0.00 0 96470 201 0 0
sp_fill_shopping_cart 31424 31424 0 0.00 1 12127 45 0 0
sp_fill_shopping_cart_clo_res 457301 457301 0 0.00 0 12698 15 0 0
sp_get_clo_for_invoice 5128 5128 0 0.00 0 29261 333 0 0
sp_kill_cost_storno 16514 16514 0 0.00 0 8035 63 0 0
sp_kill_qstorno_ret_qs2qd 1071437 1071437 0 0.00 0 33821 36 0 0
sp_kill_qty_storno 566229 566229 0 0.00 0 33851 79 0 0
sp_lock_selected_doc 20957 20957 0 0.00 0 13042 9 0 0
sp_make_cost_storno 28201 28201 0 0.00 0 16607 77 0 0
sp_multiply_rows_for_pdistr 28201 28201 0 0.00 0 23488 40 0 0
sp_multiply_rows_for_qdistr 20324 20324 0 0.00 0 13802 177 0 0
sp_pay_from_customer 7124 7124 0 0.00 4 16233 128 0 0
sp_pay_to_supplier 5940 5940 0 0.00 4 31745 167 0 0
sp_payment_common 13064 13064 0 0.00 1 11457 25 0 0
sp_qd_handle_on_cancel_clo 1811 1811 0 0.00 0 14327 373 0 0
sp_qd_handle_on_invoice_upd_sts 10473 10473 0 0.00 0 27775 423 0 0
sp_qd_handle_on_reserve_upd_sts 10010 10010 0 0.00 0 28631 79 0 0
sp_reserve_write_off 10010 10010 0 0.00 7 28662 237 0 0
sp_supplier_invoice 5574 5574 0 0.00 7 86927 3981 0 0
sp_supplier_order 5554 5554 0 0.00 6 29924 1594 0 0
srv_aggregate_perf_data 96 96 0 0.00 3024 294873 20362 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1113 115500 41772 0 0
srv_find_qd_qs_mism 1023258 1023258 0 0.00 0 25795 6 0 0
srv_make_invnt_saldo 402 402 0 0.00 78 123625 5009 0 0
srv_make_money_saldo 713 713 0 0.00 1 17916 668 0 0
srv_recalc_idx_stat 4 4 0 0.00 82211 529460 331435 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5131 5131 0 0.00 0 14914 178 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 6322 125 0 0
t$perf-norm:sp_cancel_client_order 1811 1811 0 0.00 0 3470 14 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 3411 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 4289 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 388 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 491 14 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 25486 1539 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 3712 13 0 0
t$perf-norm:sp_client_order 20324 20324 0 0.00 0 11361 26 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 535 4 0 0
t$perf-norm:sp_pay_from_customer 7124 7124 0 0.00 0 8147 14 0 0
t$perf-norm:sp_pay_to_supplier 5940 5940 0 0.00 0 9569 17 0 0
t$perf-norm:sp_reserve_write_off 10010 10010 0 0.00 0 14353 20 0 0
t$perf-norm:sp_supplier_invoice 5574 5574 0 0.00 0 8307 22 0 0
t$perf-norm:sp_supplier_order 5554 5554 0 0.00 0 6270 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 137 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3636 71 0 0
t$perf-norm:srv_make_invnt_saldo 402 402 0 0.00 0 4743 28 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 1510 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10482 10250 232 2.21 0 10041 20 0 232
v_all_customers 36582 36582 0 0.00 0 21 1 0 0
v_all_suppliers 30236 30236 0 0.00 0 21 1 0 0
v_all_wares 40638 40638 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 8598 23 0 0
v_cancel_client_order 3622 3622 0 0.00 0 3072 7 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 1596 6 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 306 3 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1947 9 0 0
v_cancel_supplier_invoice 828 808 20 2.41 0 2506 22 0 20
v_cancel_supplier_order 816 816 0 0.00 0 1025 7 0 0
v_cancel_write_off 2044 2044 0 0.00 0 2797 16 0 0
v_max_id_clo_ord 5546 5546 0 0.00 0 11039 4 0 0
v_max_id_clo_res 968 968 0 0.00 0 2524 13 0 0
v_max_id_ord_sup 5558 5558 0 0.00 0 8290 5 0 0
v_max_non_paid_invoice 1926 1926 0 0.00 0 197 2 0 0
v_max_non_paid_realizn 3949 3949 0 0.00 0 1834 3 0 0
v_min_id_clo_ord 5546 5546 0 0.00 0 1171 5 0 0
v_min_id_clo_res 968 968 0 0.00 0 8272 18 0 0
v_min_id_ord_sup 5558 5558 0 0.00 0 6945 12 0 0
v_min_non_paid_invoice 5940 5940 0 0.00 0 11450 22 0 0
v_min_non_paid_realizn 7124 7124 0 0.00 0 7028 14 0 0
v_our_firm 10406 10406 0 0.00 0 12 1 0 0
v_reserve_write_off 20020 20020 0 0.00 0 3798 6 0 0
x_lock_depdocs_on_canc_invoice 10921 10921 0 0.00 0 15143 130 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 4856 288 0 0
x_make_qstorno_1000_1200 5554 5554 0 0.00 2 29905 1496 0 0
x_make_qstorno_1200_2000 5574 5574 0 0.00 3 86891 3828 0 0
x_make_qstorno_2100_3300 457303 457303 0 0.00 0 96302 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2021 07:48:10 to 03-11-2021 07:48: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
05:16:55 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:55 34619 8192000000 9718405952 10014347264 792505888 8.820 100 92 184 0 824077312 7955824 23615600
05:19:35 18573 8192000000 9757549312 10064707584 835032256 9.250 100 84 168 0 868421696 13103904 20285536
05:21:00 22911 8192000000 9813935712 10132312064 866771456 9.568 100 81 162 0 913637264 27814704 19051104
05:22:31 17274 8192000000 9884980592 10221338624 934869136 10.243 100 79 156 1 994478320 42702464 16906720
05:23:51 10208 8192000000 9947147696 10294501376 974918864 10.635 100 89 178 0 1065176352 71638048 18619440
05:25:06 2677 8192000000 9985907984 10346790912 1010663712 10.982 100 85 170 1 1101413056 73505552 17243792
05:26:11 2888 8192000000 10012842608 10379358208 1036070496 11.227 100 88 174 1 1128505040 75299408 17135136
05:27:16 1637 8192000000 10030561008 10398212096 1055770432 11.416 100 93 187 0 1139709456 64593440 19345584
05:28:18 31544 8192000000 10011151936 10399285248 1069145376 11.544 100 72 142 0 1134105760 49226672 15733712
05:29:49 6447 8192000000 9988874096 10392240128 1077918096 11.628 100 26 51 0 1110071984 26217808 5936080
05:30:56 7970 8192000000 10018544448 10431488000 1087206512 11.716 100 33 66 1 1138345104 43426816 7711776
05:32:06 21053 8192000000 9996067040 10397425664 1091901456 11.761 100 32 65 0 1118056368 18446272 7708640
05:33:29 14464 8192000000 10031019072 10417242112 1095399120 11.794 100 91 184 3 1137560544 22837712 19323712
05:34:44 5688 8192000000 10035438224 10436915200 1096387664 11.803 100 42 84 1 1149108752 43148480 9572608
05:35:51 5678 8192000000 10012215344 10420617216 1095916016 11.799 100 34 66 2 1120845216 17749472 7179728
05:36:59 12207 8192000000 9992400080 10403094528 1079765440 11.645 100 33 66 1 1113022400 26108576 7148384
05:38:12 4955 8192000000 9971070032 10350710784 1044465968 11.308 99 54 105 1 1070180592 13441392 12273232
05:39:17 5832 8192000000 9947739072 10326339584 984653024 10.729 94 32 63 2 1010326064 18651216 7021824
05:40:23 52154 8192000000 9929345808 10310062080 995660368 10.836 97 32 63 1 1034159808 31765440 6734000
05:42:17 3756 8192000000 9955230640 10343161856 1021947632 11.091 98 53 104 0 1065243344 32438288 10857424
05:43:23 6136 8192000000 9967132048 10356736000 1046034544 11.323 100 40 80 0 1065416864 10171296 9211024
05:44:30 4697 8192000000 9973985840 10364006400 1063258432 11.488 100 42 82 4 1084734576 11865408 9610736
05:45:39 54394 8192000000 9990081664 10386415616 1075885552 11.608 100 30 62 0 1100847760 18483232 6478976
05:47:38 27888 8192000000 10040766128 10438234112 1095420176 11.794 100 63 125 0 1144146224 36242336 12483712
05:49:10 9431 8192000000 10094681440 10492321792 1111711904 11.949 100 63 126 2 1194696192 70119760 12864528
05:50:22 9426 8192000000 10068965520 10479702016 1121277504 12.039 100 39 78 0 1185352928 55823184 8252240
05:51:46 53585 8192000000 10114352656 10528079872 1143610256 12.249 100 48 93 1 1226032576 71960736 10461584
05:53:52 18636 8192000000 10134167744 10563936256 1145288816 12.265 100 37 74 0 1253617808 100624368 7704624
05:55:15 9855 8192000000 10161723760 10572365824 1144105904 12.254 100 54 108 0 1262947216 107344928 11496384
05:56:36 10954 8192000000 10206851632 10617081856 1160302816 12.406 100 60 118 1 1304276528 131305104 12668608
05:57:51 7116 8192000000 10257270416 10664271872 1153549920 12.343 100 68 136 1 1358655168 190509824 14595424
05:59:07 3630 8192000000 10263990528 10667438080 1162291600 12.425 100 77 152 2 1371531328 192736240 16503488
06:00:16 11837 8192000000 10246321488 10675433472 1152326080 12.331 100 54 108 2 1346963488 182376048 12261360
06:01:35 3164 8192000000 10265258944 10668896256 1167143696 12.470 100 67 132 0 1364378832 182180400 15054736
06:02:49 2065 8192000000 10239254128 10654924800 1148470064 12.295 100 67 132 2 1341681248 178399760 14811424
06:03:54 2590 8192000000 10305226816 10700177408 1166961920 12.468 100 94 185 1 1402455360 215490448 20002992
06:05:00 6917 8192000000 10292643472 10686939136 1130884336 12.130 99 76 151 0 1376016800 227135600 17996864
06:06:18 4664 8192000000 10300270576 10700697600 1156807088 12.373 100 83 166 1 1402509936 226387728 19315120
06:07:30 1110 8192000000 10347451568 10759938048 1151844272 12.327 99 85 170 1 1434528544 263237536 19446736
06:08:39 5066 8192000000 10319988144 10732859392 1147298368 12.284 100 57 112 2 1418758496 257749680 13710448
06:09:52 9087 8192000000 10276902912 10744217600 1143418496 12.248 99 36 70 1 1382068752 229344256 9306000
06:11:13 20324 8192000000 10289371920 10731044864 1144128992 12.254 99 26 52 1 1386501664 235380336 6992336
06:12:36 18470 8192000000 10293156736 10726387712 1130832176 12.129 99 23 46 1 1396811392 259841872 6137344
06:14:08 17527 8192000000 10289411888 10719477760 1132771360 12.147 100 36 72 0 1396900752 255882896 8246496
06:15:27 5858 8192000000 10273312080 10698731520 1139697952 12.213 100 53 105 0 1366806128 214493504 12614672
06:17:13 64172 8192000000 10041921056 10508800000 1084272784 11.688 96 8 16 0 1143397728 57753008 1371936
06:19:20 10615 8192000000 10076982048 10490298368 1114580816 11.976 99 46 91 0 1160300000 36389760 9329424
06:20:33 115500 8192000000 10058128048 10501554176 1056805184 11.426 93 15 30 0 1109494304 49489888 3199232
06:23:43 43044 8192000000 10062300800 10487177216 1087329616 11.717 96 24 48 0 1121624880 29496928 4798336
06:25:26 41404 8192000000 10106033680 10526945280 1129588592 12.117 100 17 34 1 1203196432 70030496 3577344
06:27:13 31430 8192000000 10068028368 10506223616 1138011760 12.197 100 16 30 1 1166074912 24795440 3267712
06:28:49 78874 8192000000 10105221072 10540617728 1142772944 12.242 100 45 89 1 1204896560 52401824 9721792
06:31:14 63110 8192000000 10110554384 10556235776 1145163040 12.264 100 24 48 0 1217760608 67506848 5090720
06:33:27 69121 8192000000 10104429888 10544574464 1106320160 11.898 96 29 60 0 1182987472 70772224 5895088
06:35:36 36971 8192000000 10161702464 10600247296 1124161200 12.066 98 35 70 0 1243874128 112015712 7697216
06:37:16 28676 8192000000 10105483616 10574049280 1132161200 12.142 100 17 34 0 1205924832 70278432 3485200
06:38:49 35367 8192000000 10093492096 10536288256 1116261728 11.992 98 16 32 1 1179825408 59289072 4274608
06:40:25 59356 8192000000 10087967952 10560413696 1102516368 11.862 97 13 26 0 1183475888 78488336 2471184
06:42:26 32186 8192000000 10096289760 10537709568 1117817008 12.006 99 23 46 0 1190476640 67928720 4730912
06:44:05 42841 8192000000 10117449072 10562273280 1100315120 11.841 98 15 30 0 1220069072 116246928 3507024
06:45:49 24360 8192000000 10091588144 10526785536 1113986816 11.970 99 26 52 1 1166726848 47364816 5375216
06:47:14 46002 8192000000 10169104544 10592428032 1104741136 11.883 97 34 66 1 1227910480 116372784 6796560
06:49:09 69422 8192000000 10082212592 10535391232 1105921008 11.894 98 10 20 0 1188326576 80512896 1892672
06:51:18 62891 8192000000 10093695632 10561765376 1122624016 12.052 99 11 22 0 1161534192 36764080 2146096
06:53:24 103130 8192000000 10145675184 10587721728 1146873040 12.280 100 31 61 2 1240426704 86114336 7439328
06:56:30 35217 8192000000 10069192944 10576842752 1108725088 11.920 97 7 14 0 1174949456 64941568 1282800
06:58:57 95194 8192000000 10116695040 10600366080 1102476320 11.861 97 22 44 0 1189355264 81899456 4979488
07:01:37 51126 8192000000 10077605360 10540916736 1109066240 11.924 98 27 54 0 1147900112 33217984 5615888
07:03:34 47038 8192000000 10085770464 10534080512 1113827728 11.969 98 21 41 1 1166105376 48048176 4229472
07:05:22 44351 8192000000 10179851904 10618494976 1127897920 12.102 99 33 66 0 1250454048 115693696 6862432
07:07:10 73308 8192000000 10124521024 10561179648 1117871584 12.007 98 33 66 0 1161587776 36985696 6730496
07:09:33 50275 8192000000 10067787872 10514395136 1081737056 11.664 96 26 52 0 1131242464 44181120 5324288
07:11:23 69772 8192000000 10047351360 10482688000 1069240720 11.545 96 17 34 1 1119410416 46514480 3655216
07:13:40 112217 8192000000 10119209808 10586599424 1102095968 11.858 98 32 64 0 1159329472 50399456 6834048
07:18:22 41125 8192000000 10078593856 10537656320 1130167968 12.123 100 10 18 1 1183303904 51290176 1845760
07:20:04 45148 8192000000 10093102432 10570170368 1129151040 12.113 99 11 22 0 1192943552 61547872 2244640
07:21:51 46302 8192000000 10099545104 10611871744 1137591648 12.193 100 18 36 0 1191707488 50202192 3913648
07:23:42 63485 8192000000 10136653104 10581917696 1144137488 12.254 99 18 36 0 1208825600 60826928 3861184
07:26:15 82113 8192000000 10143418112 10619719680 1144849088 12.261 99 19 38 0 1226078128 77169552 4059488
07:28:40 55939 8192000000 10119081792 10551578624 1113627264 11.967 97 38 77 0 1162218736 39475360 9116112
07:30:39 36378 8192000000 10125975808 10556186624 1136468416 12.182 99 38 76 1 1207936528 62931136 8536976
07:32:18 87376 8192000000 10088900704 10536284160 1106866704 11.903 97 10 20 1 1157944912 48961312 2116896
07:35:27 113593 8192000000 10090734928 10536099840 1121901920 12.045 99 33 66 0 1162429296 34020768 6506608
07:38:29 10166 8192000000 10064002496 10504417280 1124449952 12.069 99 37 73 1 1153719760 21655520 7614288
07:39:40 51873 8192000000 10079132288 10524434432 1116107968 11.990 98 24 48 0 1143559568 22825456 4626144
07:41:41 83043 8192000000 10060510928 10499637248 1094074704 11.781 96 20 40 0 1145844688 46841824 4928160
07:44:04 61904 8192000000 10089090592 10603347968 1126931376 12.092 100 11 21 0 1216795904 87206944 2657584
07:46:15 40474 8192000000 9467769296 9813790720 582864256 6.642 52 21 40 1 614091312 26988416 4238640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-11-2021 07:48:16 to 03-11-2021 07:48:31.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 03-11-2021 07:48:32 to 03-11-2021 07:48:33.

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          236790
MON$OLDEST_ACTIVE               238461
MON$OLDEST_SNAPSHOT             238461
MON$NEXT_TRANSACTION            238461
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                       2076640
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2650 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2650 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2650 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2650 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Nov  3 07:47:04 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

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