Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.01.2021 10: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 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 16532004 kB
MemAvailable 31205452 kB
Buffers 0 kB
Cached 15023624 kB
SwapCached 4864 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-01-19 10:26:51
DTS_END                         2021-01-19 10:26:51.3790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43804
TRN_ID                          233167
ATT_ID                          683
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: 8995.13 19712 1079416
customer order: creation 181.73 400 21807
customer order: refuse 15.54 353 1865
order to supplier: creation 47.17 1395 5660
order to supplier: removal 3.30 117553 396
invoice (draft): creation 47.42 3480 5690
invoice (draft): removal 48.58 1007 5829
invoice accept: apply 42.56 16908 5107
invoice accept: cancel 43.63 880 5236
customer reserve: creation 3950.79 165 474095
customer reserve: removal 4313.95 72 517674
realization accept: apply 86.98 164 10437
realization accept: cancel 81.43 116 9771
payment from customer: creation 60.31 99 7237
payment from customer: removal 5.88 88 706
payment to supplier: creation 50.80 127 6096
payment to supplier: removal 3.48 84 417
service: total inventory turnovers 4.14 3371 497
service: total monetary turnovers 6.42 517 770
service: aggregate perf. data 1.02 12999 122
service: refresh index statistics 0.03 234460 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-01-2021 10:27:45 to 19-01-2021 10:27:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2523
TEST_TIME 2 4179
TEST_TIME 3 4845
TEST_TIME 4 5145
TEST_TIME 5 5745
TEST_TIME 6 5407
TEST_TIME 7 5594
TEST_TIME 8 5520
TEST_TIME 9 5810
TEST_TIME 10 5721
TEST_TIME 11 5920
TEST_TIME 12 6107
TEST_TIME 13 5981
TEST_TIME 14 5919
TEST_TIME 15 6450
TEST_TIME 16 6879
TEST_TIME 17 7036
TEST_TIME 18 6967
TEST_TIME 19 7033
TEST_TIME 20 7381
TEST_TIME 21 7381
TEST_TIME 22 7932
TEST_TIME 23 8171
TEST_TIME 24 8203
TEST_TIME 25 8679
TEST_TIME 26 8674
TEST_TIME 27 8776
TEST_TIME 28 9905
TEST_TIME 29 9821
TEST_TIME 30 10507
TEST_TIME 31 10390
TEST_TIME 32 10400
TEST_TIME 33 10424
TEST_TIME 34 10356
TEST_TIME 35 10225
TEST_TIME 36 10007
TEST_TIME 37 10089
TEST_TIME 38 10072
TEST_TIME 39 10131
TEST_TIME 40 10264
TEST_TIME 41 10231
TEST_TIME 42 10254
TEST_TIME 43 10033
TEST_TIME 44 9892
TEST_TIME 45 9711
TEST_TIME 46 9696
TEST_TIME 47 9634
TEST_TIME 48 9653
TEST_TIME 49 9590
TEST_TIME 50 9579
TEST_TIME 51 9538
TEST_TIME 52 9484
TEST_TIME 53 9600
TEST_TIME 54 9624
TEST_TIME 55 9548
TEST_TIME 56 9510
TEST_TIME 57 9533
TEST_TIME 58 9454
TEST_TIME 59 9562
TEST_TIME 60 9626
TEST_TIME 61 9562
TEST_TIME 62 9657
TEST_TIME 63 9606
TEST_TIME 64 9569
TEST_TIME 65 9663
TEST_TIME 66 9696
TEST_TIME 67 9686
TEST_TIME 68 9739
TEST_TIME 69 9685
TEST_TIME 70 9587
TEST_TIME 71 9527
TEST_TIME 72 9448
TEST_TIME 73 9420
TEST_TIME 74 9345
TEST_TIME 75 9248
TEST_TIME 76 9161
TEST_TIME 77 9130
TEST_TIME 78 9224
TEST_TIME 79 9249
TEST_TIME 80 9216
TEST_TIME 81 9233
TEST_TIME 82 9318
TEST_TIME 83 9295
TEST_TIME 84 9251
TEST_TIME 85 9366
TEST_TIME 86 9292
TEST_TIME 87 9413
TEST_TIME 88 9429
TEST_TIME 89 9357
TEST_TIME 90 9343
TEST_TIME 91 9300
TEST_TIME 92 9239
TEST_TIME 93 9191
TEST_TIME 94 9148
TEST_TIME 95 9153
TEST_TIME 96 9154
TEST_TIME 97 9118
TEST_TIME 98 9074
TEST_TIME 99 9136
TEST_TIME 100 9127
TEST_TIME 101 9130
TEST_TIME 102 9114
TEST_TIME 103 9163
TEST_TIME 104 9149
TEST_TIME 105 9198
TEST_TIME 106 9196
TEST_TIME 107 9122
TEST_TIME 108 9079
TEST_TIME 109 9075
TEST_TIME 110 9031
TEST_TIME 111 9035
TEST_TIME 112 9007
TEST_TIME 113 8979
TEST_TIME 114 8987
TEST_TIME 115 8968
TEST_TIME 116 8976
TEST_TIME 117 8937
TEST_TIME 118 8974
TEST_TIME 119 8953
TEST_TIME 120 9003

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-01-2021 10:27:48 to 19-01-2021 10:27:52.

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 1517558 1517558 0 0.00 0 14782 6 0 0
doc_list_biud 1517555 1517555 0 0.00 0 34850 31 0 0
sp_add_invoice_to_stock 5206 5107 99 1.90 33 156466 16909 0 99
sp_cancel_adding_invoice 5236 5236 0 0.00 6 84949 881 0 0
sp_cancel_client_order 1865 1865 0 0.00 5 12871 353 0 0
sp_cancel_customer_reserve 517674 517674 0 0.00 1 34853 72 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 5581 89 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 5300 85 0 0
sp_cancel_supplier_invoice 5845 5829 16 0.27 2 29115 1007 0 16
sp_cancel_supplier_order 396 396 0 0.00 10 1180576 117553 0 0
sp_cancel_write_off 9771 9771 0 0.00 1 11189 117 0 0
sp_client_order 21807 21807 0 0.00 4 22567 400 0 0
sp_customer_reserve 474095 474095 0 0.00 0 63924 165 0 0
sp_fill_shopping_cart 33141 33141 0 0.00 1 12481 43 0 0
sp_fill_shopping_cart_clo_res 474081 474081 0 0.00 0 21413 16 0 0
sp_get_clo_for_invoice 5093 5093 0 0.00 0 18739 352 0 0
sp_kill_cost_storno 16130 16130 0 0.00 0 10032 57 0 0
sp_kill_qstorno_ret_qs2qd 1051344 1051344 0 0.00 0 34841 39 0 0
sp_kill_qty_storno 556300 556300 0 0.00 0 34850 83 0 0
sp_lock_selected_doc 21516 21516 0 0.00 0 7414 5 0 0
sp_make_cost_storno 28863 28863 0 0.00 0 14738 56 0 0
sp_multiply_rows_for_pdistr 28862 28862 0 0.00 0 6718 28 0 0
sp_multiply_rows_for_qdistr 21807 21807 0 0.00 0 18383 224 0 0
sp_pay_from_customer 7237 7237 0 0.00 4 8400 100 0 0
sp_pay_to_supplier 6096 6096 0 0.00 5 15270 128 0 0
sp_payment_common 13333 13333 0 0.00 1 6892 18 0 0
sp_qd_handle_on_cancel_clo 1865 1865 0 0.00 0 12863 321 0 0
sp_qd_handle_on_invoice_upd_sts 10328 10328 0 0.00 0 17890 345 0 0
sp_qd_handle_on_reserve_upd_sts 10437 10437 0 0.00 0 16880 44 0 0
sp_reserve_write_off 10437 10437 0 0.00 6 18988 164 0 0
sp_supplier_invoice 5690 5690 0 0.00 6 90593 3480 0 0
sp_supplier_order 5660 5660 0 0.00 6 29740 1395 0 0
srv_aggregate_perf_data 122 122 0 0.00 2610 163691 12999 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 3752 184066 46246 0 0
srv_find_qd_qs_mism 1029713 1029713 0 0.00 0 8774 5 0 0
srv_make_invnt_saldo 497 497 0 0.00 62 79734 3372 0 0
srv_make_money_saldo 770 770 0 0.00 1 14915 517 0 0
srv_recalc_idx_stat 4 4 0 0.00 115742 314398 234461 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5109 5109 0 0.00 0 10406 144 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 4752 91 0 0
t$perf-norm:sp_cancel_client_order 1865 1865 0 0.00 0 3079 11 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1720 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 412 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 110 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 6385 25 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 22287 1369 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 1815 6 0 0
t$perf-norm:sp_client_order 21807 21807 0 0.00 0 9071 16 0 0
t$perf-norm:sp_customer_reserve 1076 1076 0 0.00 0 1929 6 0 0
t$perf-norm:sp_pay_from_customer 7237 7237 0 0.00 0 3439 6 0 0
t$perf-norm:sp_pay_to_supplier 6096 6096 0 0.00 0 5876 8 0 0
t$perf-norm:sp_reserve_write_off 10437 10437 0 0.00 0 7630 9 0 0
t$perf-norm:sp_supplier_invoice 5690 5690 0 0.00 0 5879 15 0 0
t$perf-norm:sp_supplier_order 5660 5660 0 0.00 0 4454 15 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 715 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 97 2 0 0
t$perf-norm:srv_make_invnt_saldo 497 497 0 0.00 0 1111 8 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 629 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 40 12 0 0
v_add_invoice_to_stock 10380 10182 198 1.90 0 2404 14 0 198
v_all_customers 39448 39448 0 0.00 0 19 1 0 0
v_all_suppliers 31624 31624 0 0.00 0 15 1 0 0
v_all_wares 43612 43612 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1290 8 0 0
v_cancel_client_order 3730 3730 0 0.00 0 2793 6 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 2097 6 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1534 8 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1010 5 0 0
v_cancel_supplier_invoice 860 828 32 3.72 0 2444 19 0 32
v_cancel_supplier_order 792 792 0 0.00 0 2387 10 0 0
v_cancel_write_off 2156 2156 0 0.00 0 2738 17 0 0
v_max_id_clo_ord 5657 5657 0 0.00 0 2560 2 0 0
v_max_id_clo_res 1076 1076 0 0.00 0 2652 12 0 0
v_max_id_ord_sup 5677 5677 0 0.00 0 6114 5 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 3396 4 0 0
v_max_non_paid_realizn 3829 3829 0 0.00 0 991 2 0 0
v_min_id_clo_ord 5657 5657 0 0.00 0 4311 6 0 0
v_min_id_clo_res 1076 1076 0 0.00 0 1296 5 0 0
v_min_id_ord_sup 5677 5677 0 0.00 0 8710 8 0 0
v_min_non_paid_invoice 6096 6096 0 0.00 0 6890 14 0 0
v_min_non_paid_realizn 7237 7237 0 0.00 0 4528 10 0 0
v_our_firm 10980 10980 0 0.00 0 16 1 0 0
v_reserve_write_off 20874 20874 0 0.00 0 3254 6 0 0
x_lock_depdocs_on_canc_invoice 10651 10651 0 0.00 0 11862 108 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 4100 293 0 0
x_make_qstorno_1000_1200 5660 5660 0 0.00 2 29546 1319 0 0
x_make_qstorno_1200_2000 5690 5690 0 0.00 3 90557 3310 0 0
x_make_qstorno_2100_3300 474095 474095 0 0.00 0 63923 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-01-2021 10:27:53 to 19-01-2021 10:27:57.

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
07:56:55 5 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
07:57:56 32716 8192000000 9722708224 10018451456 796226912 8.858 100 84 168 1 824658400 8093152 20338336
07:59:33 15183 8192000000 9762392496 10067406848 833596208 9.235 100 85 170 0 866291984 11686800 21008976
08:00:56 15768 8192000000 9788954384 10108682240 865549552 9.556 100 82 162 0 903326240 19108848 18667840
08:02:15 13352 8192000000 9859751312 10185883648 912215616 10.019 100 89 178 0 969491184 37970048 19305520
08:03:33 12327 8192000000 9908455856 10248306688 944893408 10.341 100 81 161 1 1019220560 57077520 17249632
08:04:48 6088 8192000000 9944508576 10290749440 977965456 10.664 100 80 159 1 1059148448 65160336 16022656
08:05:57 5381 8192000000 9991602768 10350145536 1011897472 10.994 100 80 159 0 1103400624 74876128 16627024
08:07:04 2133 8192000000 10007986208 10371375104 1028155328 11.151 100 87 174 0 1116258784 70384992 17718464
08:08:08 3227 8192000000 10016240272 10386808832 1046198528 11.324 100 78 153 2 1131134528 68839712 16096288
08:09:14 40457 8192000000 9995183024 10371780608 1059931472 11.456 100 52 104 1 1108187072 36394512 11861088
08:10:56 7357 8192000000 9986829088 10371272704 1068265920 11.536 100 33 65 1 1101123520 26026272 6831328
08:12:04 4979 8192000000 9988813344 10387705856 1076395216 11.613 100 20 40 0 1105744768 25499056 3850496
08:13:11 7777 8192000000 9991408672 10397278208 1082859184 11.675 100 33 66 0 1108981696 19037040 7085472
08:14:20 48223 8192000000 10009578768 10433867776 1084008704 11.686 100 36 72 2 1114490112 22816576 7664832
08:16:08 4203 8192000000 10030152816 10430259200 1094273856 11.783 100 54 108 3 1144131520 38253824 11603840
08:17:15 7031 8192000000 10002875648 10393985024 1084597616 11.691 100 42 81 1 1114415936 20553840 9264480
08:18:22 3758 8192000000 10006386016 10398785536 1085998912 11.705 100 38 76 0 1127506736 33125792 8382032
08:19:28 60532 8192000000 9959872896 10333782016 1015775552 11.031 95 58 117 0 1042985344 14090992 13118800
08:21:28 7961 8192000000 9929513168 10307588096 958111792 10.471 93 42 84 1 990504272 23304896 9087584
08:22:38 7113 8192000000 9927441760 10299445248 993789120 10.818 98 35 70 1 1017212656 15263088 8160448
08:23:50 11575 8192000000 9955574528 10359087104 1022120192 11.092 99 28 52 4 1062257424 34449824 5687408
08:25:06 47018 8192000000 9945007936 10353917952 1041830624 11.282 100 23 45 2 1067563040 20976912 4755504
08:26:54 30814 8192000000 9992813232 10386202624 1067964112 11.533 100 30 59 1 1092870208 18976208 5929888
08:28:41 15351 8192000000 10040396032 10436677632 1087966768 11.723 100 44 88 1 1147106784 49917456 9222560
08:30:01 9029 8192000000 10092656640 10487390208 1105867776 11.893 100 63 126 0 1201672048 82486992 13317280
08:31:17 38050 8192000000 10081956128 10484293632 1118697024 12.015 100 33 66 1 1199260576 73500640 7062912
08:32:59 23795 8192000000 10166158864 10577141760 1134938448 12.168 100 59 118 1 1268999824 121227936 12833440
08:34:26 10840 8192000000 10174851024 10578878464 1152327600 12.331 100 63 126 1 1287421840 121412384 13681856
08:35:39 19285 8192000000 10197687840 10609414144 1156439920 12.370 100 54 108 0 1306531184 137886464 12204800
08:37:05 3751 8192000000 10266676352 10669240320 1175066816 12.544 100 79 154 1 1364262272 171199824 17995632
08:38:14 11065 8192000000 10320488192 10722516992 1181226048 12.602 100 72 144 1 1413431520 215572080 16633392
08:39:33 5417 8192000000 10346598736 10755084288 1187697280 12.662 100 64 128 0 1443461936 240611552 15153104
08:40:43 8100 8192000000 10383636400 10801569792 1184802608 12.635 100 62 124 0 1488348416 288270336 15275472
08:41:57 5482 8192000000 10379207072 10788810752 1190177296 12.685 100 76 147 2 1474649088 267671936 16799856
08:43:08 12558 8192000000 10357422560 10786054144 1181669216 12.606 100 47 94 1 1466219792 273216032 11334544
08:44:46 4906 8192000000 10412130016 10829111296 1196009312 12.739 100 82 164 0 1514009104 299511808 18487984
08:45:55 15862 8192000000 10440895632 10881097728 1177203776 12.564 100 47 94 3 1541157280 352244096 11709408
08:47:13 4608 8192000000 10412838880 10827042816 1160967776 12.412 100 64 126 0 1508363328 331905088 15490464
08:48:27 10260 8192000000 10385282704 10868240384 1172046192 12.516 100 34 66 3 1529699760 348965488 8688080
08:49:44 27860 8192000000 10395510976 10831515648 1145974048 12.272 99 31 62 1 1500873040 346458112 8440880
08:51:17 13087 8192000000 10382220544 10825744384 1136088592 12.179 98 38 74 1 1490681120 345435872 9156656
08:52:43 12125 8192000000 10368156736 10816643072 1105246736 11.887 98 31 62 1 1476314944 362476336 8591872
08:54:01 41285 8192000000 10212246336 10773200896 1114245568 11.973 99 18 36 2 1450716224 331685232 4785424
08:55:44 26729 8192000000 10142878880 10657714176 1130501024 12.126 99 43 84 2 1318748448 178039360 10208064
08:57:12 61802 8192000000 10044466224 10513453056 1050558368 11.366 93 18 35 0 1086398768 32697152 3143248
08:59:22 108834 8192000000 10051711280 10483240960 1083397600 11.680 96 10 20 0 1122633872 37633696 1602576
09:02:13 45288 8192000000 10105965104 10521600000 1127192528 12.095 100 55 111 0 1187354256 49131728 11030000
09:04:16 41143 8192000000 10082732704 10506108928 1097244800 11.811 97 21 41 1 1138421328 36388160 4788368
09:06:10 59611 8192000000 10073996960 10576351232 1110807472 11.940 99 11 21 1 1201856048 88483008 2565568
09:08:13 184066 8192000000 10085554928 10536689664 1135377056 12.172 100 28 54 0 1192699952 51469840 5853056
09:12:27 49076 8192000000 10093766576 10530217984 1123025392 12.056 99 18 36 1 1169153536 41837520 4290624
09:14:32 38199 8192000000 10121381280 10571812864 1122947616 12.055 99 25 48 5 1197092768 69116688 5028464
09:16:15 54651 8192000000 10146962944 10597715968 1134271360 12.162 99 36 72 0 1245614256 103689328 7653568
09:18:12 36954 8192000000 10148849200 10620133376 1148958464 12.300 100 21 42 1 1267587728 113592128 5037136
09:19:55 59569 8192000000 10119050080 10581086208 1141050752 12.225 99 14 28 1 1190319664 46448720 2820192
09:22:27 53643 8192000000 10109163264 10614751232 1151936768 12.328 100 8 16 0 1201004688 47564784 1503136
09:24:21 71478 8192000000 10112036464 10588413952 1095536608 11.795 95 21 41 1 1182846000 82800352 4509040
09:26:34 47743 8192000000 10090373744 10539298816 1122449008 12.050 98 18 36 0 1167255360 41119168 3687184
09:28:23 44097 8192000000 10072009856 10514841600 1142761024 12.241 100 15 30 0 1188778192 43242832 2774336
09:30:10 39514 8192000000 10146941136 10597675008 1154155600 12.348 100 26 52 0 1252445056 93036448 5253008
09:31:50 67471 8192000000 10057846112 10490884096 1108712720 11.920 97 19 38 0 1176378816 63766544 3899552
09:33:59 55044 8192000000 10107413840 10556788736 1103127152 11.867 97 24 48 1 1181130240 73189936 4813152
09:35:54 129776 8192000000 10054825648 10521296896 1109124128 11.924 99 23 45 1 1153992448 40567968 4300352
09:39:18 38179 8192000000 10030471232 10487279616 1099492544 11.833 99 11 22 0 1135995088 34201984 2300560
09:41:06 57355 8192000000 10071938848 10515976192 1101501328 11.852 99 15 30 2 1162494496 57535808 3457360
09:43:10 72151 8192000000 10083218304 10633646080 1108474544 11.918 99 16 32 1 1235009856 122494432 4040880
09:45:22 89633 8192000000 10093756016 10508365824 1053019744 11.390 93 55 112 3 1114450032 48696064 12734224
09:47:54 95758 8192000000 10062163552 10527375360 1071063392 11.562 96 18 36 0 1132798192 58123280 3611520
09:50:31 54558 8192000000 10097417312 10600112128 1121872432 12.045 100 22 44 2 1212674592 85979264 4822896
09:52:31 81071 8192000000 10095656320 10621300736 1102511760 11.861 98 18 36 0 1182383072 76275808 3595504
09:54:54 41464 8192000000 10098160432 10531680256 1084379712 11.689 97 26 52 2 1145924544 55917216 5627616
09:56:41 73591 8192000000 10029191952 10492108800 1102487840 11.861 99 23 45 1 1151781456 44669024 4624592
09:59:13 34626 8192000000 10056471216 10506395648 1114503904 11.975 99 5 10 0 1147172816 31884336 784576
10:00:56 61607 8192000000 10062058480 10526195712 1118249408 12.010 99 8 16 0 1151491840 31965456 1276976
10:02:58 64847 8192000000 10118043536 10578591744 1131024416 12.131 100 16 32 0 1179157264 44815536 3317312
10:05:19 47944 8192000000 10103378288 10565623808 1117311952 12.002 97 18 36 3 1189190864 68125744 3753168
10:07:16 49369 8192000000 10120606784 10570493952 1103579792 11.872 97 20 40 0 1174712464 67044128 4088544
10:09:09 28617 8192000000 10137546000 10576891904 1141978128 12.234 100 45 89 1 1226203088 74750432 9474528
10:10:38 12714 8192000000 10126279200 10560843776 1147735248 12.288 100 57 116 1 1200420320 40820784 11864288
10:13:48 140340 8192000000 10079288096 10560741376 1152476560 12.333 100 19 38 0 1204635264 48213344 3945360
10:17:13 57705 8192000000 10078298368 10567217152 1139086496 12.207 99 3 6 1 1181012688 41398272 527920
10:19:13 55016 8192000000 10084328160 10521452544 1119446304 12.022 98 24 46 0 1157966976 34071328 4449344
10:21:15 39628 8192000000 10057487968 10496712704 1040437696 11.269 92 29 57 0 1094575360 48113392 6024272
10:22:57 41605 8192000000 10090086256 10534625280 1088961104 11.733 97 22 44 1 1143553264 49598064 4994096
10:24:40 77425 8192000000 10130472592 10577264640 1084210144 11.688 96 17 34 0 1189125392 101023040 3892208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-01-2021 10:27:59 to 19-01-2021 10:28:13.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-01-2021 10:28:14 to 19-01-2021 10:28:16.

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          233166
MON$OLDEST_ACTIVE               235553
MON$OLDEST_SNAPSHOT             235553
MON$NEXT_TRANSACTION            235553
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                       2101696
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             801
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 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.2325 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 ---
:280: 
:281: mscs-test-161.budzdorov.ru    Tue Jan 19 10:26:56 2021
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:283: 
:284: 
:285: mscs-test-161.budzdorov.ru    Tue Jan 19 10:26:57 2021
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:287: 
:288: 
:289: mscs-test-161.budzdorov.ru    Tue Jan 19 10:26:57 2021
:290:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:291: 
:292: 
:293: mscs-test-161.budzdorov.ru    Tue Jan 19 10:27:01 2021
:294:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:295: 
:296: 
:297: mscs-test-161.budzdorov.ru    Tue Jan 19 10:27:06 2021
:298:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:299: 
:300: 
:301: mscs-test-161.budzdorov.ru    Tue Jan 19 10:27:06 2021
:302:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:303: 
:304: 
:305: mscs-test-161.budzdorov.ru    Tue Jan 19 10:27:07 2021
:306:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:307: 
--- end of diff output ---

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

19.01.21 10:28:17. 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-01-19 10:28:19 No severe PSQL-related problems occured
19.01.21 10:28:21. End of report.