Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.08.2021 10:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16203848 kB
MemAvailable 30901252 kB
Buffers 0 kB
Cached 15467828 kB
SwapCached 324 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.1
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-08-30 10:37:07
DTS_END                         2021-08-30 10:37:07.2380
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/43992
TRN_ID                          231337
ATT_ID                          665
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: 8856.78 20354 1062814
customer order: creation 168.76 433 20251
customer order: refuse 14.04 325 1685
order to supplier: creation 43.63 1501 5236
order to supplier: removal 3.05 124821 366
invoice (draft): creation 45.96 3907 5515
invoice (draft): removal 49.97 912 5996
invoice accept: apply 40.56 19287 4867
invoice accept: cancel 45.43 814 5451
customer reserve: creation 3699.68 192 443961
customer reserve: removal 4454.04 67 534485
realization accept: apply 81.21 175 9745
realization accept: cancel 83.77 115 10052
payment from customer: creation 59.38 105 7125
payment from customer: removal 5.35 99 642
payment to supplier: creation 47.84 125 5741
payment to supplier: removal 3.62 104 434
service: total inventory turnovers 3.53 3714 423
service: total monetary turnovers 6.29 581 755
service: aggregate perf. data 0.67 28767 80
service: refresh index statistics 0.03 221032 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-08-2021 10:37:46 to 30-08-2021 10:37:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6883
TEST_TIME 2 7257
TEST_TIME 3 7257
TEST_TIME 4 7452
TEST_TIME 5 6826
TEST_TIME 6 6773
TEST_TIME 7 7114
TEST_TIME 8 7207
TEST_TIME 9 7132
TEST_TIME 10 7729
TEST_TIME 11 7643
TEST_TIME 12 7588
TEST_TIME 13 7518
TEST_TIME 14 7694
TEST_TIME 15 7808
TEST_TIME 16 7764
TEST_TIME 17 8230
TEST_TIME 18 8390
TEST_TIME 19 8976
TEST_TIME 20 9077
TEST_TIME 21 9478
TEST_TIME 22 9885
TEST_TIME 23 10134
TEST_TIME 24 10200
TEST_TIME 25 11160
TEST_TIME 26 11324
TEST_TIME 27 10985
TEST_TIME 28 10910
TEST_TIME 29 10831
TEST_TIME 30 10536
TEST_TIME 31 10518
TEST_TIME 32 10294
TEST_TIME 33 10172
TEST_TIME 34 10024
TEST_TIME 35 9989
TEST_TIME 36 9887
TEST_TIME 37 9801
TEST_TIME 38 9700
TEST_TIME 39 9583
TEST_TIME 40 9383
TEST_TIME 41 9388
TEST_TIME 42 9417
TEST_TIME 43 9313
TEST_TIME 44 9230
TEST_TIME 45 9175
TEST_TIME 46 9107
TEST_TIME 47 9150
TEST_TIME 48 9072
TEST_TIME 49 9021
TEST_TIME 50 8951
TEST_TIME 51 8962
TEST_TIME 52 8945
TEST_TIME 53 9009
TEST_TIME 54 9046
TEST_TIME 55 8972
TEST_TIME 56 8993
TEST_TIME 57 8954
TEST_TIME 58 9008
TEST_TIME 59 9198
TEST_TIME 60 9273
TEST_TIME 61 9237
TEST_TIME 62 9132
TEST_TIME 63 9055
TEST_TIME 64 9103
TEST_TIME 65 9042
TEST_TIME 66 8965
TEST_TIME 67 8885
TEST_TIME 68 8835
TEST_TIME 69 8791
TEST_TIME 70 8767
TEST_TIME 71 8941
TEST_TIME 72 8911
TEST_TIME 73 8994
TEST_TIME 74 8980
TEST_TIME 75 9039
TEST_TIME 76 9061
TEST_TIME 77 9039
TEST_TIME 78 9061
TEST_TIME 79 9125
TEST_TIME 80 9143
TEST_TIME 81 9128
TEST_TIME 82 9024
TEST_TIME 83 9038
TEST_TIME 84 8963
TEST_TIME 85 9002
TEST_TIME 86 8957
TEST_TIME 87 8897
TEST_TIME 88 8868
TEST_TIME 89 8886
TEST_TIME 90 8887
TEST_TIME 91 8857
TEST_TIME 92 8891
TEST_TIME 93 8870
TEST_TIME 94 8846
TEST_TIME 95 8885
TEST_TIME 96 8947
TEST_TIME 97 8977
TEST_TIME 98 8996
TEST_TIME 99 9014
TEST_TIME 100 9048
TEST_TIME 101 9011
TEST_TIME 102 9003
TEST_TIME 103 8996
TEST_TIME 104 8922
TEST_TIME 105 8873
TEST_TIME 106 8881
TEST_TIME 107 8828
TEST_TIME 108 8808
TEST_TIME 109 8784
TEST_TIME 110 8775
TEST_TIME 111 8798
TEST_TIME 112 8839
TEST_TIME 113 8815
TEST_TIME 114 8815
TEST_TIME 115 8815
TEST_TIME 116 8853
TEST_TIME 117 8944
TEST_TIME 118 8952
TEST_TIME 119 8929
TEST_TIME 120 8863

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-08-2021 10:37:50 to 30-08-2021 10:37:53.

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 1473986 1473986 0 0.00 0 20956 7 0 0
doc_list_biud 1473986 1473986 0 0.00 0 60166 31 0 0
sp_add_invoice_to_stock 4964 4867 97 1.95 33 277931 19287 0 97
sp_cancel_adding_invoice 5451 5451 0 0.00 4 79366 815 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 12212 325 0 0
sp_cancel_customer_reserve 534485 534485 0 0.00 1 60168 68 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 6636 100 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 6615 105 0 0
sp_cancel_supplier_invoice 6007 5996 11 0.18 2 27609 913 0 11
sp_cancel_supplier_order 366 366 0 0.00 5 972999 124821 0 0
sp_cancel_write_off 10052 10052 0 0.00 1 11903 116 0 0
sp_client_order 20251 20251 0 0.00 4 44963 433 0 0
sp_customer_reserve 443961 443961 0 0.00 0 55923 193 0 0
sp_fill_shopping_cart 30991 30991 0 0.00 1 23127 49 0 0
sp_fill_shopping_cart_clo_res 443946 443946 0 0.00 0 16480 18 0 0
sp_get_clo_for_invoice 4852 4852 0 0.00 0 24606 413 0 0
sp_kill_cost_storno 16579 16579 0 0.00 0 17799 59 0 0
sp_kill_qstorno_ret_qs2qd 1085384 1085384 0 0.00 0 60162 37 0 0
sp_kill_qty_storno 572632 572632 0 0.00 0 60166 78 0 0
sp_lock_selected_doc 20140 20140 0 0.00 0 6095 4 0 0
sp_make_cost_storno 27463 27463 0 0.00 0 20953 46 0 0
sp_multiply_rows_for_pdistr 27463 27463 0 0.00 0 9638 27 0 0
sp_multiply_rows_for_qdistr 20251 20251 0 0.00 0 30379 218 0 0
sp_pay_from_customer 7125 7125 0 0.00 4 7656 106 0 0
sp_pay_to_supplier 5741 5741 0 0.00 5 19842 125 0 0
sp_payment_common 12866 12866 0 0.00 1 19829 24 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 12202 296 0 0
sp_qd_handle_on_invoice_upd_sts 10303 10303 0 0.00 0 26581 349 0 0
sp_qd_handle_on_reserve_upd_sts 9745 9745 0 0.00 0 10269 60 0 0
sp_reserve_write_off 9745 9745 0 0.00 7 21000 175 0 0
sp_supplier_invoice 5515 5515 0 0.00 6 92743 3908 0 0
sp_supplier_order 5236 5236 0 0.00 6 67856 1501 0 0
srv_aggregate_perf_data 80 80 0 0.00 2846 456323 28768 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 883 138677 48061 0 0
srv_find_qd_qs_mism 1016346 1016346 0 0.00 0 13385 6 0 0
srv_make_invnt_saldo 423 423 0 0.00 66 99960 3714 0 0
srv_make_money_saldo 755 755 0 0.00 1 16961 582 0 0
srv_recalc_idx_stat 4 4 0 0.00 136344 267498 221032 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4867 4867 0 0.00 0 16265 165 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 4890 101 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 3835 17 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 1107 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 1967 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 3098 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 5383 31 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 25482 1501 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 3715 11 0 0
t$perf-norm:sp_client_order 20251 20251 0 0.00 0 17127 18 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 4747 16 0 0
t$perf-norm:sp_pay_from_customer 7125 7125 0 0.00 0 5438 12 0 0
t$perf-norm:sp_pay_to_supplier 5741 5741 0 0.00 0 9435 10 0 0
t$perf-norm:sp_reserve_write_off 9745 9745 0 0.00 0 4535 12 0 0
t$perf-norm:sp_supplier_invoice 5515 5515 0 0.00 0 9660 25 0 0
t$perf-norm:sp_supplier_order 5236 5236 0 0.00 0 2779 14 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 215 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2913 46 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 1626 12 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 11127 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 5 0 0
v_add_invoice_to_stock 9898 9704 194 1.95 0 7607 19 0 194
v_all_customers 37240 37240 0 0.00 0 55 1 0 0
v_all_suppliers 29632 29632 0 0.00 0 16 1 0 0
v_all_wares 40502 40502 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 6323 16 0 0
v_cancel_client_order 3370 3370 0 0.00 0 5207 7 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 1297 5 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 3023 6 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 6607 22 0 0
v_cancel_supplier_invoice 766 744 22 2.87 0 6915 39 0 22
v_cancel_supplier_order 732 732 0 0.00 0 724 4 0 0
v_cancel_write_off 1858 1858 0 0.00 0 3517 16 0 0
v_max_id_clo_ord 5236 5236 0 0.00 0 383 1 0 0
v_max_id_clo_res 966 966 0 0.00 0 1262 10 0 0
v_max_id_ord_sup 5504 5504 0 0.00 0 6962 9 0 0
v_max_non_paid_invoice 1665 1665 0 0.00 0 425 2 0 0
v_max_non_paid_realizn 3684 3684 0 0.00 0 4473 5 0 0
v_min_id_clo_ord 5236 5236 0 0.00 0 7705 10 0 0
v_min_id_clo_res 966 966 0 0.00 0 11418 26 0 0
v_min_id_ord_sup 5504 5504 0 0.00 0 4827 9 0 0
v_min_non_paid_invoice 5741 5741 0 0.00 0 19826 18 0 0
v_min_non_paid_realizn 7125 7125 0 0.00 0 5652 15 0 0
v_our_firm 10144 10144 0 0.00 0 55 1 0 0
v_reserve_write_off 19490 19490 0 0.00 0 6935 6 0 0
x_lock_depdocs_on_canc_invoice 11075 11075 0 0.00 0 16167 118 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 13420 446 0 0
x_make_qstorno_1000_1200 5236 5236 0 0.00 2 67825 1420 0 0
x_make_qstorno_1200_2000 5515 5515 0 0.00 3 86639 3718 0 0
x_make_qstorno_2100_3300 443961 443961 0 0.00 0 55922 173 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 30-08-2021 10:37:54 to 30-08-2021 10:37:59.

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
08:07:11 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 29528 8192000000 9717490992 10012860416 789399744 8.789 100 91 182 0 819656064 7873856 22382464
08:09:46 15034 8192000000 9762497744 10068774912 838524624 9.285 100 88 176 0 871401840 11890688 20986528
08:11:10 7902 8192000000 9792622320 10108579840 860615648 9.506 100 79 158 0 905578864 26586720 18376496
08:12:24 12608 8192000000 9862551904 10193813504 911923872 10.016 100 80 160 1 970192688 40650608 17618208
08:13:40 8902 8192000000 9902992608 10251026432 958601744 10.475 100 78 155 1 1023011872 47647168 16762960
08:14:51 7406 8192000000 9962259792 10314719232 994258128 10.823 100 81 161 1 1069497856 58634784 16604944
08:16:02 2767 8192000000 9987155568 10346192896 1015695360 11.030 100 87 170 2 1099302496 66504560 17102576
08:17:07 6554 8192000000 10006849152 10373869568 1035044304 11.217 100 81 161 0 1121703520 71081136 15578080
08:18:14 2024 8192000000 10028043664 10396151808 1054263312 11.402 100 88 173 5 1130859680 58048496 18547872
08:19:17 5507 8192000000 9997701072 10393890816 1063346608 11.488 100 35 69 0 1126040608 56094144 6599856
08:20:24 5613 8192000000 10014809504 10406248448 1069685872 11.549 100 56 113 1 1117108864 36164800 11258192
08:21:31 11542 8192000000 9987135584 10393378816 1076129552 11.611 100 25 50 2 1106704112 25784480 4790080
08:22:48 29135 8192000000 10030672688 10441183232 1080581968 11.653 100 35 69 3 1113472528 25887872 7002688
08:24:20 3703 8192000000 10012781152 10409193472 1087064800 11.715 100 44 88 0 1117127744 20248624 9814320
08:25:26 7505 8192000000 10003035920 10406445056 1095353344 11.794 100 30 59 1 1121746256 19597696 6795216
08:26:34 8939 8192000000 10019463056 10430398464 1080018912 11.648 98 34 68 2 1121385024 33492832 7873280
08:27:48 30652 8192000000 9970717728 10377154560 1062821696 11.483 98 41 82 0 1097394592 25840320 8732576
08:29:19 3592 8192000000 9976925984 10361085952 1052484560 11.384 99 52 104 0 1084766992 21060624 11221808
08:30:23 4293 8192000000 9933116048 10307842048 983934768 10.722 95 42 85 2 1012928192 19398688 9594736
08:31:30 7948 8192000000 9938303168 10332553216 1009716848 10.973 98 39 74 0 1030991072 12401856 8872368
08:32:38 23896 8192000000 9947499824 10344181760 1010171616 10.977 97 21 42 2 1038127888 24013280 3942992
08:34:04 9136 8192000000 9986651760 10394648576 1051871920 11.379 99 41 81 2 1088003984 27652688 8479376
08:35:13 6560 8192000000 9992400592 10391248896 1075253856 11.602 100 42 83 1 1097778480 12629520 9895104
08:36:22 69255 8192000000 10000610672 10408849408 1087021552 11.714 100 34 68 0 1118142256 23911120 7209584
08:38:44 9079 8192000000 10091047488 10493386752 1112793296 11.959 100 51 100 0 1197540784 74790976 9956512
08:40:01 6292 8192000000 10101976096 10506584064 1126386144 12.087 100 60 121 0 1211633856 72669056 12578656
08:41:11 16591 8192000000 10115002624 10542325760 1132668576 12.147 100 42 82 1 1231305552 90069104 8567872
08:42:36 9832 8192000000 10155427584 10565967872 1149896976 12.309 100 62 122 1 1266442608 104016912 12528720
08:43:51 9315 8192000000 10195967568 10599686144 1134556688 12.164 98 66 132 0 1290681872 142340208 13784976
08:45:08 4854 8192000000 10194019264 10611908608 1163025568 12.432 100 64 127 1 1309503232 132390688 14086976
08:46:22 5673 8192000000 10254780080 10669862912 1170748336 12.504 100 66 131 0 1355339664 169756384 14834944
08:47:35 3528 8192000000 10224144336 10643857408 1167233328 12.471 99 64 128 0 1325524192 144690944 13599920
08:48:42 5133 8192000000 10283013744 10695622656 1186223712 12.648 100 56 112 0 1386636544 187064240 13348592
08:49:51 2648 8192000000 10315161264 10723987456 1159177456 12.396 99 70 139 1 1410937920 235985984 15774480
08:50:59 4306 8192000000 10405006928 10808377344 1178725936 12.578 100 88 167 1 1493455472 296094976 18634560
08:52:20 7066 8192000000 10374456672 10800582656 1162873296 12.430 99 64 128 0 1475300864 296351472 16076096
08:53:35 880 8192000000 10452165024 10849562624 1176359600 12.556 100 97 193 0 1541989872 343674336 21955936
08:54:39 7042 8192000000 10412580352 10833580032 1173505152 12.530 100 54 107 0 1509346160 322605168 13235840
08:55:52 10332 8192000000 10433062176 10853683200 1161695328 12.419 100 50 100 1 1530984608 356267616 13021664
08:57:09 2871 8192000000 10404974480 10826960896 1176911056 12.561 100 72 144 0 1505493792 310509792 18072944
08:58:14 25024 8192000000 10394305280 10845626368 1136194432 12.180 99 36 72 0 1483426688 337552288 9679968
08:59:49 19215 8192000000 10274956960 10738671616 1104078960 11.876 97 21 42 0 1366857632 257355760 5422912
09:01:10 27510 8192000000 10246887536 10680066048 1093602832 11.777 98 20 39 2 1334419440 235515696 5300912
09:02:39 11948 8192000000 10098518272 10518585344 1097161776 11.811 100 29 58 0 1199716960 95515904 7039280
09:03:52 102716 8192000000 10089494320 10556821504 1115520288 11.985 100 36 72 0 1195359168 71819424 8019456
09:06:41 44183 8192000000 10084591440 10560249856 1116575664 11.995 100 18 36 1 1187883744 67486944 3821136
09:08:26 38034 8192000000 10070772048 10585276416 1124227264 12.067 100 10 20 0 1214727488 88783744 1716480
09:10:18 32133 8192000000 10078695312 10550124544 1134767840 12.166 100 16 31 1 1166385536 28455344 3162352
09:11:55 54992 8192000000 10068680640 10533695488 1118427200 12.012 98 33 68 3 1170362816 44208848 7726768
09:14:11 75746 8192000000 10108982144 10572562432 1118837664 12.016 99 19 38 1 1185021600 62243952 3939984
09:16:37 86794 8192000000 10119949552 10566049792 1141291344 12.228 100 30 60 0 1211007888 62692128 7024416
09:19:04 47633 8192000000 10060466128 10518736896 1122343296 12.049 99 16 32 0 1175355632 49190064 3822272
09:21:05 74005 8192000000 10117809728 10591776768 1140167936 12.217 100 27 54 1 1219925952 73703232 6054784
09:23:27 72074 8192000000 10061206240 10558054400 1127064912 12.094 99 14 28 1 1201708896 71214320 3429664
09:25:56 65768 8192000000 10050600608 10497400832 1117903824 12.007 98 10 20 0 1137347312 17470992 1972496
09:28:02 99972 8192000000 10082545648 10559135744 1111199888 11.944 97 19 39 0 1184731360 69310480 4220992
09:30:43 24558 8192000000 10085988976 10548416512 1113977440 11.970 97 40 80 1 1167645936 45272320 8396176
09:32:07 30736 8192000000 10087334384 10526208000 1138283104 12.199 100 28 56 1 1180496400 36717312 5495984
09:33:43 72211 8192000000 10176382384 10616918016 1130517936 12.126 99 32 64 1 1248384112 111141632 6724544
09:35:56 61799 8192000000 10087308656 10555736064 1096676608 11.806 97 11 22 1 1146137568 47251552 2209408
09:37:58 138676 8192000000 10095492352 10534649856 1124133952 12.066 98 23 44 2 1204147344 75273232 4740160
09:41:17 46158 8192000000 10089122512 10590580736 1150931232 12.318 100 10 20 0 1204677472 51721024 2025216
09:43:03 68015 8192000000 10081831488 10536562688 1106038144 11.895 96 14 29 0 1150341840 41643312 2660384
09:45:12 80293 8192000000 10136465040 10610561024 1124480912 12.069 99 15 30 0 1229680528 101838368 3361248
09:47:32 71258 8192000000 10125114656 10629439488 1102105008 11.858 97 21 42 0 1254468832 147009712 5354112
09:49:54 65145 8192000000 10063970976 10533580800 1074707088 11.597 96 17 34 2 1145807744 67128192 3972464
09:52:02 95817 8192000000 10085205648 10565345280 1094569600 11.786 98 20 40 0 1156522352 58049888 3902864
09:55:27 24587 8192000000 10097778208 10548441088 1109303584 11.926 99 37 74 1 1168177536 51600816 7273136
09:56:54 80995 8192000000 10071108160 10553827328 1093695408 11.778 98 14 28 3 1165434544 68846672 2892464
09:59:18 76753 8192000000 10064359728 10541207552 1103500688 11.871 99 25 50 0 1186953776 77920272 5532816
10:01:46 64670 8192000000 10045294304 10538971136 1096507856 11.804 98 21 42 0 1156225552 55426736 4290960
10:03:59 51756 8192000000 10062388400 10529349632 1097435936 11.813 98 9 18 1 1141818688 42398096 1984656
10:06:08 67486 8192000000 10077010208 10553446400 1107641728 11.910 99 15 30 0 1178140672 66798480 3700464
10:08:22 33506 8192000000 10065391520 10507223040 1119605408 12.023 99 38 76 0 1161949104 34207936 8135760
10:09:56 54575 8192000000 10090210480 10540175360 1141841680 12.233 100 16 31 0 1179501888 34907536 2752672
10:11:51 67229 8192000000 10130725136 10627387392 1141797744 12.232 100 17 35 0 1236938304 91511520 3629040
10:14:00 78699 8192000000 10113923632 10579308544 1103913056 11.875 97 18 36 1 1147516928 40319376 3284496
10:16:19 47530 8192000000 10079248816 10514497536 1127719616 12.100 99 34 66 1 1192080624 57291552 7069456
10:18:09 69754 8192000000 10071690048 10522419200 1088390112 11.727 96 24 47 1 1129927120 36916448 4620560
10:20:26 27239 8192000000 10106730192 10543095808 1131470784 12.135 100 50 100 2 1200274544 58453904 10349856
10:21:55 120972 8192000000 10085838496 10573115392 1105793008 11.893 97 35 70 1 1189437056 76587424 7056624
10:24:56 39996 8192000000 10068959920 10559598592 1134912464 12.168 100 9 16 2 1200707280 63688416 2106400
10:26:50 115860 8192000000 10109912432 10591178752 1095870672 11.798 97 18 35 1 1182716720 82825920 4020128
10:29:47 80205 8192000000 10088033952 10537664512 1117124192 12.000 99 30 60 3 1162779392 39310800 6344400
10:32:24 44704 8192000000 10126536160 10566782976 1119599968 12.023 99 18 36 0 1177385728 54195840 3589920
10:34:09 100821 8192000000 10077583712 10592616448 1095801664 11.798 97 8 16 0 1212872336 115651552 1419120
10:36:49 21415 8192000000 9579306736 9944031232 689992176 7.768 62 43 86 0 715689584 16993744 8703664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-08-2021 10:38:00 to 30-08-2021 10:38:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 30-08-2021 10:38:17 to 30-08-2021 10:38:18.

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          232514
MON$OLDEST_ACTIVE               232515
MON$OLDEST_SNAPSHOT             232515
MON$NEXT_TRANSACTION            232515
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                       2034912
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2574 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2574 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2574 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2574 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:139: 
:140: mscs-test-161.budzdorov.ru    Mon Aug 30 10:37:15 2021
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Mon Aug 30 10:37:18 2021
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:146: 
--- end of diff output ---

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

30.08.21 10:38:20. 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-08-30 10:38:22 No severe PSQL-related problems occured
30.08.21 10:38:23. End of report.