Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16907676 kB
MemAvailable 31443940 kB
Buffers 0 kB
Cached 14741708 kB
SwapCached 0 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.9
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-12-09 19:38:02
DTS_END                         2021-12-09 19:38:02.2880
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/57204
TRN_ID                          232466
ATT_ID                          632
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: 9154.18 23199 1098502
customer order: creation 162.45 408 19494
customer order: refuse 13.51 331 1621
order to supplier: creation 42.30 1263 5076
order to supplier: removal 3.47 98164 416
invoice (draft): creation 43.93 3436 5272
invoice (draft): removal 54.56 736 6547
invoice accept: apply 40.12 17791 4814
invoice accept: cancel 48.85 725 5862
customer reserve: creation 3676.75 178 441210
customer reserve: removal 4779.83 56 573580
realization accept: apply 79.55 188 9546
realization accept: cancel 86.42 122 10370
payment from customer: creation 56.08 121 6729
payment from customer: removal 5.41 99 649
payment to supplier: creation 46.45 201 5574
payment to supplier: removal 3.23 169 388
service: total inventory turnovers 4.36 1115 523
service: total monetary turnovers 6.00 28 720
service: aggregate perf. data 0.89 18318 107
service: refresh index statistics 0.03 320523 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2021 19:38:49 to 09-12-2021 19:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4530
TEST_TIME 2 3291
TEST_TIME 3 2824
TEST_TIME 4 3484
TEST_TIME 5 4574
TEST_TIME 6 5876
TEST_TIME 7 5829
TEST_TIME 8 5674
TEST_TIME 9 6327
TEST_TIME 10 6245
TEST_TIME 11 6352
TEST_TIME 12 6295
TEST_TIME 13 6242
TEST_TIME 14 6447
TEST_TIME 15 6570
TEST_TIME 16 7015
TEST_TIME 17 7465
TEST_TIME 18 7792
TEST_TIME 19 7886
TEST_TIME 20 8253
TEST_TIME 21 8503
TEST_TIME 22 8476
TEST_TIME 23 9333
TEST_TIME 24 9664
TEST_TIME 25 10394
TEST_TIME 26 10678
TEST_TIME 27 10527
TEST_TIME 28 10170
TEST_TIME 29 10017
TEST_TIME 30 9798
TEST_TIME 31 9873
TEST_TIME 32 9783
TEST_TIME 33 9674
TEST_TIME 34 9568
TEST_TIME 35 9506
TEST_TIME 36 9435
TEST_TIME 37 9206
TEST_TIME 38 9146
TEST_TIME 39 9143
TEST_TIME 40 9069
TEST_TIME 41 8962
TEST_TIME 42 8931
TEST_TIME 43 8994
TEST_TIME 44 9062
TEST_TIME 45 9166
TEST_TIME 46 9185
TEST_TIME 47 9224
TEST_TIME 48 9298
TEST_TIME 49 9411
TEST_TIME 50 9487
TEST_TIME 51 9455
TEST_TIME 52 9497
TEST_TIME 53 9599
TEST_TIME 54 9528
TEST_TIME 55 9401
TEST_TIME 56 9297
TEST_TIME 57 9224
TEST_TIME 58 9289
TEST_TIME 59 9199
TEST_TIME 60 9164
TEST_TIME 61 9127
TEST_TIME 62 9238
TEST_TIME 63 9206
TEST_TIME 64 9171
TEST_TIME 65 9257
TEST_TIME 66 9295
TEST_TIME 67 9236
TEST_TIME 68 9293
TEST_TIME 69 9354
TEST_TIME 70 9339
TEST_TIME 71 9307
TEST_TIME 72 9391
TEST_TIME 73 9398
TEST_TIME 74 9447
TEST_TIME 75 9495
TEST_TIME 76 9477
TEST_TIME 77 9367
TEST_TIME 78 9307
TEST_TIME 79 9269
TEST_TIME 80 9257
TEST_TIME 81 9215
TEST_TIME 82 9158
TEST_TIME 83 9194
TEST_TIME 84 9151
TEST_TIME 85 9075
TEST_TIME 86 9038
TEST_TIME 87 9117
TEST_TIME 88 9113
TEST_TIME 89 9167
TEST_TIME 90 9158
TEST_TIME 91 9194
TEST_TIME 92 9237
TEST_TIME 93 9200
TEST_TIME 94 9165
TEST_TIME 95 9329
TEST_TIME 96 9317
TEST_TIME 97 9372
TEST_TIME 98 9335
TEST_TIME 99 9362
TEST_TIME 100 9321
TEST_TIME 101 9263
TEST_TIME 102 9224
TEST_TIME 103 9223
TEST_TIME 104 9213
TEST_TIME 105 9184
TEST_TIME 106 9155
TEST_TIME 107 9131
TEST_TIME 108 9082
TEST_TIME 109 9087
TEST_TIME 110 9080
TEST_TIME 111 9082
TEST_TIME 112 9049
TEST_TIME 113 9023
TEST_TIME 114 9099
TEST_TIME 115 9201
TEST_TIME 116 9176
TEST_TIME 117 9177
TEST_TIME 118 9189
TEST_TIME 119 9231
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2021 19:38:52 to 09-12-2021 19:38:55.

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 1508116 1508116 0 0.00 0 45753 6 0 0
doc_list_biud 1508116 1508116 0 0.00 0 70508 27 0 0
sp_add_invoice_to_stock 4907 4814 93 1.89 14 196067 17792 0 93
sp_cancel_adding_invoice 5862 5862 0 0.00 5 52028 725 0 0
sp_cancel_client_order 1621 1621 0 0.00 4 13314 331 0 0
sp_cancel_customer_reserve 573580 573580 0 0.00 1 24612 56 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 7053 100 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 19586 169 0 0
sp_cancel_supplier_invoice 6550 6547 3 0.04 2 70574 737 0 3
sp_cancel_supplier_order 416 416 0 0.00 8 977519 98165 0 0
sp_cancel_write_off 10370 10370 0 0.00 1 24541 122 0 0
sp_client_order 19494 19494 0 0.00 3 40210 409 0 0
sp_customer_reserve 441210 441210 0 0.00 0 48410 178 0 0
sp_fill_shopping_cart 29837 29837 0 0.00 1 17076 39 0 0
sp_fill_shopping_cart_clo_res 441207 441207 0 0.00 0 27437 14 0 0
sp_get_clo_for_invoice 4811 4811 0 0.00 0 24516 289 0 0
sp_kill_cost_storno 17269 17269 0 0.00 0 19578 56 0 0
sp_kill_qstorno_ret_qs2qd 1164493 1164493 0 0.00 0 70506 31 0 0
sp_kill_qty_storno 612753 612753 0 0.00 0 70508 66 0 0
sp_lock_selected_doc 19917 19917 0 0.00 0 11083 6 0 0
sp_make_cost_storno 26660 26660 0 0.00 0 37958 69 0 0
sp_multiply_rows_for_pdistr 26660 26660 0 0.00 0 29258 36 0 0
sp_multiply_rows_for_qdistr 19494 19494 0 0.00 0 13536 181 0 0
sp_pay_from_customer 6729 6729 0 0.00 4 23086 122 0 0
sp_pay_to_supplier 5574 5574 0 0.00 5 45139 201 0 0
sp_payment_common 12303 12303 0 0.00 1 29529 48 0 0
sp_qd_handle_on_cancel_clo 1621 1621 0 0.00 0 13304 304 0 0
sp_qd_handle_on_invoice_upd_sts 10673 10673 0 0.00 0 34520 308 0 0
sp_qd_handle_on_reserve_upd_sts 9546 9546 0 0.00 0 22345 55 0 0
sp_reserve_write_off 9546 9546 0 0.00 6 31546 188 0 0
sp_supplier_invoice 5272 5272 0 0.00 6 125478 3436 0 0
sp_supplier_order 5076 5076 0 0.00 5 40427 1263 0 0
srv_aggregate_perf_data 107 107 0 0.00 2512 226316 18319 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2569 140343 50619 0 0
srv_find_qd_qs_mism 1053767 1053767 0 0.00 0 11886 5 0 0
srv_make_invnt_saldo 523 523 0 0.00 35 47150 1116 0 0
srv_make_money_saldo 720 720 0 0.00 0 4447 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 274531 367339 320524 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4814 4814 0 0.00 0 13217 140 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 2764 82 0 0
t$perf-norm:sp_cancel_client_order 1621 1621 0 0.00 0 2776 12 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 1075 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 852 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1195 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 2032 21 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 12829 1286 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 6354 9 0 0
t$perf-norm:sp_client_order 19494 19494 0 0.00 0 8952 15 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 4899 11 0 0
t$perf-norm:sp_pay_from_customer 6729 6729 0 0.00 0 5925 10 0 0
t$perf-norm:sp_pay_to_supplier 5574 5574 0 0.00 0 4583 7 0 0
t$perf-norm:sp_reserve_write_off 9546 9546 0 0.00 0 10274 10 0 0
t$perf-norm:sp_supplier_invoice 5272 5272 0 0.00 0 5394 18 0 0
t$perf-norm:sp_supplier_order 5076 5076 0 0.00 0 2996 11 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 78 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 129 3 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 691 16 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 2011 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 97 28 0 0
v_add_invoice_to_stock 9808 9622 186 1.89 0 5017 18 0 186
v_all_customers 35590 35590 0 0.00 0 15 0 0 0
v_all_suppliers 28702 28702 0 0.00 0 32 1 0 0
v_all_wares 38988 38988 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2826 9 0 0
v_cancel_client_order 3242 3242 0 0.00 0 1227 2 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2821 9 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 1121 3 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 5237 18 0 0
v_cancel_supplier_invoice 834 828 6 0.71 0 993 15 0 6
v_cancel_supplier_order 832 832 0 0.00 0 1049 5 0 0
v_cancel_write_off 1962 1962 0 0.00 0 6225 16 0 0
v_max_id_clo_ord 5076 5076 0 0.00 0 180 1 0 0
v_max_id_clo_res 929 929 0 0.00 0 2741 11 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 8010 5 0 0
v_max_non_paid_invoice 1566 1566 0 0.00 0 2106 4 0 0
v_max_non_paid_realizn 3532 3532 0 0.00 0 6407 7 0 0
v_min_id_clo_ord 5076 5076 0 0.00 0 10066 11 0 0
v_min_id_clo_res 929 929 0 0.00 0 2502 5 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 12008 12 0 0
v_min_non_paid_invoice 5574 5574 0 0.00 0 29520 55 0 0
v_min_non_paid_realizn 6729 6729 0 0.00 0 22886 26 0 0
v_our_firm 9792 9792 0 0.00 0 15 1 0 0
v_reserve_write_off 19092 19092 0 0.00 0 5223 5 0 0
x_lock_depdocs_on_canc_invoice 11995 11995 0 0.00 0 15276 121 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 12547 414 0 0
x_make_qstorno_1000_1200 5076 5076 0 0.00 1 40418 1192 0 0
x_make_qstorno_1200_2000 5272 5272 0 0.00 3 124418 3290 0 0
x_make_qstorno_2100_3300 441210 441210 0 0.00 0 47038 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2021 19:38:57 to 09-12-2021 19:39:01.

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:08:06 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:07 19374 8192000000 9887911200 10200539136 978094752 10.666 100 79 159 0 1004922384 5838736 20988896
17:10:29 21650 8192000000 9945805872 10268151808 1026195072 11.132 100 91 183 0 1057834704 7778352 23861280
17:11:59 20602 8192000000 9985104400 10319532032 1064479168 11.499 100 87 174 0 1095675440 8910864 22285408
17:13:22 13335 8192000000 10029437440 10377076736 1114143824 11.972 100 75 150 0 1142033120 9498128 18391168
17:14:38 13040 8192000000 10081767984 10442002432 1171582128 12.512 100 78 156 1 1200821952 11134000 18105824
17:15:56 7031 8192000000 10114256832 10486530048 1207646032 12.847 100 72 141 1 1234964592 11676496 15642064
17:17:05 5045 8192000000 10161117120 10536177664 1247313664 13.214 100 83 167 1 1279051696 13335488 18402544
17:18:12 1792 8192000000 10201980768 10582454272 1279684208 13.510 100 90 181 0 1314499664 14486768 20328688
17:19:15 1720 8192000000 10209500560 10598780928 1298934320 13.686 100 84 168 1 1330967616 12984352 19048944
17:20:18 8098 8192000000 10196431216 10600947712 1301141744 13.706 100 58 117 1 1324112160 10178112 12792304
17:21:29 6534 8192000000 10193778208 10609262592 1308326832 13.771 100 23 46 1 1320663488 7320640 5016016
17:22:36 13897 8192000000 10195126416 10617438208 1316876912 13.848 100 16 32 1 1330327888 10123936 3327040
17:23:50 8284 8192000000 10209223744 10633633792 1332713408 13.992 100 17 34 1 1341726736 5179104 3834224
17:25:04 18134 8192000000 10252830480 10650755072 1335800736 14.020 100 78 156 4 1360080656 6338496 17941424
17:26:22 6371 8192000000 10231323392 10649219072 1335410160 14.016 100 27 53 0 1347731024 6546480 5774384
17:27:29 13161 8192000000 10218217264 10634702848 1315892976 13.840 99 21 42 0 1326496528 5985920 4617632
17:28:43 14264 8192000000 10170068720 10576703488 1193103680 12.712 91 33 66 1 1207311056 4632640 9574736
17:29:58 10899 8192000000 10156955888 10563125248 1223181104 12.991 95 25 50 0 1234405232 6018048 5206080
17:31:13 4032 8192000000 10132973280 10531033088 1226484480 13.022 98 25 50 1 1238274048 6099840 5689728
17:32:17 5363 8192000000 10140613360 10541723648 1243219328 13.176 100 22 42 3 1257858672 9339744 5299600
17:33:25 38209 8192000000 10163673456 10573643776 1271973024 13.440 100 29 56 2 1284213664 5279712 6960928
17:35:13 5849 8192000000 10187942016 10594070528 1286436304 13.572 100 38 76 1 1302474320 7917264 8120752
17:36:20 6152 8192000000 10182267184 10595119104 1292576528 13.628 100 28 55 2 1304946352 6076368 6293456
17:37:27 16343 8192000000 10195090304 10611253248 1302614304 13.719 100 11 22 0 1310367328 5321424 2431600
17:38:53 50071 8192000000 10245171120 10653204480 1339144448 14.050 100 45 91 1 1356601536 6392080 11065008
17:41:00 101534 8192000000 10255337728 10680647680 1359030608 14.229 100 45 90 0 1381820352 12049792 10739952
17:44:02 4094 8192000000 10296322272 10712580096 1376963536 14.389 100 56 113 2 1406308256 16176896 13167824
17:45:22 10576 8192000000 10302094000 10715836416 1377589440 14.395 100 55 108 2 1406310224 16253744 12467040
17:46:39 41901 8192000000 10329641968 10752987136 1391624992 14.520 100 59 118 1 1429328656 24018416 13685248
17:48:33 3496 8192000000 10349408656 10766782464 1416116768 14.738 100 70 140 1 1460550064 26480688 17952608
17:49:44 6697 8192000000 10354906480 10783444992 1418424352 14.759 100 54 108 0 1462613184 31301632 12887200
17:51:01 3047 8192000000 10378950912 10799144960 1428900320 14.852 99 73 144 1 1487376848 40952576 17523952
17:52:11 5233 8192000000 10378955440 10799329280 1426116720 14.827 100 70 139 2 1487187104 43463728 17606656
17:53:26 2569 8192000000 10401853296 10816925696 1432937200 14.887 100 79 157 1 1501292112 47539072 20815840
17:54:33 4887 8192000000 10402756000 10821844992 1436103712 14.915 100 82 164 1 1509401728 53003488 20294528
17:55:40 11505 8192000000 10370722208 10804219904 1423390576 14.803 100 43 86 4 1485283440 50302608 11590256
17:57:13 4562 8192000000 10381865584 10802139136 1399739328 14.593 98 73 146 0 1468598240 50236080 18622832
17:58:20 15700 8192000000 10358361712 10790821888 1385455648 14.465 98 37 74 2 1451971824 55976048 10540128
17:59:37 18133 8192000000 10340393296 10774638592 1373845264 14.361 99 32 64 0 1436220176 53534528 8840384
18:00:59 27698 8192000000 10339974128 10776375296 1363555888 14.269 99 33 66 4 1434566576 61391136 9619552
18:02:42 53987 8192000000 10260801296 10701836288 1277135152 13.487 95 17 34 1 1323348592 41282752 4930688
18:04:41 21809 8192000000 10261472624 10684903424 1332732512 13.992 100 34 66 0 1349071184 8256288 8082384
18:06:10 66378 8192000000 10232256240 10671484928 1317641920 13.855 99 23 46 1 1330277328 7525216 5110192
18:08:27 28215 8192000000 10246540112 10675552256 1320537744 13.882 98 50 100 0 1341870592 9842320 11490528
18:10:02 6642 8192000000 10230271088 10690514944 1336279328 14.024 100 5 10 0 1346632432 9279728 1073376
18:11:10 84339 8192000000 10250851200 10685972480 1313227168 13.815 97 32 64 4 1327925520 7597152 7101200
18:14:27 25160 8192000000 10265629536 10714071040 1364814368 14.281 100 18 36 0 1375774768 7306256 3654144
18:15:55 82113 8192000000 10266169824 10719948800 1354833568 14.191 99 26 52 1 1370675520 10133248 5708704
18:18:19 36138 8192000000 10259087392 10712223744 1335585120 14.018 98 7 15 1 1343352400 6385488 1381792
18:20:00 44074 8192000000 10269867616 10711875584 1353451664 14.179 99 31 60 2 1373662992 12754864 7456464
18:21:53 72133 8192000000 10285365264 10750533632 1380569792 14.422 100 14 28 0 1397209072 13646864 2992416
18:24:06 67013 8192000000 10301738576 10757029888 1368347744 14.312 98 22 44 0 1393148656 19658288 5142624
18:26:42 56748 8192000000 10307730176 10758774784 1373796624 14.361 98 23 45 1 1399450736 20323008 5331104
18:28:41 60979 8192000000 10312569040 10753331200 1373128224 14.355 98 29 58 0 1393802784 13383120 7291440
18:30:43 67310 8192000000 10299567936 10757623808 1376149968 14.382 99 18 34 1 1397673792 17923888 3599936
18:32:52 81288 8192000000 10296118160 10742824960 1362204864 14.257 98 25 49 1 1375431616 7220032 6006720
18:35:36 28271 8192000000 10312211616 10762178560 1401632640 14.610 100 50 98 4 1423684384 10611040 11440704
18:37:17 69073 8192000000 10310056320 10771681280 1410163200 14.685 100 10 20 0 1422884096 10182736 2538160
18:39:27 64253 8192000000 10280896272 10739421184 1366497712 14.296 98 19 38 1 1383466608 12274960 4693936
18:41:42 43685 8192000000 10300167488 10757156864 1382629520 14.440 99 15 30 0 1398089968 12277664 3182784
18:43:31 99086 8192000000 10284893008 10733809664 1311338176 13.798 94 9 18 0 1323398816 9867472 2193168
18:46:20 41346 8192000000 10284411648 10733539328 1338538512 14.044 97 24 48 0 1353497168 9688736 5269920
18:48:04 72663 8192000000 10273731936 10716655616 1314966416 13.831 95 21 42 0 1329837840 10546512 4324912
18:50:20 45773 8192000000 10271115856 10722213888 1332190496 13.987 97 26 52 0 1353709488 15821632 5697360
18:53:09 140343 8192000000 10269109328 10722185216 1291096464 13.614 94 15 30 0 1306979392 12405152 3477776
18:56:31 80124 8192000000 10271457888 10717503488 1333448880 13.998 97 23 46 2 1347321232 8871184 5001168
18:58:54 37071 8192000000 10244002512 10692640768 1314998416 13.831 97 16 32 0 1328069408 9707024 3363968
19:02:03 64419 8192000000 10250060128 10701627392 1328582896 13.954 98 14 28 0 1346231008 14132576 3515536
19:04:08 50717 8192000000 10260495296 10702114816 1330490992 13.972 98 24 48 1 1351709568 15348304 5870272
19:05:59 55887 8192000000 10272252512 10727268352 1356519072 14.206 99 18 36 0 1371333536 10591472 4222992
19:07:55 51760 8192000000 10289046656 10744729600 1380516832 14.421 100 14 27 0 1392663760 9035568 3111360
19:09:48 91451 8192000000 10291290480 10746298368 1354764976 14.190 98 11 22 2 1373393184 15614912 3013296
19:12:20 77628 8192000000 10274340736 10743713792 1340473712 14.062 96 11 22 1 1360257792 17446608 2337472
19:14:38 67037 8192000000 10296734080 10756968448 1377657472 14.396 99 25 50 1 1398294064 14687216 5949376
19:16:53 25939 8192000000 10313091760 10745479168 1367089568 14.301 98 55 108 0 1389867264 10538464 12239232
19:18:22 123405 8192000000 10305415536 10760040448 1398521168 14.582 100 36 71 2 1417434176 10610704 8302304
19:21:31 43479 8192000000 10291699584 10755391488 1384870336 14.460 99 6 12 1 1402397280 16277968 1248976
19:23:24 52543 8192000000 10266813184 10720436224 1352198016 14.167 98 12 24 0 1363873056 8988656 2686384
19:25:46 121247 8192000000 10276317904 10746744832 1360961488 14.246 98 14 27 1 1377399936 13252896 3185552
19:28:50 73193 8192000000 10288822320 10746564608 1357652320 14.216 98 14 28 0 1372080256 11554128 2873808
19:31:05 93658 8192000000 10313623056 10775998464 1393428544 14.536 100 21 42 1 1419599536 20511696 5659296
19:33:40 50884 8192000000 10283029408 10734624768 1332895584 13.993 96 9 18 0 1344057968 9345728 1816656
19:35:31 34280 8192000000 10296841024 10742325248 1371000144 14.336 99 36 71 0 1388617392 9888864 7728384
19:37:06 71869 8192000000 9186260528 9437847552 341632048 4.003 26 3 6 0 346125120 4016688 476384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2021 19:39:02 to 09-12-2021 19:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2021 19:39:18 to 09-12-2021 19:39:20.

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          232767
MON$OLDEST_ACTIVE               232768
MON$OLDEST_SNAPSHOT             232768
MON$NEXT_TRANSACTION            232768
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                       2003456
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:159: 
:160: mscs-test-161.budzdorov.ru    Thu Dec  9 19:38:20 2021
:161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:162: 
--- end of diff output ---

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

09.12.21 19:39:21. 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-12-09 19:39:23 No severe PSQL-related problems occured
09.12.21 19:39:25. End of report.