Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.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 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 16826320 kB
MemAvailable 31380344 kB
Buffers 0 kB
Cached 14801896 kB
SwapCached 32 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-06-14 10:26:58
DTS_END                         2021-06-14 10:26:58.9640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46058
TRN_ID                          237659
ATT_ID                          661
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: 8799.33 18874 1055920
customer order: creation 174.98 406 20998
customer order: refuse 14.80 333 1776
order to supplier: creation 45.53 1229 5464
order to supplier: removal 3.11 117014 373
invoice (draft): creation 45.62 3411 5474
invoice (draft): removal 48.64 880 5837
invoice accept: apply 41.62 16890 4994
invoice accept: cancel 43.68 775 5242
customer reserve: creation 3821.28 167 458553
customer reserve: removal 4270.82 67 512498
realization accept: apply 83.16 169 9979
realization accept: cancel 77.81 134 9337
payment from customer: creation 58.39 108 7007
payment from customer: removal 5.54 82 665
payment to supplier: creation 49.06 148 5887
payment to supplier: removal 3.54 77 425
service: total inventory turnovers 4.43 2625 532
service: total monetary turnovers 6.26 465 751
service: aggregate perf. data 1.03 14307 123
service: refresh index statistics 0.04 218197 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-06-2021 10:28:05 to 14-06-2021 10:28:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5436
TEST_TIME 2 6394
TEST_TIME 3 5348
TEST_TIME 4 4898
TEST_TIME 5 4812
TEST_TIME 6 5129
TEST_TIME 7 4921
TEST_TIME 8 5129
TEST_TIME 9 5625
TEST_TIME 10 5493
TEST_TIME 11 5724
TEST_TIME 12 5605
TEST_TIME 13 5716
TEST_TIME 14 5757
TEST_TIME 15 5864
TEST_TIME 16 6199
TEST_TIME 17 6312
TEST_TIME 18 7156
TEST_TIME 19 7635
TEST_TIME 20 7754
TEST_TIME 21 7936
TEST_TIME 22 8097
TEST_TIME 23 8540
TEST_TIME 24 8642
TEST_TIME 25 8558
TEST_TIME 26 9662
TEST_TIME 27 11094
TEST_TIME 28 11017
TEST_TIME 29 10876
TEST_TIME 30 10537
TEST_TIME 31 10319
TEST_TIME 32 10368
TEST_TIME 33 10226
TEST_TIME 34 10129
TEST_TIME 35 9969
TEST_TIME 36 9915
TEST_TIME 37 9788
TEST_TIME 38 9553
TEST_TIME 39 9498
TEST_TIME 40 9368
TEST_TIME 41 9293
TEST_TIME 42 9339
TEST_TIME 43 9289
TEST_TIME 44 9313
TEST_TIME 45 9366
TEST_TIME 46 9243
TEST_TIME 47 9310
TEST_TIME 48 9313
TEST_TIME 49 9404
TEST_TIME 50 9437
TEST_TIME 51 9382
TEST_TIME 52 9441
TEST_TIME 53 9499
TEST_TIME 54 9507
TEST_TIME 55 9344
TEST_TIME 56 9240
TEST_TIME 57 9168
TEST_TIME 58 9164
TEST_TIME 59 9098
TEST_TIME 60 9063
TEST_TIME 61 9066
TEST_TIME 62 9041
TEST_TIME 63 9039
TEST_TIME 64 9012
TEST_TIME 65 9058
TEST_TIME 66 9111
TEST_TIME 67 9094
TEST_TIME 68 9112
TEST_TIME 69 9204
TEST_TIME 70 9154
TEST_TIME 71 9220
TEST_TIME 72 9257
TEST_TIME 73 9142
TEST_TIME 74 9070
TEST_TIME 75 8985
TEST_TIME 76 8927
TEST_TIME 77 8886
TEST_TIME 78 8885
TEST_TIME 79 8915
TEST_TIME 80 8935
TEST_TIME 81 8930
TEST_TIME 82 8901
TEST_TIME 83 8894
TEST_TIME 84 8919
TEST_TIME 85 8894
TEST_TIME 86 8914
TEST_TIME 87 8945
TEST_TIME 88 8906
TEST_TIME 89 9012
TEST_TIME 90 8915
TEST_TIME 91 8846
TEST_TIME 92 8816
TEST_TIME 93 8831
TEST_TIME 94 8797
TEST_TIME 95 8819
TEST_TIME 96 8815
TEST_TIME 97 8815
TEST_TIME 98 8841
TEST_TIME 99 8883
TEST_TIME 100 8874
TEST_TIME 101 8866
TEST_TIME 102 8896
TEST_TIME 103 8919
TEST_TIME 104 8961
TEST_TIME 105 8984
TEST_TIME 106 9015
TEST_TIME 107 9023
TEST_TIME 108 8952
TEST_TIME 109 8912
TEST_TIME 110 8862
TEST_TIME 111 8824
TEST_TIME 112 8820
TEST_TIME 113 8824
TEST_TIME 114 8803
TEST_TIME 115 8807
TEST_TIME 116 8830
TEST_TIME 117 8810
TEST_TIME 118 8828
TEST_TIME 119 8813
TEST_TIME 120 8804
TEST_TIME 121 8732

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-06-2021 10:28:09 to 14-06-2021 10:28:12.

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 1481068 1481068 0 0.00 0 18603 7 0 0
doc_list_biud 1481068 1481068 0 0.00 0 86963 29 0 0
sp_add_invoice_to_stock 5106 4994 112 2.19 24 154529 16890 0 112
sp_cancel_adding_invoice 5242 5242 0 0.00 5 88455 775 0 0
sp_cancel_client_order 1776 1776 0 0.00 5 34334 333 0 0
sp_cancel_customer_reserve 512498 512498 0 0.00 1 86965 68 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 6217 83 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 6130 78 0 0
sp_cancel_supplier_invoice 5841 5837 4 0.06 2 16588 880 0 4
sp_cancel_supplier_order 373 373 0 0.00 8 1092724 117015 0 0
sp_cancel_write_off 9337 9337 0 0.00 1 31061 134 0 0
sp_client_order 20998 20998 0 0.00 4 38222 406 0 0
sp_customer_reserve 458553 458553 0 0.00 0 56427 167 0 0
sp_fill_shopping_cart 31934 31934 0 0.00 1 13197 44 0 0
sp_fill_shopping_cart_clo_res 458538 458538 0 0.00 0 34703 19 0 0
sp_get_clo_for_invoice 4979 4979 0 0.00 0 37109 404 0 0
sp_kill_cost_storno 15669 15669 0 0.00 0 17746 63 0 0
sp_kill_qstorno_ret_qs2qd 1040543 1040543 0 0.00 0 86961 36 0 0
sp_kill_qty_storno 550021 550021 0 0.00 0 86962 78 0 0
sp_lock_selected_doc 20722 20722 0 0.00 0 8103 4 0 0
sp_make_cost_storno 27852 27852 0 0.00 0 18601 56 0 0
sp_multiply_rows_for_pdistr 27852 27852 0 0.00 0 7923 29 0 0
sp_multiply_rows_for_qdistr 20998 20998 0 0.00 0 23841 214 0 0
sp_pay_from_customer 7007 7007 0 0.00 4 14302 108 0 0
sp_pay_to_supplier 5887 5887 0 0.00 5 21688 149 0 0
sp_payment_common 12894 12894 0 0.00 1 7914 21 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 34325 294 0 0
sp_qd_handle_on_invoice_upd_sts 10221 10221 0 0.00 0 30250 341 0 0
sp_qd_handle_on_reserve_upd_sts 9979 9979 0 0.00 0 34733 51 0 0
sp_reserve_write_off 9979 9979 0 0.00 7 34747 170 0 0
sp_supplier_invoice 5474 5474 0 0.00 7 95597 3411 0 0
sp_supplier_order 5464 5464 0 0.00 5 28142 1229 0 0
srv_aggregate_perf_data 123 123 0 0.00 2661 154230 14307 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2309 132505 48965 0 0
srv_find_qd_qs_mism 1008557 1008557 0 0.00 0 11999 6 0 0
srv_make_invnt_saldo 532 532 0 0.00 31 106020 2626 0 0
srv_make_money_saldo 751 751 0 0.00 1 13073 466 0 0
srv_recalc_idx_stat 5 5 0 0.00 140401 338449 218197 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4994 4994 0 0.00 0 8931 139 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 4585 78 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 1746 9 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 1157 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 2349 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 827 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 669 14 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 17086 1327 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 2488 9 0 0
t$perf-norm:sp_client_order 20998 20998 0 0.00 0 5589 14 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 2019 7 0 0
t$perf-norm:sp_pay_from_customer 7007 7007 0 0.00 0 4914 7 0 0
t$perf-norm:sp_pay_to_supplier 5887 5887 0 0.00 0 5547 7 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 6619 6 0 0
t$perf-norm:sp_supplier_invoice 5474 5474 0 0.00 0 3369 13 0 0
t$perf-norm:sp_supplier_order 5464 5464 0 0.00 0 7602 12 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 789 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 59 3 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 2795 19 0 0
t$perf-norm:srv_make_money_saldo 751 751 0 0.00 0 2303 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 10182 9958 224 2.19 0 5256 14 0 224
v_all_customers 38138 38138 0 0.00 0 30 1 0 0
v_all_suppliers 30294 30294 0 0.00 0 77 1 0 0
v_all_wares 41996 41996 0 0.00 0 267 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2233 11 0 0
v_cancel_client_order 3552 3552 0 0.00 0 4710 7 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 2657 7 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2692 8 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2125 7 0 0
v_cancel_supplier_invoice 840 832 8 0.95 0 1046 16 0 8
v_cancel_supplier_order 746 746 0 0.00 0 1907 6 0 0
v_cancel_write_off 2010 2010 0 0.00 0 2155 17 0 0
v_max_id_clo_ord 5464 5464 0 0.00 0 504 1 0 0
v_max_id_clo_res 978 978 0 0.00 0 4554 24 0 0
v_max_id_ord_sup 5472 5472 0 0.00 0 4991 9 0 0
v_max_non_paid_invoice 1676 1676 0 0.00 0 463 2 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 2787 3 0 0
v_min_id_clo_ord 5464 5464 0 0.00 0 5721 8 0 0
v_min_id_clo_res 978 978 0 0.00 0 1218 5 0 0
v_min_id_ord_sup 5472 5472 0 0.00 0 5455 8 0 0
v_min_non_paid_invoice 5887 5887 0 0.00 0 7911 19 0 0
v_min_non_paid_realizn 7007 7007 0 0.00 0 7691 13 0 0
v_our_firm 10470 10470 0 0.00 0 13 1 0 0
v_reserve_write_off 19958 19958 0 0.00 0 3403 5 0 0
x_lock_depdocs_on_canc_invoice 10663 10663 0 0.00 0 8822 99 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 7715 308 0 0
x_make_qstorno_1000_1200 5464 5464 0 0.00 2 28134 1160 0 0
x_make_qstorno_1200_2000 5474 5474 0 0.00 3 93159 3232 0 0
x_make_qstorno_2100_3300 458553 458553 0 0.00 0 56426 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-06-2021 10:28:13 to 14-06-2021 10:28:17.

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:57:02 7 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
07:58:03 21667 8192000000 9714874416 10011734016 793415312 8.830 100 88 176 0 822541344 6949088 22176944
07:59:31 22087 8192000000 9749640000 10053783552 824583408 9.145 100 87 173 2 856667600 10847248 21236944
08:01:07 19554 8192000000 9798855600 10115055616 863448112 9.535 100 85 170 1 909197952 25877440 19872400
08:02:32 15952 8192000000 9868891824 10203873280 922678224 10.122 100 77 154 0 973207184 34128832 16400128
08:03:53 7557 8192000000 9919889296 10268536832 966527024 10.553 100 68 136 0 1037302848 56482672 14293152
08:05:03 6101 8192000000 9979202016 10336104448 997292688 10.852 100 84 168 0 1085099520 70660816 17146016
08:06:13 3190 8192000000 9976511216 10352934912 1023712384 11.108 100 65 126 0 1095490720 58423520 13354816
08:07:18 2856 8192000000 10020776256 10392199168 1049430160 11.355 100 83 166 0 1127781248 61305152 17045936
08:08:24 1458 8192000000 10047844512 10422898688 1068400352 11.537 100 93 183 1 1160435376 73096128 18938896
08:09:25 4374 8192000000 9996516688 10386366464 1073720976 11.588 100 42 83 4 1106268272 24392464 8154832
08:10:31 4575 8192000000 10010356048 10407051264 1082655520 11.673 100 32 64 0 1119315008 29950544 6708944
08:11:36 4505 8192000000 9999037296 10397454336 1087594560 11.720 100 48 95 0 1116352960 17059264 11699136
08:12:41 67559 8192000000 9994914000 10397134848 1089605760 11.739 100 26 52 0 1109584080 14523728 5454592
08:14:51 4733 8192000000 10028213472 10431262720 1096412960 11.804 100 45 86 2 1145305360 39370880 9521520
08:15:57 5141 8192000000 10009126128 10405863424 1094629600 11.787 100 54 108 0 1114155600 7201152 12324848
08:17:07 55088 8192000000 9987530240 10390376448 1054020752 11.399 97 28 55 2 1080408656 20266240 6121664
08:19:04 4406 8192000000 9940912000 10325602304 990472448 10.786 94 42 84 1 1008966320 9207040 9286832
08:20:12 4525 8192000000 9936539712 10310262784 992783616 10.809 98 55 108 4 1023753232 18148208 12821408
08:21:17 6737 8192000000 9951882176 10340163584 1015334464 11.027 99 46 93 1 1051213184 25253728 10624992
08:22:26 62123 8192000000 9968120320 10363707392 1032240192 11.190 99 30 58 1 1066033968 27854128 5939648
08:24:28 8040 8192000000 9972080288 10377076736 1058895888 11.446 100 52 102 1 1102980608 31891744 12192976
08:25:39 10854 8192000000 9979535920 10381934592 1069301856 11.545 100 31 62 0 1090303344 13793072 7208416
08:26:50 9957 8192000000 9993626192 10396602368 1083328784 11.679 100 21 42 1 1107531616 20017904 4184928
08:28:08 50663 8192000000 10039897504 10451550208 1111618544 11.948 100 50 100 0 1177716976 56594512 9503920
08:30:05 19312 8192000000 10064940352 10474151936 1130874304 12.130 100 38 74 1 1170132016 31374496 7883216
08:31:28 21417 8192000000 10122995520 10529210368 1148563680 12.296 100 57 113 1 1223902288 63609344 11729264
08:32:53 13443 8192000000 10136780032 10552283136 1151483968 12.323 100 47 94 0 1243737120 82509408 9743744
08:34:14 7883 8192000000 10170786576 10594705408 1163294672 12.434 100 48 96 3 1286020528 111866816 10859040
08:35:27 20319 8192000000 10213452976 10654658560 1175613680 12.549 100 43 86 1 1341613248 156061200 9938368
08:36:59 3867 8192000000 10286376224 10698113024 1190983232 12.693 100 72 135 1 1386907936 180590544 15334160
08:38:07 18597 8192000000 10307376160 10724790272 1198687856 12.764 100 60 118 2 1411508704 199393520 13427328
08:39:32 4670 8192000000 10352269808 10760523776 1174545904 12.539 99 74 148 1 1444320368 253403648 16370816
08:40:43 3380 8192000000 10396788240 10812198912 1193549488 12.716 100 73 143 2 1499539008 289280000 16709520
08:41:54 5177 8192000000 10473032912 10883133440 1193330672 12.714 100 90 180 0 1567119840 352936080 20853088
08:43:04 3617 8192000000 10480836336 10887766016 1178092320 12.572 100 82 162 1 1576563856 379522256 18949280
08:44:13 2309 8192000000 10464534112 10899443712 1168469616 12.483 100 77 152 0 1562865664 376100064 18295984
08:45:19 8479 8192000000 10381176176 10829004800 1131150208 12.132 97 38 76 0 1491738288 351031872 9556208
08:46:35 21161 8192000000 10403682192 10854641664 1134290800 12.162 99 35 70 1 1499028976 355482304 9255872
08:48:02 17450 8192000000 10418499344 10891915264 1144304416 12.256 100 41 82 1 1549711440 395039136 10367888
08:49:23 31974 8192000000 10424509568 10879381504 1139798320 12.214 99 25 50 0 1513147328 366798736 6550272
08:50:56 16845 8192000000 10418155344 10896388096 1113204400 11.963 99 21 42 1 1535447488 416220688 6022400
08:52:15 29770 8192000000 10357356752 10870530048 1114534144 11.975 99 30 60 0 1495758768 373585760 7638864
08:53:46 132505 8192000000 10062569840 10507284480 1080391840 11.651 96 17 34 1 1151473872 66872832 4209200
08:57:25 63384 8192000000 10115188496 10563559424 1108875616 11.922 99 33 62 4 1175994640 59726784 7392240
08:59:29 65363 8192000000 10044149568 10524323840 1079542240 11.643 97 10 20 0 1126400288 44992928 1865120
09:01:35 44277 8192000000 10058448320 10501361664 1112931824 11.960 99 12 24 0 1158177664 42958848 2286992
09:03:20 35602 8192000000 10058726640 10499837952 1123430352 12.059 100 15 30 0 1160535568 33974160 3131056
09:05:03 85439 8192000000 10037522096 10490896384 1100579872 11.843 98 15 30 1 1139054368 35297648 3176848
09:07:30 35819 8192000000 10082771744 10562871296 1122816880 12.054 99 10 20 1 1182615440 57498704 2299856
09:09:14 69065 8192000000 10089133392 10552578048 1127217568 12.095 99 17 34 0 1185133840 54674208 3242064
09:11:23 65076 8192000000 10103349152 10552832000 1119538864 12.023 98 15 30 0 1169813072 47166704 3107504
09:13:30 86114 8192000000 10156490384 10632126464 1155053488 12.357 100 23 46 0 1243904496 84020672 4830336
09:16:22 38021 8192000000 10098353984 10533093376 1127453152 12.097 98 26 52 0 1154879024 22043216 5382656
09:18:02 77203 8192000000 10142918192 10579599360 1142176112 12.236 99 25 50 0 1253058736 105296432 5586192
09:20:24 82434 8192000000 10092573920 10575237120 1155800688 12.364 100 13 26 2 1191889072 33533888 2554496
09:23:09 50641 8192000000 10085104624 10561257472 1159771856 12.401 100 7 14 0 1208597712 47322416 1503440
09:26:17 46576 8192000000 10088002528 10559987712 1144955104 12.262 100 33 64 4 1190585808 38500560 7130144
09:28:12 27714 8192000000 10108499056 10557145088 1153742944 12.345 100 24 48 1 1184260688 25548464 4969280
09:29:45 53456 8192000000 10103212736 10541277184 1085111728 11.696 94 31 62 1 1153410512 61416576 6882208
09:31:39 81805 8192000000 10058422640 10497392640 1056166816 11.420 93 17 33 0 1094455488 35168880 3119792
09:34:11 70708 8192000000 10099015712 10574471168 1104237744 11.878 98 21 42 1 1183125856 74604208 4283904
09:36:26 34895 8192000000 10090741680 10515951616 1082107776 11.668 96 41 82 0 1134774400 44251152 8415472
09:38:08 73925 8192000000 10037872128 10501660672 1082628144 11.673 97 24 48 1 1134339152 46836400 4874608
09:41:44 55713 8192000000 10055736784 10522107904 1111426496 11.946 100 5 10 1 1131934272 19824304 683472
09:43:55 67514 8192000000 10099570576 10595086336 1101748608 11.854 98 18 36 0 1179447184 73868496 3830080
09:46:44 73182 8192000000 10054002144 10504200192 1081056960 11.658 96 19 38 0 1144574736 59842448 3675328
09:49:03 15283 8192000000 10069776272 10489425920 1105831472 11.893 98 58 117 1 1138101648 20463472 11806704
09:50:19 40517 8192000000 10055997056 10481336320 1123441632 12.059 100 23 47 1 1174768096 46803904 4522560
09:52:02 61870 8192000000 10075948048 10508222464 1093927776 11.780 97 34 68 0 1139442288 38559264 6955248
09:54:09 78823 8192000000 10142537520 10600861696 1094616592 11.787 97 36 72 0 1163717440 60864176 8236672
09:56:29 97937 8192000000 10057997360 10511372288 1092372176 11.765 98 16 32 1 1142558864 46898288 3288400
09:59:07 80974 8192000000 10053949152 10515410944 1114128224 11.971 99 10 20 1 1159484544 43431440 1924880
10:01:36 58683 8192000000 10053414976 10504368128 1115470672 11.984 98 19 38 2 1159427952 39673248 4284032
10:03:35 55406 8192000000 10055666608 10527412224 1093119920 11.772 96 17 34 0 1140225008 43713936 3391152
10:05:34 68708 8192000000 10058267808 10515988480 1118357360 12.011 99 7 14 0 1158838096 39206128 1274608
10:07:42 66713 8192000000 10097627680 10582654976 1117418128 12.003 98 26 52 0 1195727808 72668256 5641424
10:09:50 90038 8192000000 10095584176 10590019584 1106264992 11.897 97 22 44 1 1177120944 66387072 4468880
10:12:21 81367 8192000000 10080647280 10529980416 1110950672 11.941 97 13 26 0 1169350288 55451920 2947696
10:14:46 126909 8192000000 10065744384 10533564416 1094318080 11.784 96 15 30 1 1137235936 39390048 3527808
10:18:10 60509 8192000000 10081266160 10595258368 1134693904 12.166 100 9 18 1 1187272016 50625248 1952864
10:20:11 24886 8192000000 10047027184 10487939072 1101687024 11.854 98 27 55 1 1124797808 16784240 6326544
10:21:44 82242 8192000000 10018655392 10470076416 1009408160 10.970 90 21 42 2 1064498912 50784816 4305936
10:24:07 62130 8192000000 10097751552 10548736000 1092128944 11.763 98 25 50 0 1163747104 66233552 5384608
10:26:10 56036 8192000000 9089497072 9387331584 223846768 2.659 20 7 14 0 277195264 51616832 1731664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-06-2021 10:28:19 to 14-06-2021 10:28:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2021 10:28:35 to 14-06-2021 10:28:37.

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          235382
MON$OLDEST_ACTIVE               238010
MON$OLDEST_SNAPSHOT             238010
MON$NEXT_TRANSACTION            238010
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                       2085728
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             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2516 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2516 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2516 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2516 Firebird 4.0/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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Mon Jun 14 10:27:10 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Mon Jun 14 10:27:24 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Mon Jun 14 10:27:31 2021
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
--- end of diff output ---

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

14.06.21 10:28:38. 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-06-14 10:28:40 No severe PSQL-related problems occured
14.06.21 10:28:41. End of report.