Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.02.2021 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16274036 kB
MemAvailable 31132480 kB
Buffers 0 kB
Cached 15432232 kB
SwapCached 3268 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-02-26 07:36:34
DTS_END                         2021-02-26 07:36:34.2690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35418
TRN_ID                          228758
ATT_ID                          602
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: 8894.75 24087 1067370
customer order: creation 164.97 420 19796
customer order: refuse 13.68 356 1641
order to supplier: creation 43.85 1627 5262
order to supplier: removal 3.17 143718 380
invoice (draft): creation 44.27 4058 5312
invoice (draft): removal 49.06 1083 5887
invoice accept: apply 40.92 18106 4910
invoice accept: cancel 44.59 1068 5351
customer reserve: creation 3825.13 173 459015
customer reserve: removal 4379.16 82 525499
realization accept: apply 82.03 186 9843
realization accept: cancel 77.43 146 9292
payment from customer: creation 57.67 110 6920
payment from customer: removal 5.58 91 670
payment to supplier: creation 48.99 128 5879
payment to supplier: removal 3.35 101 402
service: total inventory turnovers 4.03 3992 483
service: total monetary turnovers 6.06 762 727
service: aggregate perf. data 0.81 17346 97
service: refresh index statistics 0.03 288180 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-02-2021 07:39:18 to 26-02-2021 07:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4754
TEST_TIME 2 4944
TEST_TIME 3 3931
TEST_TIME 4 4951
TEST_TIME 5 4944
TEST_TIME 6 4927
TEST_TIME 7 5097
TEST_TIME 8 5336
TEST_TIME 9 5411
TEST_TIME 10 5968
TEST_TIME 11 5885
TEST_TIME 12 5833
TEST_TIME 13 5832
TEST_TIME 14 6248
TEST_TIME 15 6397
TEST_TIME 16 6685
TEST_TIME 17 6761
TEST_TIME 18 7139
TEST_TIME 19 7052
TEST_TIME 20 7070
TEST_TIME 21 7409
TEST_TIME 22 7450
TEST_TIME 23 7740
TEST_TIME 24 7972
TEST_TIME 25 8047
TEST_TIME 26 8000
TEST_TIME 27 8251
TEST_TIME 28 8129
TEST_TIME 29 8168
TEST_TIME 30 8779
TEST_TIME 31 9373
TEST_TIME 32 10173
TEST_TIME 33 10182
TEST_TIME 34 9944
TEST_TIME 35 10266
TEST_TIME 36 10096
TEST_TIME 37 9924
TEST_TIME 38 9882
TEST_TIME 39 9870
TEST_TIME 40 9832
TEST_TIME 41 9792
TEST_TIME 42 9784
TEST_TIME 43 9868
TEST_TIME 44 9769
TEST_TIME 45 9784
TEST_TIME 46 9814
TEST_TIME 47 9675
TEST_TIME 48 9827
TEST_TIME 49 9793
TEST_TIME 50 9959
TEST_TIME 51 9796
TEST_TIME 52 9611
TEST_TIME 53 9458
TEST_TIME 54 9459
TEST_TIME 55 9394
TEST_TIME 56 9365
TEST_TIME 57 9427
TEST_TIME 58 9373
TEST_TIME 59 9290
TEST_TIME 60 9200
TEST_TIME 61 9156
TEST_TIME 62 9125
TEST_TIME 63 9194
TEST_TIME 64 9284
TEST_TIME 65 9253
TEST_TIME 66 9321
TEST_TIME 67 9343
TEST_TIME 68 9363
TEST_TIME 69 9398
TEST_TIME 70 9340
TEST_TIME 71 9451
TEST_TIME 72 9455
TEST_TIME 73 9407
TEST_TIME 74 9389
TEST_TIME 75 9348
TEST_TIME 76 9550
TEST_TIME 77 9454
TEST_TIME 78 9348
TEST_TIME 79 9297
TEST_TIME 80 9261
TEST_TIME 81 9224
TEST_TIME 82 9195
TEST_TIME 83 9133
TEST_TIME 84 9100
TEST_TIME 85 9074
TEST_TIME 86 9051
TEST_TIME 87 9014
TEST_TIME 88 9004
TEST_TIME 89 9007
TEST_TIME 90 9038
TEST_TIME 91 9161
TEST_TIME 92 9159
TEST_TIME 93 9186
TEST_TIME 94 9188
TEST_TIME 95 9207
TEST_TIME 96 9198
TEST_TIME 97 9270
TEST_TIME 98 9298
TEST_TIME 99 9208
TEST_TIME 100 9152
TEST_TIME 101 9103
TEST_TIME 102 9046
TEST_TIME 103 9027
TEST_TIME 104 9003
TEST_TIME 105 8982
TEST_TIME 106 8978
TEST_TIME 107 8944
TEST_TIME 108 8920
TEST_TIME 109 8927
TEST_TIME 110 8918
TEST_TIME 111 8951
TEST_TIME 112 8940
TEST_TIME 113 8980
TEST_TIME 114 8942
TEST_TIME 115 9087
TEST_TIME 116 9075
TEST_TIME 117 9039
TEST_TIME 118 8982
TEST_TIME 119 8947
TEST_TIME 120 8897

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-02-2021 07:39:21 to 26-02-2021 07:39:25.

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 1488873 1488873 0 0.00 0 15562 7 0 0
doc_list_biud 1488870 1488870 0 0.00 0 31585 36 0 0
sp_add_invoice_to_stock 5004 4910 94 1.87 37 184923 18106 0 94
sp_cancel_adding_invoice 5351 5351 0 0.00 6 66711 1069 0 0
sp_cancel_client_order 1641 1641 0 0.00 4 10880 356 0 0
sp_cancel_customer_reserve 525499 525499 0 0.00 1 20222 82 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 7588 91 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 5982 102 0 0
sp_cancel_supplier_invoice 5890 5887 3 0.05 2 31589 1084 0 3
sp_cancel_supplier_order 380 380 0 0.00 9 1277746 143718 0 0
sp_cancel_write_off 9292 9292 0 0.00 1 12733 146 0 0
sp_client_order 19796 19796 0 0.00 4 22563 420 0 0
sp_customer_reserve 459015 459015 0 0.00 0 91092 174 0 0
sp_fill_shopping_cart 30351 30351 0 0.00 1 31339 49 0 0
sp_fill_shopping_cart_clo_res 459012 459012 0 0.00 0 19651 18 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 24817 405 0 0
sp_kill_cost_storno 15715 15715 0 0.00 0 9309 75 0 0
sp_kill_qstorno_ret_qs2qd 1066557 1066557 0 0.00 0 31584 44 0 0
sp_kill_qty_storno 562796 562796 0 0.00 0 31584 95 0 0
sp_lock_selected_doc 20347 20347 0 0.00 0 10877 4 0 0
sp_make_cost_storno 27546 27546 0 0.00 0 15552 58 0 0
sp_multiply_rows_for_pdistr 27546 27546 0 0.00 0 9672 34 0 0
sp_multiply_rows_for_qdistr 19796 19796 0 0.00 0 15634 207 0 0
sp_pay_from_customer 6920 6920 0 0.00 4 11475 110 0 0
sp_pay_to_supplier 5879 5879 0 0.00 5 12748 128 0 0
sp_payment_common 12799 12799 0 0.00 1 8105 18 0 0
sp_qd_handle_on_cancel_clo 1641 1641 0 0.00 0 10872 323 0 0
sp_qd_handle_on_invoice_upd_sts 10255 10255 0 0.00 0 17715 477 0 0
sp_qd_handle_on_reserve_upd_sts 9842 9842 0 0.00 0 12580 56 0 0
sp_reserve_write_off 9843 9843 0 0.00 7 14676 187 0 0
sp_supplier_invoice 5312 5312 0 0.00 9 122840 4059 0 0
sp_supplier_order 5262 5262 0 0.00 5 45920 1628 0 0
srv_aggregate_perf_data 97 97 0 0.00 2650 209781 17346 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1386 128566 45581 0 0
srv_find_qd_qs_mism 1018908 1018908 0 0.00 0 14630 6 0 0
srv_make_invnt_saldo 483 483 0 0.00 45 147983 3992 0 0
srv_make_money_saldo 727 727 0 0.00 1 39713 762 0 0
srv_recalc_idx_stat 4 4 0 0.00 142391 380256 288180 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4910 4910 0 0.00 0 13246 198 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 2107 90 0 0
t$perf-norm:sp_cancel_client_order 1641 1641 0 0.00 0 2618 14 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 4800 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 550 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 660 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2108 29 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 24562 2039 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 3049 9 0 0
t$perf-norm:sp_client_order 19796 19796 0 0.00 0 8491 24 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 5566 19 0 0
t$perf-norm:sp_pay_from_customer 6920 6920 0 0.00 0 6842 13 0 0
t$perf-norm:sp_pay_to_supplier 5879 5879 0 0.00 0 6921 12 0 0
t$perf-norm:sp_reserve_write_off 9843 9843 0 0.00 0 6230 13 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 5330 24 0 0
t$perf-norm:sp_supplier_order 5262 5262 0 0.00 0 6735 24 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 545 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 41 1 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 750 10 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 404 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9996 9808 188 1.88 0 6482 16 0 188
v_all_customers 36322 36322 0 0.00 0 141 1 0 0
v_all_suppliers 29754 29754 0 0.00 0 18 1 0 0
v_all_wares 39592 39592 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1564 7 0 0
v_cancel_client_order 3282 3282 0 0.00 0 4106 7 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2204 7 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 2392 7 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 335 4 0 0
v_cancel_supplier_invoice 826 820 6 0.72 0 498 12 0 6
v_cancel_supplier_order 760 760 0 0.00 0 357 4 0 0
v_cancel_write_off 1984 1984 0 0.00 0 1358 15 0 0
v_max_id_clo_ord 5252 5252 0 0.00 0 328 1 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 4583 14 0 0
v_max_id_ord_sup 5303 5303 0 0.00 0 17737 15 0 0
v_max_non_paid_invoice 1557 1557 0 0.00 0 249 2 0 0
v_max_non_paid_realizn 3595 3595 0 0.00 0 2320 3 0 0
v_min_id_clo_ord 5252 5252 0 0.00 0 2280 7 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 7666 12 0 0
v_min_id_ord_sup 5303 5303 0 0.00 0 8366 7 0 0
v_min_non_paid_invoice 5879 5879 0 0.00 0 8100 13 0 0
v_min_non_paid_realizn 6920 6920 0 0.00 0 6620 12 0 0
v_our_firm 9920 9920 0 0.00 0 33 1 0 0
v_reserve_write_off 19684 19684 0 0.00 0 4470 6 0 0
x_lock_depdocs_on_canc_invoice 10828 10828 0 0.00 0 23795 135 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 3601 358 0 0
x_make_qstorno_1000_1200 5262 5262 0 0.00 2 45911 1539 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 4 111855 3869 0 0
x_make_qstorno_2100_3300 459015 459015 0 0.00 0 91091 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-02-2021 07:39:26 to 26-02-2021 07:39:30.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:06:37 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:07:39 29288 8192000000 9738764784 10037485568 813458432 9.032 100 89 177 1 842229616 7705088 21066096
05:09:12 15489 8192000000 9766616352 10073243648 842565424 9.326 100 87 174 0 875436976 12384336 20487216
05:10:42 19542 8192000000 9806795888 10121310208 868607600 9.586 100 86 172 0 915041808 26410368 20023840
05:12:15 15155 8192000000 9866036480 10200764416 924406176 10.140 100 75 149 0 984449808 43801232 16242400
05:13:35 11528 8192000000 9930128224 10275467264 958851040 10.478 100 76 152 0 1038071248 63706896 15513312
05:14:49 3707 8192000000 9970153984 10324430848 1000222288 10.881 100 78 154 1 1084239104 67756208 16260608
05:15:55 3852 8192000000 9984393248 10352992256 1025401872 11.124 100 73 146 1 1097678080 57526704 14749504
05:17:00 2407 8192000000 10025309024 10397569024 1058636128 11.443 100 88 175 0 1141027168 64445200 17945840
05:18:04 6793 8192000000 10032957376 10416062464 1069553376 11.548 100 66 128 2 1151340000 69278448 12508176
05:19:11 6414 8192000000 9999630688 10385047552 1072839328 11.579 100 41 82 2 1118189200 35968144 9381728
05:20:18 8809 8192000000 10000778608 10396336128 1081337856 11.660 100 32 64 2 1110543536 22099904 7105776
05:21:31 10703 8192000000 9996434272 10403966976 1088223600 11.726 100 25 49 3 1108125008 14456560 5444848
05:22:42 60552 8192000000 10006110288 10423377920 1092174384 11.763 100 24 47 0 1116695168 19508368 5012416
05:24:43 11401 8192000000 10025848240 10422648832 1100365456 11.841 100 29 58 2 1124897680 18310592 6221632
05:25:55 13269 8192000000 10003408592 10422341632 1100345584 11.841 100 17 34 2 1128812912 24983504 3483824
05:27:12 8793 8192000000 9995218288 10399686656 1050751504 11.368 97 22 44 4 1069506944 14075696 4679744
05:28:22 56912 8192000000 9946686448 10355720192 979189936 10.676 92 28 55 1 999465808 14818032 5457840
05:30:20 7900 8192000000 9946355024 10337103872 1009434704 10.970 97 37 74 2 1039274912 21259008 8581200
05:31:33 7765 8192000000 9949385664 10333425664 978052096 10.665 95 48 96 1 1012905488 24190944 10662448
05:32:45 4070 8192000000 9956451920 10345246720 1018932800 11.062 99 41 81 1 1048643744 20917968 8792976
05:33:49 58139 8192000000 9965388080 10369314816 1041389200 11.278 99 33 63 3 1061894560 12783600 7721760
05:35:48 3787 8192000000 9956522256 10354577408 1058113760 11.438 100 48 95 1 1087169728 18233696 10822272
05:36:57 14450 8192000000 10012545728 10404691968 1087046624 11.715 100 45 86 1 1114456528 19192176 8217728
05:38:16 14744 8192000000 10054633104 10449473536 1102780144 11.864 100 59 115 1 1168114208 52356832 12977232
05:39:47 41209 8192000000 10106373936 10520047616 1120456384 12.031 100 42 83 1 1224845824 95308768 9080672
05:41:35 42843 8192000000 10130288128 10537762816 1132755504 12.147 100 43 84 1 1234393632 92286272 9351856
05:43:31 41935 8192000000 10169219136 10587754496 1136731792 12.185 100 51 101 1 1254100208 105137440 12230976
05:45:20 11216 8192000000 10217353776 10632175616 1156332320 12.369 100 63 126 1 1329331056 159439120 13559616
05:46:44 11877 8192000000 10223678624 10668761088 1165403776 12.454 100 50 100 1 1336925376 160565456 10956144
05:48:02 3515 8192000000 10271224224 10684461056 1178203568 12.573 100 64 127 0 1381701888 188694352 14803968
05:49:08 8340 8192000000 10310424048 10723508224 1184012944 12.628 100 65 128 1 1409874128 210284816 15576368
05:50:21 1914 8192000000 10333009984 10754711552 1173689376 12.531 100 79 157 0 1426147104 234764272 17693456
05:51:29 3682 8192000000 10375443216 10783715328 1182037408 12.609 100 84 167 3 1468870080 267069344 19763328
05:52:38 10512 8192000000 10310268080 10734669824 1165857424 12.458 100 51 102 0 1423246976 245296608 12092944
05:54:00 1385 8192000000 10333140400 10760122368 1171807216 12.514 100 93 187 2 1444466480 252392880 20266384
05:55:05 2417 8192000000 10348593696 10759458816 1163766592 12.439 100 73 147 0 1443810320 263289328 16754400
05:56:15 2075 8192000000 10403822304 10808774656 1162271456 12.425 100 88 176 3 1501681136 319506640 19903040
05:57:24 2772 8192000000 10398941760 10816028672 1166543776 12.465 100 88 174 2 1489960240 303012400 20404064
05:58:34 1612 8192000000 10449121376 10848137216 1174951856 12.543 100 91 179 1 1545589616 348664048 21973712
05:59:41 1676 8192000000 10466715552 10872057856 1161591216 12.418 100 82 164 2 1560327312 379106272 19629824
06:00:58 4513 8192000000 10438448560 10847768576 1158331632 12.388 100 60 121 2 1525509008 352427104 14750272
06:02:10 10383 8192000000 10466437312 10887942144 1147861888 12.289 99 49 98 0 1558017600 397148368 13007344
06:03:27 11055 8192000000 10457230288 10889146368 1151213232 12.321 100 44 87 1 1559171440 396722304 11235904
06:05:02 6805 8192000000 10498248256 10938466304 1140798192 12.223 100 45 90 1 1602083152 449549584 11735376
06:06:12 10984 8192000000 10470341472 10951692288 1128191408 12.104 100 35 67 3 1568548400 430832320 9524672
06:07:31 15376 8192000000 10309447200 10744356864 1139207632 12.208 100 56 112 0 1392589344 240128832 13252880
06:08:51 28344 8192000000 10114927168 10567606272 1128787856 12.110 99 23 47 1 1215999424 82141520 5070048
06:10:44 47884 8192000000 10102662048 10606727168 1121649952 12.043 99 10 20 0 1196836720 73167536 2019232
06:12:32 53788 8192000000 10081212960 10520338432 1121509456 12.041 98 36 71 1 1142165968 13270128 7386384
06:14:28 97825 8192000000 10092867104 10572914688 1096571040 11.805 97 19 38 0 1178356496 77693744 4091712
06:17:45 59784 8192000000 10112778288 10614558720 1122348944 12.049 99 24 48 1 1236447840 108365504 5733392
06:19:56 70519 8192000000 10079059424 10534637568 1115650704 11.986 98 10 18 3 1144573808 27345968 1577136
06:22:27 101399 8192000000 10137610080 10603098112 1119111504 12.019 98 6 12 0 1190875520 70276192 1487824
06:25:14 128565 8192000000 10087514960 10589331456 1128611456 12.108 99 8 16 0 1190557568 60128032 1818080
06:28:29 70867 8192000000 10077110640 10510770176 1136232416 12.180 99 46 92 1 1161677216 15496384 9948416
06:30:45 42756 8192000000 10113490368 10542313472 1128906672 12.111 99 22 44 0 1196016320 62254288 4855360
06:32:30 39047 8192000000 10101804960 10575212544 1140434784 12.220 100 37 74 1 1199066416 50942048 7689584
06:34:17 44136 8192000000 10079913664 10530856960 1104056032 11.876 97 14 28 0 1154849632 47987104 2806496
06:36:06 49266 8192000000 10131655120 10579939328 1128656320 12.109 99 11 22 0 1208667056 77825264 2185472
06:37:58 120068 8192000000 10120872064 10623463424 1100271920 11.840 96 19 39 0 1187701920 83251728 4178272
06:40:58 34300 8192000000 10088762272 10525986816 1114355600 11.974 98 30 60 1 1149528816 29544160 5629056
06:42:35 63788 8192000000 10075126992 10551181312 1113362448 11.964 97 15 30 0 1207680624 91088976 3229200
06:44:46 70415 8192000000 10075209712 10537279488 1134013488 12.159 99 21 42 2 1188662560 50409056 4240016
06:46:57 102490 8192000000 10079328816 10566885376 1077483712 11.623 94 16 32 0 1148555488 67650944 3420832
06:49:47 26056 8192000000 10091429920 10517585920 1110219104 11.934 98 37 74 0 1163826464 45780592 7826768
06:51:18 74725 8192000000 10073450528 10625466368 1105143008 11.886 98 21 42 0 1250363920 140920944 4299968
06:53:42 97112 8192000000 10042016800 10481655808 1091275888 11.755 97 25 50 0 1120007776 23238544 5493344
06:56:25 64501 8192000000 10060040352 10514128896 1119871296 12.026 99 8 16 0 1160409792 38846576 1691920
06:59:32 74046 8192000000 10050777328 10520567808 1127391152 12.097 99 16 32 0 1169285856 38447376 3447328
07:01:47 47405 8192000000 10063294480 10514640896 1125290688 12.077 100 21 42 0 1168695440 38633008 4771744
07:03:40 54322 8192000000 10112938096 10569621504 1121147536 12.038 99 23 47 0 1201074400 74750112 5176752
07:05:49 67700 8192000000 10146809824 10594770944 1118545360 12.013 98 29 58 0 1225789056 101104672 6139024
07:08:20 55107 8192000000 10061957536 10533122048 1113823264 11.969 98 12 24 0 1182666560 66553136 2290160
07:10:16 43214 8192000000 10090127536 10516283392 1128889152 12.111 99 25 50 1 1166292240 32110336 5292752
07:12:00 47956 8192000000 10104818272 10542256128 1097270784 11.812 96 31 62 0 1146226272 42679280 6276208
07:13:52 125637 8192000000 10072651856 10604273664 1112460864 11.956 98 13 25 1 1170043200 55000432 2581904
07:16:58 45625 8192000000 10064777024 10528944128 1134097152 12.160 100 8 16 0 1164275168 28546784 1631232
07:18:44 64379 8192000000 10051556896 10542383104 1126393904 12.087 99 5 10 0 1176500368 49173296 933168
07:20:49 67371 8192000000 10049850592 10516541440 1113855216 11.969 98 11 22 0 1158061248 41088352 3117680
07:23:07 38106 8192000000 10087245136 10534883328 1121383392 12.040 98 22 44 1 1152469216 26821200 4264624
07:24:49 74152 8192000000 10113265344 10576154624 1106601216 11.900 97 27 54 0 1195772816 83041664 6129936
07:27:04 67893 8192000000 10097661744 10582605824 1106718144 11.901 97 12 24 1 1192198880 82716720 2764016
07:29:12 82366 8192000000 10134647184 10598162432 1116581024 11.995 98 22 44 0 1210879296 89539152 4759120
07:31:37 59289 8192000000 10072446112 10514890752 1116900000 11.998 99 14 28 1 1171601008 51830992 2870016
07:33:43 68877 8192000000 10049355392 10520838144 1128360912 12.106 100 11 22 0 1158248800 27556816 2331072
07:36:03 68714 8192000000 9745355696 10167779328 849925568 9.399 76 4 8 0 883630736 33056624 648544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-02-2021 07:39:32 to 26-02-2021 07:39:47.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 26-02-2021 07:39:48 to 26-02-2021 07:39:49.

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          229681
MON$OLDEST_ACTIVE               230128
MON$OLDEST_SNAPSHOT             230128
MON$NEXT_TRANSACTION            230128
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                       2125952
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2375 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2375 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2375 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.2375 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Feb 26 07:37:36 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Feb 26 07:37:36 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

26.02.21 07:39:51. 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-02-26 07:39:53 No severe PSQL-related problems occured
26.02.21 07:39:54. End of report.