Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2020 22:28
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17559708 kB
MemAvailable 31631180 kB
Buffers 0 kB
Cached 14155168 kB
SwapCached 5424 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.7
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 2020-10-14 22:26:57
DTS_END                         2020-10-14 22:26:57.6890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/49884
TRN_ID                          257944
ATT_ID                          642
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: 9918.08 19631 1190169
customer order: creation 186.88 373 22426
customer order: refuse 16.25 297 1950
order to supplier: creation 49.41 1211 5929
order to supplier: removal 3.86 69455 463
invoice (draft): creation 50.06 3073 6007
invoice (draft): removal 54.97 627 6596
invoice accept: apply 45.88 15423 5505
invoice accept: cancel 49.81 701 5977
customer reserve: creation 4205.87 155 504704
customer reserve: removal 4937.98 42 592557
realization accept: apply 90.12 154 10814
realization accept: cancel 85.62 104 10274
payment from customer: creation 64.01 111 7681
payment from customer: removal 6.56 64 787
payment to supplier: creation 53.78 118 6453
payment to supplier: removal 3.88 95 466
service: total inventory turnovers 5.42 968 650
service: total monetary turnovers 6.81 71 817
service: aggregate perf. data 0.91 17378 109
service: refresh index statistics 0.03 282197 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2020 22:28:51 to 14-10-2020 22:28:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5792
TEST_TIME 2 5198
TEST_TIME 3 5915
TEST_TIME 4 6376
TEST_TIME 5 6000
TEST_TIME 6 5817
TEST_TIME 7 6069
TEST_TIME 8 6382
TEST_TIME 9 6902
TEST_TIME 10 7497
TEST_TIME 11 7997
TEST_TIME 12 8049
TEST_TIME 13 8338
TEST_TIME 14 8935
TEST_TIME 15 9119
TEST_TIME 16 9619
TEST_TIME 17 9759
TEST_TIME 18 10651
TEST_TIME 19 11214
TEST_TIME 20 11898
TEST_TIME 21 12135
TEST_TIME 22 12092
TEST_TIME 23 12053
TEST_TIME 24 11924
TEST_TIME 25 11829
TEST_TIME 26 11688
TEST_TIME 27 11512
TEST_TIME 28 11556
TEST_TIME 29 11558
TEST_TIME 30 11520
TEST_TIME 31 11357
TEST_TIME 32 11321
TEST_TIME 33 11290
TEST_TIME 34 11234
TEST_TIME 35 11061
TEST_TIME 36 10925
TEST_TIME 37 10967
TEST_TIME 38 10980
TEST_TIME 39 10843
TEST_TIME 40 10815
TEST_TIME 41 10733
TEST_TIME 42 10640
TEST_TIME 43 10517
TEST_TIME 44 10437
TEST_TIME 45 10379
TEST_TIME 46 10399
TEST_TIME 47 10397
TEST_TIME 48 10386
TEST_TIME 49 10396
TEST_TIME 50 10407
TEST_TIME 51 10534
TEST_TIME 52 10511
TEST_TIME 53 10438
TEST_TIME 54 10631
TEST_TIME 55 10605
TEST_TIME 56 10690
TEST_TIME 57 10581
TEST_TIME 58 10452
TEST_TIME 59 10392
TEST_TIME 60 10335
TEST_TIME 61 10240
TEST_TIME 62 10145
TEST_TIME 63 10168
TEST_TIME 64 10174
TEST_TIME 65 10226
TEST_TIME 66 10224
TEST_TIME 67 10204
TEST_TIME 68 10260
TEST_TIME 69 10226
TEST_TIME 70 10227
TEST_TIME 71 10256
TEST_TIME 72 10321
TEST_TIME 73 10399
TEST_TIME 74 10418
TEST_TIME 75 10406
TEST_TIME 76 10405
TEST_TIME 77 10365
TEST_TIME 78 10295
TEST_TIME 79 10227
TEST_TIME 80 10171
TEST_TIME 81 10097
TEST_TIME 82 10118
TEST_TIME 83 10139
TEST_TIME 84 10123
TEST_TIME 85 10107
TEST_TIME 86 10045
TEST_TIME 87 10059
TEST_TIME 88 10113
TEST_TIME 89 10090
TEST_TIME 90 10084
TEST_TIME 91 10166
TEST_TIME 92 10185
TEST_TIME 93 10181
TEST_TIME 94 10213
TEST_TIME 95 10269
TEST_TIME 96 10255
TEST_TIME 97 10243
TEST_TIME 98 10260
TEST_TIME 99 10160
TEST_TIME 100 10122
TEST_TIME 101 10108
TEST_TIME 102 10088
TEST_TIME 103 10034
TEST_TIME 104 9998
TEST_TIME 105 10007
TEST_TIME 106 9949
TEST_TIME 107 9923
TEST_TIME 108 9951
TEST_TIME 109 9923
TEST_TIME 110 9908
TEST_TIME 111 9890
TEST_TIME 112 9923
TEST_TIME 113 9940
TEST_TIME 114 9990
TEST_TIME 115 9987
TEST_TIME 116 9972
TEST_TIME 117 9991
TEST_TIME 118 9973
TEST_TIME 119 9963
TEST_TIME 120 9921

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2020 22:28:54 to 14-10-2020 22:28:58.

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 1658767 1658767 0 0.00 0 19084 5 0 0
doc_list_biud 1658766 1658766 0 0.00 0 53164 20 0 0
sp_add_invoice_to_stock 5595 5505 90 1.60 21 118724 15423 0 90
sp_cancel_adding_invoice 5977 5977 0 0.00 5 67423 702 0 0
sp_cancel_client_order 1950 1950 0 0.00 4 18248 297 0 0
sp_cancel_customer_reserve 592557 592557 0 0.00 1 21278 42 0 0
sp_cancel_pay_from_customer 787 787 0 0.00 3 7753 64 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 2 9428 95 0 0
sp_cancel_supplier_invoice 6602 6596 6 0.09 2 13611 628 0 6
sp_cancel_supplier_order 463 463 0 0.00 7 1015426 69456 0 0
sp_cancel_write_off 10274 10274 0 0.00 1 9384 104 0 0
sp_client_order 22426 22426 0 0.00 3 17724 373 0 0
sp_customer_reserve 504704 504704 0 0.00 0 60878 155 0 0
sp_fill_shopping_cart 34352 34352 0 0.00 1 15789 33 0 0
sp_fill_shopping_cart_clo_res 504698 504698 0 0.00 0 19565 14 0 0
sp_get_clo_for_invoice 5498 5498 0 0.00 0 34465 262 0 0
sp_kill_cost_storno 17504 17504 0 0.00 0 14885 44 0 0
sp_kill_qstorno_ret_qs2qd 1202447 1202447 0 0.00 0 53162 23 0 0
sp_kill_qty_storno 634129 634129 0 0.00 0 53162 51 0 0
sp_lock_selected_doc 22703 22703 0 0.00 0 7426 7 0 0
sp_make_cost_storno 30446 30446 0 0.00 0 19062 43 0 0
sp_multiply_rows_for_pdistr 30446 30446 0 0.00 0 9939 25 0 0
sp_multiply_rows_for_qdistr 22426 22426 0 0.00 0 13959 173 0 0
sp_pay_from_customer 7681 7681 0 0.00 4 14531 112 0 0
sp_pay_to_supplier 6453 6453 0 0.00 5 17056 118 0 0
sp_payment_common 14134 14134 0 0.00 1 8174 26 0 0
sp_qd_handle_on_cancel_clo 1950 1950 0 0.00 0 18243 270 0 0
sp_qd_handle_on_invoice_upd_sts 11475 11475 0 0.00 0 43824 258 0 0
sp_qd_handle_on_reserve_upd_sts 10814 10814 0 0.00 0 14994 49 0 0
sp_reserve_write_off 10814 10814 0 0.00 6 15022 155 0 0
sp_supplier_invoice 6007 6007 0 0.00 6 102181 3073 0 0
sp_supplier_order 5929 5929 0 0.00 5 34104 1211 0 0
srv_aggregate_perf_data 110 109 1 0.90 2920 342712 17378 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1938 148947 51527 0 0
srv_find_qd_qs_mism 1138760 1138760 0 0.00 0 13467 5 0 0
srv_make_invnt_saldo 650 650 0 0.00 20 51336 968 0 0
srv_make_money_saldo 817 817 0 0.00 1 16881 72 0 0
srv_recalc_idx_stat 4 4 0 0.00 107485 377334 282198 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5505 5505 0 0.00 0 33153 155 0 0
t$perf-norm:sp_cancel_adding_invoice 483 483 0 0.00 0 11610 110 0 0
t$perf-norm:sp_cancel_client_order 1950 1950 0 0.00 0 5631 19 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 3601 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 787 787 0 0.00 0 3265 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 2270 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 459 459 0 0.00 0 1964 9 0 0
t$perf-norm:sp_cancel_supplier_order 463 463 0 0.00 0 30649 1359 0 0
t$perf-norm:sp_cancel_write_off 1075 1075 0 0.00 0 3301 7 0 0
t$perf-norm:sp_client_order 22426 22426 0 0.00 0 11799 14 0 0
t$perf-norm:sp_customer_reserve 1130 1130 0 0.00 0 4017 7 0 0
t$perf-norm:sp_pay_from_customer 7681 7681 0 0.00 0 23144 17 0 0
t$perf-norm:sp_pay_to_supplier 6453 6453 0 0.00 0 9813 15 0 0
t$perf-norm:sp_reserve_write_off 10814 10814 0 0.00 0 25344 20 0 0
t$perf-norm:sp_supplier_invoice 6007 6007 0 0.00 0 6301 17 0 0
t$perf-norm:sp_supplier_order 5929 5929 0 0.00 0 4441 14 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 233 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 20090 400 0 0
t$perf-norm:srv_make_invnt_saldo 650 650 0 0.00 0 4358 20 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 3463 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 59 18 0 0
v_add_invoice_to_stock 11176 10996 180 1.61 0 8088 21 0 180
v_all_customers 40438 40438 0 0.00 0 172 1 0 0
v_all_suppliers 33168 33168 0 0.00 0 20 1 0 0
v_all_wares 44852 44852 0 0.00 0 170 1 0 0
v_cancel_adding_invoice 966 966 0 0.00 0 825 6 0 0
v_cancel_client_order 3900 3900 0 0.00 0 3455 8 0 0
v_cancel_customer_prepayment 1574 1574 0 0.00 0 2679 8 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 3422 9 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 7452 14 0 0
v_cancel_supplier_invoice 930 918 12 1.29 0 4646 21 0 12
v_cancel_supplier_order 926 926 0 0.00 0 590 3 0 0
v_cancel_write_off 2150 2150 0 0.00 0 4071 17 0 0
v_max_id_clo_ord 5929 5929 0 0.00 0 253 1 0 0
v_max_id_clo_res 1130 1130 0 0.00 0 3880 19 0 0
v_max_id_ord_sup 5997 5997 0 0.00 0 4423 5 0 0
v_max_non_paid_invoice 1795 1795 0 0.00 0 2503 5 0 0
v_max_non_paid_realizn 4289 4289 0 0.00 0 5747 7 0 0
v_min_id_clo_ord 5929 5929 0 0.00 0 1932 5 0 0
v_min_id_clo_res 1130 1130 0 0.00 0 6469 13 0 0
v_min_id_ord_sup 5997 5997 0 0.00 0 6975 5 0 0
v_min_non_paid_invoice 6453 6453 0 0.00 0 6510 14 0 0
v_min_non_paid_realizn 7681 7681 0 0.00 0 7797 15 0 0
v_our_firm 11198 11198 0 0.00 0 17 1 0 0
v_reserve_write_off 21628 21628 0 0.00 0 12741 9 0 0
x_lock_depdocs_on_canc_invoice 12114 12114 0 0.00 0 22255 88 0 0
x_lock_depdocs_on_canc_sup_ord 463 463 0 0.00 0 2251 207 0 0
x_make_qstorno_1000_1200 5929 5929 0 0.00 1 34098 1145 0 0
x_make_qstorno_1200_2000 6007 6007 0 0.00 3 102169 2956 0 0
x_make_qstorno_2100_3300 504704 504704 0 0.00 0 60866 139 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2020 22:28:59 to 14-10-2020 22:29:03.

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
19:56:59 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:00 15396 8192000000 10371051824 10691039232 1425441552 14.821 100 89 178 1 1461091696 6562976 29087168
19:59:20 10207 8192000000 10432554992 10763481088 1483838576 15.335 100 91 182 0 1522046672 8725280 29482816
20:00:35 19783 8192000000 10468056496 10810859520 1527182944 15.713 100 84 168 0 1563679504 10010400 26486160
20:02:07 19333 8192000000 10551338912 10909528064 1614397696 16.462 100 84 168 2 1650519680 10673088 25448896
20:03:35 7263 8192000000 10634201360 11008573440 1699957792 17.185 100 66 132 0 1730588800 11630976 19000032
20:04:43 3098 8192000000 10710133104 11090173952 1772813312 17.790 100 85 168 4 1810181568 13412832 23955424
20:05:47 2119 8192000000 10773947200 11161251840 1836640480 18.313 100 87 173 3 1874471744 13557024 24274240
20:06:51 1253 8192000000 10822689824 11214139392 1879822912 18.664 100 90 175 1 1918105040 12311088 25971040
20:07:52 9776 8192000000 10802749008 11223490560 1882740128 18.687 100 49 98 0 1903016304 5002800 15273376
20:09:08 12871 8192000000 10803028128 11231965184 1887617904 18.727 100 23 45 1 1901075536 6642832 6814800
20:10:22 9134 8192000000 10805175568 11243565056 1899018512 18.818 100 18 36 1 1909824480 5946560 4859408
20:11:33 21401 8192000000 10813959088 11256049664 1908598800 18.895 100 24 48 1 1921342800 6074240 6669760
20:13:25 4556 8192000000 10838718752 11269287936 1917021296 18.963 100 40 77 3 1935854352 7040224 11792832
20:14:33 4858 8192000000 10827055456 11253387264 1888039632 18.730 99 24 48 0 1901305568 5856192 7409744
20:15:38 8574 8192000000 10793664240 11229904896 1869951408 18.584 99 24 48 1 1882847680 5598528 7297744
20:16:50 51325 8192000000 10754768736 11194011648 1775710208 17.814 94 22 44 0 1791086880 9625104 5751568
20:18:42 6511 8192000000 10723350512 11142021120 1791096688 17.941 99 26 52 0 1804927440 6553488 7277264
20:19:49 10893 8192000000 10713973504 11122282496 1730968016 17.444 96 17 34 2 1741074944 4913264 5193664
20:21:01 54102 8192000000 10739236256 11162066944 1808623056 18.085 99 15 30 0 1818568560 5523776 4421728
20:22:56 5819 8192000000 10759459552 11178295296 1834194576 18.294 99 34 66 4 1850048656 5757600 10096480
20:24:02 10693 8192000000 10785876160 11213762560 1868772128 18.574 100 13 26 1 1877033952 4749072 3512752
20:25:13 20866 8192000000 10799119456 11225313280 1882717232 18.687 100 20 39 3 1893777664 4976224 6084208
20:26:36 19429 8192000000 10822592544 11254321152 1907778368 18.889 100 22 42 2 1918843680 4721888 6343424
20:27:59 41789 8192000000 10874273616 11306295296 1927470816 19.047 99 39 78 2 1949206352 10476752 11258784
20:29:47 26883 8192000000 10898811504 11335831552 1962629584 19.327 100 47 94 3 1991557120 15490032 13437504
20:31:23 10075 8192000000 10917848624 11353382912 1979725568 19.463 100 43 84 1 2010266656 18335104 12205984
20:32:39 11187 8192000000 10919020640 11354513408 1976553136 19.437 100 45 91 2 2010906752 20803632 13549984
20:33:55 10503 8192000000 10933912704 11373432832 1990820064 19.550 100 50 100 0 2028668080 22746848 15101168
20:35:10 11994 8192000000 10958071824 11387764736 2001747072 19.637 100 57 114 0 2047753744 28365904 17640768
20:36:24 1938 8192000000 10926906896 11340087296 1940841568 19.153 98 76 151 2 1995582192 31912304 22828320
20:37:29 3289 8192000000 10922772384 11345784832 1957380976 19.285 100 63 125 2 2009106784 32082368 19643440
20:38:40 7112 8192000000 10922258480 11348393984 1955628592 19.271 100 57 114 2 2007243152 33513136 18101424
20:39:57 3684 8192000000 10918427936 11334721536 1941172832 19.156 100 75 149 3 2002251040 38547024 22531184
20:41:04 7830 8192000000 10882026528 11305054208 1873697152 18.614 97 43 86 0 1926899968 39029136 14173680
20:42:15 12151 8192000000 10853171632 11285032960 1882371248 18.684 99 27 54 2 1930687040 38778704 9537088
20:43:35 15804 8192000000 10880651024 11316109312 1895471872 18.790 99 34 68 2 1950515008 42590000 12453136
20:44:55 27186 8192000000 10887916176 11324121088 1905855536 18.873 99 26 52 1 1953314336 38802064 8656736
20:46:22 73555 8192000000 10862904544 11320451072 1902974816 18.850 99 10 20 0 1930493728 24645264 2873648
20:48:36 105632 8192000000 10876042080 11342675968 1899709392 18.824 98 9 18 0 1918233232 16076672 2447168
20:51:34 52473 8192000000 10884720960 11339485184 1951982256 19.242 100 30 61 1 1969826336 10133264 7710816
20:53:29 116276 8192000000 10863646160 11343331328 1928461744 19.055 99 30 60 0 1952069648 14503120 9104784
20:56:33 67118 8192000000 10887716352 11355598848 1963665440 19.335 100 22 44 0 1985040832 15507744 5867648
20:58:48 25622 8192000000 10911869184 11356200960 1955256208 19.268 99 26 51 4 1968966400 7197120 6513072
21:00:18 50397 8192000000 10879529568 11336187904 1938856688 19.138 98 14 28 2 1950970816 8325072 3789056
21:02:10 81683 8192000000 10911136464 11370725376 1969129952 19.379 99 13 26 2 1985464640 12057312 4277376
21:04:32 48502 8192000000 10909754944 11377717248 1991464032 19.555 100 20 40 1 2007684976 10912544 5308400
21:06:22 49529 8192000000 10920465088 11390803968 1996855440 19.598 100 27 54 0 2014129328 9888688 7385200
21:08:12 52813 8192000000 10909680896 11380600832 1980945920 19.472 99 23 46 0 1995651584 7634880 7070784
21:11:37 65938 8192000000 10906865312 11383275520 1945447392 19.190 97 8 16 1 1958921120 11199392 2274336
21:13:44 29349 8192000000 10896000320 11362066432 1947111936 19.203 98 19 38 3 1960583952 8167808 5304208
21:16:10 40542 8192000000 10932880080 11403206656 2004375728 19.657 100 13 26 4 2022657328 14493488 3788112
21:18:05 119625 8192000000 10880400784 11345293312 1825654112 18.224 91 13 26 1 1843563744 13941632 3968000
21:21:05 55252 8192000000 10872160864 11332739072 1863338432 18.530 95 25 51 1 1878138256 8282976 6516848
21:23:02 25526 8192000000 10853426608 11296079872 1877275936 18.643 98 31 63 2 1897327776 11546992 8504848
21:24:35 70047 8192000000 10806576720 11255066624 1794016288 17.965 94 36 72 0 1817005824 13149920 9839616
21:27:59 50260 8192000000 10804387808 11268136960 1873190432 18.610 99 10 20 0 1882737056 6756992 2789632
21:29:50 49443 8192000000 10815294256 11282194432 1865012416 18.544 98 14 28 1 1883742704 14291424 4438864
21:31:39 46510 8192000000 10854536896 11310411776 1889017248 18.738 98 22 44 1 1904879824 9347504 6515072
21:33:27 34578 8192000000 10883473344 11342331904 1953229152 19.252 100 20 38 1 1967239952 8434496 5576304
21:35:01 105610 8192000000 10893562432 11356123136 1908141808 18.892 97 21 42 0 1930565600 16433600 5990192
21:37:49 29443 8192000000 10896990080 11365023744 1957110176 19.283 100 9 18 0 1976683488 16859888 2713424
21:39:19 60279 8192000000 10907395760 11364978688 1961039008 19.314 100 20 40 1 1978012192 11343728 5629456
21:41:19 51838 8192000000 10906349120 11363876864 1949666592 19.224 99 35 70 0 1971171728 11304496 10200640
21:43:19 35916 8192000000 10918814848 11374632960 1977648896 19.446 100 43 85 1 2005556672 15776768 12131008
21:44:55 69026 8192000000 10902244288 11367096320 1940079984 19.147 98 22 44 1 1953938352 7686288 6172080
21:47:05 96899 8192000000 10899252352 11387117568 1944461872 19.182 98 12 24 2 1969609920 21538496 3609552
21:49:42 45194 8192000000 10886519072 11372724224 1949227440 19.220 99 10 19 0 1970920368 18938544 2754384
21:51:34 65725 8192000000 10889304336 11361243136 1915275888 18.949 97 20 40 2 1933277632 12652992 5348752
21:53:40 97966 8192000000 10880788592 11345891328 1888127760 18.731 96 18 35 0 1906760192 13533840 5098592
21:56:19 57030 8192000000 10913418992 11387301888 1975163312 19.426 100 21 42 3 1996985744 16252064 5570368
21:58:22 56199 8192000000 10898260864 11376037888 1884437264 18.701 95 16 30 0 1904973024 16989824 3545936
22:00:21 97172 8192000000 10901958896 11382153216 1939592064 19.144 98 11 22 0 1963085712 20390160 3103488
22:03:00 148947 8192000000 10903068352 11385442304 1908762624 18.897 96 11 22 0 1924965232 13348064 2854544
22:06:50 50267 8192000000 10878493744 11346395136 1942785872 19.169 99 21 42 1 1957038848 8517088 5735888
22:08:48 68018 8192000000 10856487584 11331862528 1914647200 18.944 98 8 16 0 1924178400 7071024 2460176
22:11:16 74360 8192000000 10822459536 11292401664 1856711088 18.477 95 15 30 1 1870272976 8654368 4907520
22:13:37 33731 8192000000 10840290448 11304087552 1878933296 18.656 98 17 34 4 1893652256 10018752 4700208
22:15:12 32067 8192000000 10824721872 11282247680 1894385344 18.781 100 28 56 0 1910140464 7799680 7955440
22:17:00 42226 8192000000 10837309120 11290140672 1874780704 18.623 98 25 50 1 1894516400 12593152 7142544
22:18:44 53368 8192000000 10847502208 11297480704 1876779008 18.639 98 34 68 2 1903374368 17007632 9587728
22:20:43 85399 8192000000 10853566288 11290071040 1823107328 18.203 95 27 53 1 1841361536 10651408 7602800
22:23:11 87912 8192000000 10869777504 11340943360 1915859584 18.954 99 28 56 0 1938881072 15449824 7571664
22:25:59 77019 8192000000 9191999184 9451048960 358050304 4.187 19 9 15 1 367321328 6888272 2382752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2020 22:29:04 to 14-10-2020 22:29:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 90 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2020 22:29:20 to 14-10-2020 22:29:22.

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          258234
MON$OLDEST_ACTIVE               258235
MON$OLDEST_SNAPSHOT             258235
MON$NEXT_TRANSACTION            258235
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                       2026528
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Wed Oct 14 22:27:23 2020
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Wed Oct 14 22:27:23 2020
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
--- end of diff output ---

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

14.10.20 22:29:23. 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
2020-10-14 22:29:25 No severe PSQL-related problems occured
14.10.20 22:29:26. End of report.