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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16429792 kB
MemAvailable 31175568 kB
Buffers 20 kB
Cached 15160616 kB
SwapCached 4876 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-03 04:26:57
DTS_END                         2021-02-03 04:26:56.9920
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/43444
TRN_ID                          229820
ATT_ID                          686
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: 9195.12 25004 1103414
customer order: creation 159.95 408 19194
customer order: refuse 14.01 325 1681
order to supplier: creation 43.47 1348 5216
order to supplier: removal 3.58 121982 430
invoice (draft): creation 43.79 3371 5255
invoice (draft): removal 54.44 866 6533
invoice accept: apply 39.38 17229 4726
invoice accept: cancel 48.98 823 5877
customer reserve: creation 3670.96 166 440515
customer reserve: removal 4835.27 73 580232
realization accept: apply 78.65 213 9438
realization accept: cancel 80.72 132 9687
payment from customer: creation 56.48 128 6778
payment from customer: removal 5.30 72 636
payment to supplier: creation 45.96 162 5515
payment to supplier: removal 3.54 139 425
service: total inventory turnovers 3.54 3395 425
service: total monetary turnovers 6.16 451 739
service: aggregate perf. data 0.89 15037 107
service: refresh index statistics 0.04 333762 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-02-2021 04:27:33 to 03-02-2021 04:27:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4299
TEST_TIME 2 3584
TEST_TIME 3 3509
TEST_TIME 4 3085
TEST_TIME 5 3028
TEST_TIME 6 3103
TEST_TIME 7 3602
TEST_TIME 8 3747
TEST_TIME 9 3921
TEST_TIME 10 4088
TEST_TIME 11 4511
TEST_TIME 12 4983
TEST_TIME 13 5110
TEST_TIME 14 5271
TEST_TIME 15 5423
TEST_TIME 16 5947
TEST_TIME 17 6171
TEST_TIME 18 6877
TEST_TIME 19 7269
TEST_TIME 20 7292
TEST_TIME 21 7492
TEST_TIME 22 8057
TEST_TIME 23 8377
TEST_TIME 24 8334
TEST_TIME 25 8422
TEST_TIME 26 8789
TEST_TIME 27 9627
TEST_TIME 28 11087
TEST_TIME 29 10944
TEST_TIME 30 10757
TEST_TIME 31 10481
TEST_TIME 32 10217
TEST_TIME 33 10154
TEST_TIME 34 9970
TEST_TIME 35 10010
TEST_TIME 36 9905
TEST_TIME 37 9952
TEST_TIME 38 9777
TEST_TIME 39 9853
TEST_TIME 40 9975
TEST_TIME 41 9991
TEST_TIME 42 9915
TEST_TIME 43 9881
TEST_TIME 44 10029
TEST_TIME 45 9935
TEST_TIME 46 10014
TEST_TIME 47 10063
TEST_TIME 48 10081
TEST_TIME 49 10025
TEST_TIME 50 10038
TEST_TIME 51 9958
TEST_TIME 52 9876
TEST_TIME 53 9799
TEST_TIME 54 9680
TEST_TIME 55 9611
TEST_TIME 56 9587
TEST_TIME 57 9528
TEST_TIME 58 9504
TEST_TIME 59 9460
TEST_TIME 60 9479
TEST_TIME 61 9460
TEST_TIME 62 9395
TEST_TIME 63 9337
TEST_TIME 64 9290
TEST_TIME 65 9524
TEST_TIME 66 9526
TEST_TIME 67 9811
TEST_TIME 68 9870
TEST_TIME 69 9745
TEST_TIME 70 9714
TEST_TIME 71 9681
TEST_TIME 72 9606
TEST_TIME 73 9609
TEST_TIME 74 9599
TEST_TIME 75 9638
TEST_TIME 76 9670
TEST_TIME 77 9606
TEST_TIME 78 9628
TEST_TIME 79 9620
TEST_TIME 80 9558
TEST_TIME 81 9648
TEST_TIME 82 9601
TEST_TIME 83 9672
TEST_TIME 84 9578
TEST_TIME 85 9467
TEST_TIME 86 9418
TEST_TIME 87 9360
TEST_TIME 88 9299
TEST_TIME 89 9232
TEST_TIME 90 9220
TEST_TIME 91 9198
TEST_TIME 92 9188
TEST_TIME 93 9170
TEST_TIME 94 9136
TEST_TIME 95 9111
TEST_TIME 96 9196
TEST_TIME 97 9184
TEST_TIME 98 9224
TEST_TIME 99 9240
TEST_TIME 100 9244
TEST_TIME 101 9236
TEST_TIME 102 9255
TEST_TIME 103 9285
TEST_TIME 104 9243
TEST_TIME 105 9169
TEST_TIME 106 9134
TEST_TIME 107 9144
TEST_TIME 108 9108
TEST_TIME 109 9057
TEST_TIME 110 9058
TEST_TIME 111 9030
TEST_TIME 112 9033
TEST_TIME 113 8993
TEST_TIME 114 9034
TEST_TIME 115 8996
TEST_TIME 116 8986
TEST_TIME 117 9042
TEST_TIME 118 9171
TEST_TIME 119 9209
TEST_TIME 120 9199

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-02-2021 04:27:36 to 03-02-2021 04:27:40.

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 1511534 1511534 0 0.00 0 22007 7 0 0
doc_list_biud 1511534 1511534 0 0.00 0 33022 34 0 0
sp_add_invoice_to_stock 4854 4726 128 2.63 40 208464 17229 0 128
sp_cancel_adding_invoice 5877 5877 0 0.00 6 83667 824 0 0
sp_cancel_client_order 1681 1681 0 0.00 4 18129 326 0 0
sp_cancel_customer_reserve 580232 580232 0 0.00 1 24949 73 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 2950 73 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 16197 140 0 0
sp_cancel_supplier_invoice 6546 6533 13 0.19 2 18485 866 0 13
sp_cancel_supplier_order 430 430 0 0.00 10 1137929 121982 0 0
sp_cancel_write_off 9687 9687 0 0.00 1 25636 133 0 0
sp_client_order 19194 19194 0 0.00 4 18145 408 0 0
sp_customer_reserve 440515 440515 0 0.00 0 74025 166 0 0
sp_fill_shopping_cart 29643 29643 0 0.00 1 18005 47 0 0
sp_fill_shopping_cart_clo_res 440506 440506 0 0.00 0 17585 20 0 0
sp_get_clo_for_invoice 4717 4717 0 0.00 0 34194 447 0 0
sp_kill_cost_storno 16625 16625 0 0.00 0 16192 57 0 0
sp_kill_qstorno_ret_qs2qd 1177114 1177114 0 0.00 0 25625 39 0 0
sp_kill_qty_storno 618595 618595 0 0.00 0 33021 83 0 0
sp_lock_selected_doc 19670 19670 0 0.00 0 11047 7 0 0
sp_make_cost_storno 26448 26448 0 0.00 0 18088 73 0 0
sp_multiply_rows_for_pdistr 26448 26448 0 0.00 0 17946 35 0 0
sp_multiply_rows_for_qdistr 19194 19194 0 0.00 0 13247 244 0 0
sp_pay_from_customer 6778 6778 0 0.00 4 17406 129 0 0
sp_pay_to_supplier 5515 5515 0 0.00 5 18110 162 0 0
sp_payment_common 12293 12293 0 0.00 1 14856 37 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 0 18049 306 0 0
sp_qd_handle_on_invoice_upd_sts 10594 10594 0 0.00 0 33021 372 0 0
sp_qd_handle_on_reserve_upd_sts 9438 9438 0 0.00 0 17912 54 0 0
sp_reserve_write_off 9438 9438 0 0.00 7 22044 214 0 0
sp_supplier_invoice 5255 5255 0 0.00 7 75546 3372 0 0
sp_supplier_order 5216 5216 0 0.00 6 31640 1348 0 0
srv_aggregate_perf_data 107 107 0 0.00 2747 114187 15037 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1350 132070 46879 0 0
srv_find_qd_qs_mism 1058398 1058398 0 0.00 0 18411 5 0 0
srv_make_invnt_saldo 425 425 0 0.00 56 116102 3395 0 0
srv_make_money_saldo 739 739 0 0.00 1 16165 451 0 0
srv_recalc_idx_stat 5 5 0 0.00 199809 470166 333763 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4727 4727 0 0.00 0 18131 167 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 2694 92 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 5991 15 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 1000 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 660 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 672 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 2173 20 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 21146 1402 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 5642 13 0 0
t$perf-norm:sp_client_order 19194 19194 0 0.00 0 6034 17 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 830 3 0 0
t$perf-norm:sp_pay_from_customer 6778 6778 0 0.00 0 4647 8 0 0
t$perf-norm:sp_pay_to_supplier 5515 5515 0 0.00 0 3241 8 0 0
t$perf-norm:sp_reserve_write_off 9438 9438 0 0.00 0 6372 8 0 0
t$perf-norm:sp_supplier_invoice 5255 5255 0 0.00 0 6414 17 0 0
t$perf-norm:sp_supplier_order 5216 5216 0 0.00 0 5958 20 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 218 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 287 3 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 209 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9690 9434 256 2.64 0 4451 17 0 256
v_all_customers 35464 35464 0 0.00 0 255 1 0 0
v_all_suppliers 29032 29032 0 0.00 0 257 1 0 0
v_all_wares 38388 38388 0 0.00 0 255 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 196 6 0 0
v_cancel_client_order 3362 3362 0 0.00 0 1165 4 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 2703 7 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 853 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 764 4 0 0
v_cancel_supplier_invoice 786 760 26 3.30 0 310 14 0 26
v_cancel_supplier_order 860 860 0 0.00 0 1935 5 0 0
v_cancel_write_off 1892 1892 0 0.00 0 1492 15 0 0
v_max_id_clo_ord 5214 5214 0 0.00 0 1714 2 0 0
v_max_id_clo_res 991 991 0 0.00 0 5864 21 0 0
v_max_id_ord_sup 5235 5235 0 0.00 0 2830 4 0 0
v_max_non_paid_invoice 1448 1448 0 0.00 0 5514 14 0 0
v_max_non_paid_realizn 3517 3517 0 0.00 0 5541 6 0 0
v_min_id_clo_ord 5214 5214 0 0.00 0 9013 11 0 0
v_min_id_clo_res 991 991 0 0.00 0 6320 19 0 0
v_min_id_ord_sup 5235 5235 0 0.00 0 11804 10 0 0
v_min_non_paid_invoice 5515 5515 0 0.00 0 14585 25 0 0
v_min_non_paid_realizn 6778 6778 0 0.00 0 7544 25 0 0
v_our_firm 9446 9446 0 0.00 0 252 1 0 0
v_reserve_write_off 18876 18876 0 0.00 0 7039 5 0 0
x_lock_depdocs_on_canc_invoice 12030 12030 0 0.00 0 20590 108 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 13734 335 0 0
x_make_qstorno_1000_1200 5216 5216 0 0.00 2 31632 1262 0 0
x_make_qstorno_1200_2000 5255 5255 0 0.00 4 75510 3199 0 0
x_make_qstorno_2100_3300 440515 440515 0 0.00 0 74020 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-02-2021 04:27:41 to 03-02-2021 04:27:45.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:57:00 7 8192000000 8800324864 8812158976 5828400 0.071 2 2 3 0 5982272 92512 61360
01:58:01 20900 8192000000 9716659728 10010337280 790636368 8.801 100 87 174 0 819220208 7558432 21025408
01:59:29 10108 8192000000 9751151696 10055307264 827480528 9.174 100 88 176 1 861278560 12673424 21124608
02:00:56 18899 8192000000 9806052336 10122870784 866627600 9.566 100 83 166 2 911566464 25876352 19062512
02:02:19 11622 8192000000 9871141728 10204606464 917974672 10.076 100 82 162 2 979556720 43159008 18423040
02:03:40 9777 8192000000 9936296608 10279407616 961212432 10.501 100 83 166 1 1048874480 70901680 16760368
02:04:52 4378 8192000000 9999596208 10352906240 998924736 10.868 100 85 170 0 1113056320 97040016 17091568
02:05:57 2109 8192000000 9986985680 10351517696 1027117328 11.141 100 87 172 0 1104942272 60367008 17457936
02:07:01 1712 8192000000 10031545088 10399932416 1059117552 11.448 100 91 181 3 1149623664 72354992 18151120
02:08:04 32696 8192000000 9999227408 10392752128 1066929312 11.523 100 53 106 1 1129577008 51704400 10943296
02:09:41 3842 8192000000 9995134336 10378215424 1079556976 11.643 100 63 127 5 1107297360 13080352 14660032
02:10:47 7240 8192000000 9987725632 10392334336 1083124336 11.677 100 32 66 0 1105087600 14132768 7830496
02:11:59 5186 8192000000 10004253184 10399215616 1088614320 11.729 100 41 82 1 1123445808 26167360 8664128
02:13:05 14029 8192000000 10016124976 10404573184 1091826160 11.760 100 66 133 1 1123006640 15833664 15346816
02:14:20 6950 8192000000 10006303136 10416087040 1094766064 11.788 100 31 62 1 1120743648 19581632 6395952
02:15:31 6071 8192000000 10017543824 10422284288 1096677040 11.806 100 42 82 1 1139884224 34330400 8876784
02:16:37 5510 8192000000 10003926192 10393530368 1057081888 11.429 97 48 96 3 1095179760 28414880 9682992
02:17:45 51268 8192000000 9994774160 10398679040 1005160704 10.929 93 39 78 1 1038129616 24892960 8075952
02:19:38 7487 8192000000 9938163344 10327908352 1007317568 10.949 97 33 65 0 1030892208 16337392 7237248
02:20:46 3954 8192000000 9964543760 10331869184 1022497552 11.096 100 49 94 0 1068717728 36078448 10141728
02:21:50 7378 8192000000 9956263936 10342002688 1021219376 11.084 99 50 98 1 1053871840 22067024 10585440
02:22:58 49857 8192000000 9971535632 10376556544 1040769472 11.272 100 26 52 1 1078867424 33077376 5020576
02:24:52 4530 8192000000 9967471648 10354827264 1061980880 11.475 100 42 83 3 1081119856 9963872 9175104
02:25:58 4518 8192000000 9979110480 10368229376 1062278624 11.478 100 45 91 2 1092413392 20104992 10029776
02:27:03 31221 8192000000 9984531168 10375077888 1076230528 11.612 100 32 62 1 1102017168 19189072 6597568
02:28:41 98168 8192000000 10039132784 10457321472 1107920368 11.913 100 34 68 3 1154674112 39279760 7473984
02:31:30 22236 8192000000 10090395520 10498740224 1124038928 12.065 100 31 62 5 1200542720 70011344 6492448
02:33:01 29174 8192000000 10188265280 10597629952 1143313360 12.247 100 49 97 3 1266837904 113202480 10322064
02:34:46 16886 8192000000 10190477120 10599546880 1160008592 12.403 100 54 107 1 1295727584 124084272 11634720
02:36:08 10779 8192000000 10214312928 10632404992 1158714624 12.391 99 45 90 2 1325570736 156986880 9869232
02:37:22 3175 8192000000 10290908752 10696359936 1174650400 12.540 100 76 152 1 1392634976 201867264 16117312
02:38:34 2194 8192000000 10285855312 10697564160 1151524096 12.324 98 66 131 0 1383559376 216326720 15708560
02:39:39 3639 8192000000 10350181152 10755850240 1176595728 12.558 100 79 159 0 1459636672 265545216 17495728
02:40:50 11158 8192000000 10363823136 10774740992 1169036784 12.488 99 58 115 1 1466353264 283385824 13930656
02:42:05 4444 8192000000 10420433504 10821931008 1180315568 12.593 100 80 160 0 1508158560 309145280 18697712
02:43:17 3172 8192000000 10424156000 10831785984 1167961760 12.478 100 86 171 2 1524037456 336083648 19992048
02:44:27 1347 8192000000 10412061360 10826051584 1159051312 12.394 100 91 179 1 1516367984 336196112 21120560
02:45:33 7537 8192000000 10431145440 10850676736 1177827152 12.570 100 46 92 0 1533934544 344569760 11537632
02:46:47 8065 8192000000 10456488784 10869538816 1151546224 12.324 98 48 96 4 1551616720 387773840 12296656
02:47:58 6065 8192000000 10459641456 10880479232 1150146720 12.311 99 50 100 0 1555808784 392209088 13452976
02:49:08 11103 8192000000 10439122464 10907762688 1161352784 12.416 100 35 70 2 1530328848 360014848 8961216
02:50:21 9083 8192000000 10463724176 10895400960 1163910976 12.440 100 29 58 1 1559965808 388710848 7343984
02:51:34 34771 8192000000 10487364704 10985594880 1127929856 12.102 98 30 60 1 1627305152 491236656 8138640
02:53:13 36808 8192000000 10355206544 10925047808 1099927360 11.837 98 18 36 0 1535399088 429739136 5732592
02:54:51 52292 8192000000 10051837984 10487906304 1078345232 11.632 96 12 24 0 1124962352 43981328 2635792
02:56:46 132069 8192000000 10038903392 10469281792 1095316800 11.793 99 21 42 0 1129680608 29433920 4929888
03:00:01 23887 8192000000 10119820112 10545311744 1121351984 12.040 99 44 87 0 1202596288 72004144 9240160
03:01:26 84668 8192000000 10115323488 10557722624 1106152128 11.896 98 21 44 0 1212441040 101358384 4930528
03:03:54 62795 8192000000 10144602624 10586005504 1114519120 11.975 98 16 32 0 1201796480 83545008 3732352
03:06:29 61978 8192000000 10053759200 10571317248 1122643072 12.052 99 11 22 1 1179988112 55362192 1982848
03:08:32 32971 8192000000 10108891312 10551848960 1116408080 11.993 98 34 68 2 1186837152 62583664 7845408
03:10:09 50026 8192000000 10108988608 10592104448 1138008048 12.197 100 16 31 0 1231403712 90120896 3274768
03:12:06 48545 8192000000 10084981440 10523549696 1147970144 12.290 100 17 34 1 1191100048 39646992 3482912
03:14:06 58741 8192000000 10070225840 10511818752 1110745776 11.939 97 18 36 0 1174303904 59569968 3988160
03:16:14 23100 8192000000 10108382656 10542219264 1133672432 12.156 99 25 50 3 1204757760 65967728 5117600
03:17:43 120830 8192000000 10076398672 10528460800 1128321216 12.106 99 24 47 2 1183504464 50284704 4898544
03:20:59 37908 8192000000 10079803216 10545311744 1140765168 12.223 100 8 16 0 1187085696 44161488 2159040
03:22:52 35449 8192000000 10080721472 10581917696 1128705792 12.109 99 10 20 1 1169105696 38279568 2120336
03:24:29 44601 8192000000 10091502448 10554253312 1132258160 12.143 99 27 54 0 1194514624 56074960 6181504
03:26:19 59559 8192000000 10107070000 10546663424 1115521728 11.985 98 15 30 0 1184596224 65521040 3553456
03:28:19 48098 8192000000 10183779104 10620190720 1135029312 12.169 98 28 55 2 1253432512 111549408 6853792
03:30:12 55348 8192000000 10262779936 10702024704 1118709120 12.015 97 22 44 0 1319510544 195042144 5759280
03:32:22 38023 8192000000 10132888656 10633314304 1106206576 11.896 98 26 52 1 1265973824 153909456 5857792
03:34:01 72275 8192000000 10041698752 10496581632 1094707152 11.787 97 9 18 0 1139076320 42698448 1670720
03:36:13 45743 8192000000 10053109408 10521661440 1102175408 11.858 98 13 26 0 1138801792 33879024 2747360
03:38:30 46827 8192000000 10059818208 10539196416 1106632816 11.901 98 17 33 1 1146278224 35443536 4201872
03:40:36 74261 8192000000 10082879712 10578628608 1067915744 11.532 95 16 31 2 1119741328 48273424 3552160
03:43:44 45782 8192000000 10073535168 10517938176 1119862048 12.026 100 20 39 0 1181529984 57876144 3791792
03:45:30 54288 8192000000 10060736672 10474967040 1080646896 11.654 96 27 54 2 1109007280 23315120 5045264
03:47:25 28815 8192000000 10101638496 10581741568 1125161248 12.076 100 38 76 1 1196776368 63961296 7653824
03:49:39 122936 8192000000 10068914432 10561703936 1091578464 11.758 97 11 22 1 1158359952 64103312 2678176
03:52:43 85293 8192000000 10056084576 10538913792 1108550224 11.919 99 15 30 2 1152078752 40688192 2840336
03:55:27 60768 8192000000 10056191008 10536648704 1118219408 12.010 100 6 12 0 1166992208 47463248 1309552
03:57:29 69174 8192000000 10045109824 10497695744 1105240416 11.887 99 13 26 0 1154875280 47096912 2537952
03:59:59 60128 8192000000 10114293232 10554494976 1129988480 12.121 100 26 52 0 1198351344 63343296 5019568
04:02:04 102137 8192000000 10119051456 10615652352 1114656368 11.976 98 25 50 0 1253929968 133542176 5731424
04:04:46 63761 8192000000 10083508208 10531606528 1141264096 12.227 100 18 36 2 1211027344 66099856 3663392
04:06:51 40248 8192000000 10088254576 10542161920 1129895472 12.120 99 17 33 0 1176718416 43533840 3289104
04:08:36 49621 8192000000 10103530016 10553405440 1146528320 12.277 100 21 42 2 1216003984 64817792 4657872
04:10:47 106864 8192000000 10124497040 10583293952 1144838480 12.261 99 27 53 0 1200369856 50343408 5187968
04:13:42 40559 8192000000 10067578816 10568220672 1143797184 12.251 100 5 10 0 1206735552 61828608 1109760
04:15:30 43558 8192000000 10084504736 10525143040 1123735632 12.062 98 12 24 1 1169698352 43403456 2559264
04:17:18 78639 8192000000 10107440592 10559279104 1109933328 11.932 97 23 46 2 1159807072 44840704 5033040
04:19:37 33809 8192000000 10169641760 10606751744 1118000576 12.008 98 38 76 1 1210823696 84517504 8305616
04:21:12 58174 8192000000 10181758496 10620329984 1119446496 12.022 97 18 36 1 1225497520 101178448 4872576
04:23:11 85406 8192000000 10152748000 10722320384 1135061776 12.169 99 12 24 0 1270145760 132246608 2837376
04:25:38 62353 8192000000 10084424864 10514272256 1114558336 11.976 97 16 32 1 1145838256 28355936 2923984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          229530
MON$OLDEST_ACTIVE               230339
MON$OLDEST_SNAPSHOT             230339
MON$NEXT_TRANSACTION            230339
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                       2108736
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2357 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.2357 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 ---
:21: 
:22: mscs-test-161.budzdorov.ru    Wed Feb  3 04:27:02 2021
:23:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:24: 
:25: 
:26: mscs-test-161.budzdorov.ru    Wed Feb  3 04:27:02 2021
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
--- end of diff output ---

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

03.02.21 04:28:05. 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-03 04:28:08 No severe PSQL-related problems occured
03.02.21 04:28:09. End of report.