Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.03.2021 04:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16660144 kB
MemAvailable 31471116 kB
Buffers 20 kB
Cached 14898288 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 4.0.0
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-03-20 04:26:58
DTS_END                         2021-03-20 04:26:58.8660
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/45456
TRN_ID                          245367
ATT_ID                          669
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: 9054.33 20514 1086519
customer order: creation 179.51 365 21541
customer order: refuse 14.22 319 1706
order to supplier: creation 48.05 1476 5766
order to supplier: removal 2.99 114975 359
invoice (draft): creation 47.78 3696 5734
invoice (draft): removal 48.38 966 5806
invoice accept: apply 43.39 17425 5207
invoice accept: cancel 43.82 890 5258
customer reserve: creation 3946.73 174 473608
customer reserve: removal 4385.78 62 526294
realization accept: apply 86.60 175 10392
realization accept: cancel 74.88 122 8985
payment from customer: creation 60.54 98 7265
payment from customer: removal 5.74 130 689
payment to supplier: creation 51.70 100 6204
payment to supplier: removal 3.45 57 414
service: total inventory turnovers 3.65 3548 438
service: total monetary turnovers 6.28 663 753
service: aggregate perf. data 0.80 20212 96
service: refresh index statistics 0.03 244817 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-03-2021 04:27:40 to 20-03-2021 04:27:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 694
TEST_TIME 2 1718
TEST_TIME 3 2518
TEST_TIME 4 3462
TEST_TIME 5 4480
TEST_TIME 6 5016
TEST_TIME 7 5263
TEST_TIME 8 5607
TEST_TIME 9 5515
TEST_TIME 10 5464
TEST_TIME 11 5781
TEST_TIME 12 5869
TEST_TIME 13 6094
TEST_TIME 14 6232
TEST_TIME 15 6818
TEST_TIME 16 6898
TEST_TIME 17 7810
TEST_TIME 18 8235
TEST_TIME 19 8595
TEST_TIME 20 8691
TEST_TIME 21 8624
TEST_TIME 22 8661
TEST_TIME 23 8971
TEST_TIME 24 9190
TEST_TIME 25 9682
TEST_TIME 26 9866
TEST_TIME 27 10232
TEST_TIME 28 10491
TEST_TIME 29 10408
TEST_TIME 30 11082
TEST_TIME 31 10930
TEST_TIME 32 10789
TEST_TIME 33 10799
TEST_TIME 34 10792
TEST_TIME 35 10788
TEST_TIME 36 10634
TEST_TIME 37 10365
TEST_TIME 38 10099
TEST_TIME 39 10197
TEST_TIME 40 10072
TEST_TIME 41 10012
TEST_TIME 42 10129
TEST_TIME 43 10298
TEST_TIME 44 10203
TEST_TIME 45 10155
TEST_TIME 46 10051
TEST_TIME 47 10023
TEST_TIME 48 9941
TEST_TIME 49 9975
TEST_TIME 50 9892
TEST_TIME 51 9853
TEST_TIME 52 9972
TEST_TIME 53 9927
TEST_TIME 54 9947
TEST_TIME 55 9875
TEST_TIME 56 9868
TEST_TIME 57 9882
TEST_TIME 58 9949
TEST_TIME 59 9885
TEST_TIME 60 9771
TEST_TIME 61 9711
TEST_TIME 62 9671
TEST_TIME 63 9580
TEST_TIME 64 9553
TEST_TIME 65 9569
TEST_TIME 66 9512
TEST_TIME 67 9504
TEST_TIME 68 9443
TEST_TIME 69 9479
TEST_TIME 70 9477
TEST_TIME 71 9574
TEST_TIME 72 9602
TEST_TIME 73 9585
TEST_TIME 74 9773
TEST_TIME 75 9741
TEST_TIME 76 9696
TEST_TIME 77 9780
TEST_TIME 78 9911
TEST_TIME 79 9831
TEST_TIME 80 9711
TEST_TIME 81 9651
TEST_TIME 82 9603
TEST_TIME 83 9512
TEST_TIME 84 9474
TEST_TIME 85 9398
TEST_TIME 86 9335
TEST_TIME 87 9282
TEST_TIME 88 9266
TEST_TIME 89 9183
TEST_TIME 90 9218
TEST_TIME 91 9234
TEST_TIME 92 9259
TEST_TIME 93 9265
TEST_TIME 94 9238
TEST_TIME 95 9251
TEST_TIME 96 9231
TEST_TIME 97 9250
TEST_TIME 98 9337
TEST_TIME 99 9294
TEST_TIME 100 9310
TEST_TIME 101 9298
TEST_TIME 102 9299
TEST_TIME 103 9309
TEST_TIME 104 9343
TEST_TIME 105 9271
TEST_TIME 106 9201
TEST_TIME 107 9189
TEST_TIME 108 9137
TEST_TIME 109 9106
TEST_TIME 110 9053
TEST_TIME 111 9062
TEST_TIME 112 9007
TEST_TIME 113 9015
TEST_TIME 114 8999
TEST_TIME 115 8975
TEST_TIME 116 9035
TEST_TIME 117 8991
TEST_TIME 118 9079
TEST_TIME 119 9088
TEST_TIME 120 9055

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-03-2021 04:27:44 to 20-03-2021 04:27:47.

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 1525685 1525685 0 0.00 0 34139 7 0 0
doc_list_biud 1525659 1525659 0 0.00 0 78672 28 0 0
sp_add_invoice_to_stock 5354 5207 147 2.74 33 173711 17426 0 147
sp_cancel_adding_invoice 5258 5258 0 0.00 4 75292 891 0 0
sp_cancel_client_order 1706 1706 0 0.00 4 33873 319 0 0
sp_cancel_customer_reserve 526294 526294 0 0.00 1 61437 62 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 27600 131 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 1183 58 0 0
sp_cancel_supplier_invoice 5823 5806 17 0.29 2 78676 966 0 17
sp_cancel_supplier_order 359 359 0 0.00 10 1222488 114976 0 0
sp_cancel_write_off 8985 8985 0 0.00 1 11938 122 0 0
sp_client_order 21541 21541 0 0.00 4 34395 365 0 0
sp_customer_reserve 473608 473608 0 0.00 0 97901 174 0 0
sp_fill_shopping_cart 33033 33033 0 0.00 1 23804 43 0 0
sp_fill_shopping_cart_clo_res 473607 473607 0 0.00 0 36690 14 0 0
sp_get_clo_for_invoice 5205 5205 0 0.00 0 34730 373 0 0
sp_kill_cost_storno 15346 15346 0 0.00 0 7411 57 0 0
sp_kill_qstorno_ret_qs2qd 1067738 1067738 0 0.00 0 78671 34 0 0
sp_kill_qty_storno 563986 563986 0 0.00 0 78672 74 0 0
sp_lock_selected_doc 21356 21356 0 0.00 0 5784 4 0 0
sp_make_cost_storno 29066 29066 0 0.00 0 34128 50 0 0
sp_multiply_rows_for_pdistr 29043 29043 0 0.00 0 7650 26 0 0
sp_multiply_rows_for_qdistr 21541 21541 0 0.00 0 11271 176 0 0
sp_pay_from_customer 7265 7265 0 0.00 4 26291 98 0 0
sp_pay_to_supplier 6204 6204 0 0.00 5 18418 101 0 0
sp_payment_common 13463 13463 0 0.00 1 7349 14 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 33863 288 0 0
sp_qd_handle_on_invoice_upd_sts 10455 10455 0 0.00 0 29971 363 0 0
sp_qd_handle_on_reserve_upd_sts 10381 10381 0 0.00 0 30582 63 0 0
sp_reserve_write_off 10392 10392 0 0.00 7 31390 175 0 0
sp_supplier_invoice 5734 5734 0 0.00 6 126756 3696 0 0
sp_supplier_order 5766 5766 0 0.00 5 44916 1476 0 0
srv_aggregate_perf_data 97 96 1 1.03 2904 538174 20212 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1983 131415 46101 0 0
srv_find_qd_qs_mism 1037543 1037543 0 0.00 0 11815 6 0 0
srv_make_invnt_saldo 438 438 0 0.00 52 108842 3548 0 0
srv_make_money_saldo 753 753 0 0.00 0 22443 664 0 0
srv_recalc_idx_stat 4 4 0 0.00 136861 427249 244818 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 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 5207 5207 0 0.00 0 12371 154 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 7299 84 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 2474 12 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 5197 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 1546 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 179 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1463 16 0 0
t$perf-norm:sp_cancel_supplier_order 359 359 0 0.00 0 15678 1568 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 3371 9 0 0
t$perf-norm:sp_client_order 21541 21541 0 0.00 0 7681 15 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 2047 5 0 0
t$perf-norm:sp_pay_from_customer 7265 7265 0 0.00 0 5177 8 0 0
t$perf-norm:sp_pay_to_supplier 6204 6204 0 0.00 0 4542 9 0 0
t$perf-norm:sp_reserve_write_off 10392 10392 0 0.00 0 5532 9 0 0
t$perf-norm:sp_supplier_invoice 5734 5734 0 0.00 0 5700 16 0 0
t$perf-norm:sp_supplier_order 5766 5766 0 0.00 0 5878 16 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 434 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 83 2 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 2173 14 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 798 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10688 10394 294 2.75 0 18354 21 0 294
v_all_customers 38478 38478 0 0.00 0 103 1 0 0
v_all_suppliers 31638 31638 0 0.00 0 19 1 0 0
v_all_wares 43080 43080 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 9761 19 0 0
v_cancel_client_order 3412 3412 0 0.00 0 18357 10 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 27589 26 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 372 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1164 6 0 0
v_cancel_supplier_invoice 840 806 34 4.04 0 2293 20 0 34
v_cancel_supplier_order 718 718 0 0.00 0 29913 44 0 0
v_cancel_write_off 2120 2120 0 0.00 0 2207 14 0 0
v_max_id_clo_ord 5764 5764 0 0.00 0 2174 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 6039 15 0 0
v_max_id_ord_sup 5728 5728 0 0.00 0 6786 7 0 0
v_max_non_paid_invoice 1873 1873 0 0.00 0 644 2 0 0
v_max_non_paid_realizn 4106 4106 0 0.00 0 769 3 0 0
v_min_id_clo_ord 5764 5764 0 0.00 0 956 4 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 1511 7 0 0
v_min_id_ord_sup 5728 5728 0 0.00 0 13258 10 0 0
v_min_non_paid_invoice 6200 6200 0 0.00 0 2854 7 0 0
v_min_non_paid_realizn 7263 7263 0 0.00 0 6579 9 0 0
v_our_firm 10916 10916 0 0.00 0 17 1 0 0
v_reserve_write_off 20762 20762 0 0.00 0 26232 6 0 0
x_lock_depdocs_on_canc_invoice 10661 10661 0 0.00 0 25091 109 0 0
x_lock_depdocs_on_canc_sup_ord 359 359 0 0.00 0 15457 423 0 0
x_make_qstorno_1000_1200 5766 5766 0 0.00 2 44904 1395 0 0
x_make_qstorno_1200_2000 5734 5734 0 0.00 2 116974 3533 0 0
x_make_qstorno_2100_3300 473608 473608 0 0.00 0 97872 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-03-2021 04:27:48 to 20-03-2021 04:27:52.

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
01:57:02 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:03 29569 8192000000 9725890816 10019921920 797048064 8.866 100 89 178 1 826285696 7398736 21838896
01:59:45 18917 8192000000 9759346624 10063167488 832414192 9.224 100 91 181 2 866838368 12692048 21732128
02:01:10 13966 8192000000 9799963424 10116468736 867425680 9.574 100 84 167 1 905415056 18975280 19014096
02:02:27 13161 8192000000 9871767456 10202927104 917086864 10.067 100 81 162 2 983055904 48106752 17862288
02:03:47 8311 8192000000 9934776016 10280804352 962441792 10.513 100 85 170 0 1050086432 70353312 17291328
02:04:58 3714 8192000000 9972271232 10326147072 1000125888 10.880 100 87 174 0 1088726592 70274656 18326048
02:06:04 2234 8192000000 10014726928 10375979008 1028168352 11.151 100 82 163 0 1125430512 81389344 15872816
02:07:07 1668 8192000000 10023927216 10390355968 1054236224 11.401 100 92 184 0 1133513552 60187488 19089840
02:08:09 39117 8192000000 10004310576 10387361792 1073944336 11.590 100 55 112 1 1134921984 49186496 11791152
02:09:49 3359 8192000000 10009252880 10392035328 1081454320 11.661 100 60 114 3 1120128096 25815568 12858208
02:10:52 8268 8192000000 9997839664 10401861632 1086602240 11.710 100 25 50 0 1135380128 43367440 5410448
02:12:05 5626 8192000000 10013287776 10418094080 1091795760 11.760 100 28 56 1 1132282624 34062848 6424016
02:13:12 26154 8192000000 10025076208 10436091904 1092955936 11.771 100 36 72 2 1120564288 19937936 7670416
02:14:42 5462 8192000000 10012550176 10424324096 1097570144 11.815 100 45 92 1 1133780432 26168768 10041520
02:15:48 5652 8192000000 10006540304 10406047744 1089767536 11.740 99 45 89 0 1115822192 15621344 10433312
02:16:56 6451 8192000000 9984947984 10382753792 1043779936 11.301 97 37 75 0 1075894592 24570224 7544432
02:18:05 17297 8192000000 9985810016 10392014848 1033113168 11.198 97 33 66 0 1072772048 33553488 6105392
02:19:25 6532 8192000000 9960144832 10349031424 1014631328 11.020 97 49 95 2 1051658384 25827568 11199488
02:20:33 2953 8192000000 9924470320 10307846144 1004887264 10.926 98 56 113 0 1031653456 12388960 14377232
02:21:36 15875 8192000000 9935700928 10322436096 1022801248 11.099 98 26 52 0 1057506752 28159712 6545792
02:22:54 22671 8192000000 9941339440 10342981632 1022430608 11.095 98 30 60 1 1054779824 25668464 6680752
02:24:19 8608 8192000000 9977624096 10370519040 1057034256 11.428 100 48 93 3 1088969360 20214752 11720352
02:25:27 3818 8192000000 9991105024 10381914112 1067761392 11.531 100 36 72 0 1097234560 21404480 8068688
02:26:33 7911 8192000000 9986536352 10385952768 1074199120 11.592 100 37 72 4 1094710624 12900464 7611040
02:27:43 47836 8192000000 10023263936 10420068352 1092182752 11.763 100 53 106 0 1127437440 23874016 11380672
02:30:00 18103 8192000000 10112798544 10513571840 1119786512 12.025 100 65 127 1 1207921264 75913056 12221696
02:31:20 1982 8192000000 10118599376 10516246528 1134174064 12.161 100 69 135 0 1221905184 71794240 15936880
02:32:24 7358 8192000000 10144494720 10553135104 1141283712 12.228 100 53 106 0 1262153984 109551120 11319152
02:33:35 4594 8192000000 10173721792 10582585344 1147559264 12.287 100 54 108 1 1281698720 122451728 11687728
02:34:43 7340 8192000000 10170779840 10586017792 1138236656 12.199 99 59 116 2 1268086784 116778192 13071936
02:35:55 5847 8192000000 10189715024 10601701376 1152626032 12.334 100 63 122 3 1296639664 129862224 14151408
02:37:05 2369 8192000000 10229669888 10628628480 1143392368 12.247 99 75 149 1 1332218192 172571088 16254736
02:38:10 12245 8192000000 10248261232 10650550272 1143348512 12.247 100 66 130 1 1353582928 194553232 15681184
02:39:27 3902 8192000000 10278194768 10682933248 1150695072 12.316 100 76 153 0 1381930032 214513360 16721600
02:40:35 10185 8192000000 10285600880 10693513216 1140368256 12.219 99 67 133 2 1379617184 224185696 15063232
02:41:50 3851 8192000000 10322886256 10749284352 1135054864 12.169 99 79 158 1 1418030368 264620144 18355360
02:43:01 2767 8192000000 10317588336 10716348416 1131292176 12.134 100 76 150 0 1407464752 259495056 16677520
02:44:06 10310 8192000000 10220834352 10668015616 1115012960 11.980 99 38 77 1 1341335888 217263136 9059792
02:45:22 6512 8192000000 10250497952 10656858112 1133678688 12.156 100 56 109 1 1345907296 199723952 12504656
02:46:35 10954 8192000000 10264209008 10682445824 1137875392 12.196 100 44 86 0 1375814976 227755120 10184464
02:47:50 7104 8192000000 10318716176 10738966528 1123828480 12.063 99 39 78 0 1400453536 267338000 9287056
02:49:05 7703 8192000000 10312425216 10760327168 1110581648 11.938 98 41 80 1 1404202400 283768688 9852064
02:50:17 25320 8192000000 10314761904 10754826240 1125264016 12.077 100 32 64 1 1423023392 289247328 8512048
02:51:52 33674 8192000000 10245871200 10689359872 1125707888 12.081 100 21 42 0 1343586384 212757712 5120784
02:53:29 26014 8192000000 10224064960 10703667200 1128655536 12.109 100 28 53 3 1332619856 196781520 7182800
02:54:57 34549 8192000000 10206641344 10664169472 1136972304 12.187 100 21 41 1 1311956832 169881056 5103472
02:56:33 61985 8192000000 10067880832 10548129792 1119475184 12.022 99 10 19 1 1196134432 74505584 2153664
02:58:36 53017 8192000000 10118207008 10581864448 1147543168 12.286 100 27 54 1 1205128320 52345456 5239696
03:00:53 108245 8192000000 10118300704 10555002880 1132079696 12.141 98 23 47 1 1210071664 72908320 5083648
03:04:41 131415 8192000000 10194273472 10637438976 1136711056 12.185 98 40 79 0 1279227984 133701616 8815312
03:07:59 53015 8192000000 10199121856 10707181568 1133626736 12.156 99 15 30 0 1298993808 161871504 3495568
03:09:55 37522 8192000000 10081461456 10527891456 1135317312 12.171 99 13 27 0 1169495920 31265744 2912864
03:11:39 66619 8192000000 10083580512 10546995200 1106074080 11.895 96 14 28 1 1159110400 49849584 3186736
03:13:49 61563 8192000000 10096032640 10555576320 1133102064 12.151 99 5 10 1 1174858752 40832816 923872
03:15:51 35569 8192000000 10072553376 10527956992 1133710816 12.156 100 26 51 0 1184622976 46302144 4610016
03:17:30 82053 8192000000 10058084208 10541572096 1106005408 11.895 97 15 29 0 1185461328 76390160 3065760
03:20:04 64311 8192000000 10041210864 10501120000 1079266944 11.640 95 26 52 0 1131582848 47375648 4940256
03:22:11 41888 8192000000 10101997472 10548678656 1093396400 11.775 97 41 82 0 1181981200 80314928 8269872
03:24:01 86776 8192000000 10072497600 10509877248 1097652240 11.815 98 18 36 1 1154003632 53091648 3259744
03:26:33 88615 8192000000 10056485520 10522537984 1114760096 11.977 99 22 44 1 1148022560 29222208 4040256
03:30:44 74095 8192000000 10030093200 10508500992 1064815232 11.503 95 9 18 0 1117142896 50452720 1874944
03:33:01 48351 8192000000 10092242560 10523525120 1093904528 11.780 97 17 34 1 1140741520 43393936 3443056
03:34:50 22245 8192000000 10115754112 10556825600 1098495616 11.823 98 45 84 2 1194820896 86835104 9490176
03:36:14 27750 8192000000 10120811456 10559803392 1123256000 12.058 100 26 51 1 1193112400 64097904 5758496
03:37:48 71883 8192000000 10113731536 10555904000 1094013984 11.781 97 24 47 0 1182962608 83639872 5308752
03:40:12 49963 8192000000 10062040128 10549215232 1097660800 11.815 99 22 43 0 1218332448 116438400 4233248
03:42:10 32456 8192000000 10109705792 10535178240 1120294064 12.030 99 28 55 2 1176531680 50414160 5823456
03:43:50 87187 8192000000 10083994384 10597437440 1109151088 11.924 98 6 12 0 1184903088 74636336 1115664
03:46:19 123402 8192000000 10089458976 10541338624 1132211008 12.142 100 31 62 0 1180615376 41570304 6834064
03:49:28 99120 8192000000 10082357216 10559315968 1138232544 12.199 100 13 26 1 1191825696 50506176 3086976
03:52:08 55680 8192000000 10090061440 10555113472 1138875232 12.205 99 11 22 2 1199393056 58188480 2329344
03:54:16 131278 8192000000 10123203280 10613796864 1149429168 12.304 100 14 28 0 1224821296 72433632 2958496
03:57:28 75965 8192000000 10109882368 10571546624 1130819728 12.129 98 19 38 2 1200963744 66453952 3690064
04:00:03 38323 8192000000 10066937376 10499936256 1114286096 11.973 97 23 46 1 1133500896 14665696 4549104
04:01:43 45767 8192000000 10135354512 10579816448 1125212144 12.076 98 31 62 2 1191414720 60092384 6110192
04:04:04 74780 8192000000 10077570384 10599178240 1120153760 12.028 99 28 56 0 1198185232 72507968 5523504
04:06:22 35870 8192000000 10111619760 10582220800 1135105584 12.169 100 33 66 0 1215740784 73713552 6921648
04:08:38 47229 8192000000 10104907248 10555809792 1138125520 12.198 100 29 58 1 1201721888 58065568 5530800
04:10:26 77389 8192000000 10081271072 10564521984 1095638256 11.796 96 21 42 0 1139913360 39889552 4385552
04:14:05 45963 8192000000 10060264608 10526568448 1138573968 12.202 100 8 16 0 1166465728 25840624 2051136
04:16:01 90317 8192000000 10111363504 10610274304 1123887392 12.064 98 15 30 0 1186774304 59908656 2978256
04:19:26 44176 8192000000 10116167008 10558054400 1119218080 12.020 98 22 44 0 1204563360 80547792 4797488
04:21:18 46915 8192000000 10158542688 10611929088 1146879120 12.280 100 27 54 0 1261394096 108773856 5741120
04:23:14 66129 8192000000 10094877680 10613907456 1114548272 11.975 97 33 65 3 1200003872 77940528 7515072
04:25:22 36729 8192000000 10075203216 10526273536 1106234912 11.897 97 33 65 3 1160287360 47150064 6902384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-03-2021 04:27:54 to 20-03-2021 04:28:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 147 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-03-2021 04:28:10 to 20-03-2021 04:28:12.

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          243182
MON$OLDEST_ACTIVE               245971
MON$OLDEST_SNAPSHOT             245971
MON$NEXT_TRANSACTION            245971
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                       2121152
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             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:65: 
:66: mscs-test-161.budzdorov.ru    Sat Mar 20 04:27:00 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Sat Mar 20 04:27:01 2021
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Sat Mar 20 04:27:01 2021
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Sat Mar 20 04:27:02 2021
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
--- end of diff output ---

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

20.03.21 04:28:13. 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-03-20 04:28:15 No severe PSQL-related problems occured
20.03.21 04:28:17. End of report.