Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.06.2021 07:28
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 16670188 kB
MemAvailable 31367080 kB
Buffers 0 kB
Cached 14971060 kB
SwapCached 2408 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-20 07:26:59
DTS_END                         2021-06-20 07:26:59.1360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53604
TRN_ID                          237738
ATT_ID                          645
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: 9037.36 23907 1084483
customer order: creation 172.72 435 20726
customer order: refuse 15.01 321 1801
order to supplier: creation 46.66 1314 5599
order to supplier: removal 3.35 125918 402
invoice (draft): creation 46.79 3377 5615
invoice (draft): removal 50.18 1074 6022
invoice accept: apply 42.14 17464 5057
invoice accept: cancel 45.09 985 5411
customer reserve: creation 3872.98 172 464758
customer reserve: removal 4437.65 75 532518
realization accept: apply 84.29 174 10115
realization accept: cancel 90.53 130 10864
payment from customer: creation 59.86 106 7183
payment from customer: removal 5.73 75 688
payment to supplier: creation 49.54 125 5945
payment to supplier: removal 3.53 78 423
service: total inventory turnovers 3.83 3070 459
service: total monetary turnovers 6.35 607 762
service: aggregate perf. data 1.09 12529 131
service: refresh index statistics 0.03 310120 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-06-2021 07:28:23 to 20-06-2021 07:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3447
TEST_TIME 2 3787
TEST_TIME 3 3674
TEST_TIME 4 4095
TEST_TIME 5 4473
TEST_TIME 6 4436
TEST_TIME 7 4791
TEST_TIME 8 5201
TEST_TIME 9 5254
TEST_TIME 10 5553
TEST_TIME 11 5545
TEST_TIME 12 5866
TEST_TIME 13 6264
TEST_TIME 14 6744
TEST_TIME 15 6626
TEST_TIME 16 6717
TEST_TIME 17 6837
TEST_TIME 18 7221
TEST_TIME 19 7360
TEST_TIME 20 7563
TEST_TIME 21 7695
TEST_TIME 22 7920
TEST_TIME 23 8291
TEST_TIME 24 8215
TEST_TIME 25 8387
TEST_TIME 26 8488
TEST_TIME 27 8637
TEST_TIME 28 8781
TEST_TIME 29 10924
TEST_TIME 30 11123
TEST_TIME 31 10930
TEST_TIME 32 10967
TEST_TIME 33 10835
TEST_TIME 34 10721
TEST_TIME 35 10420
TEST_TIME 36 10343
TEST_TIME 37 10287
TEST_TIME 38 10113
TEST_TIME 39 10065
TEST_TIME 40 10192
TEST_TIME 41 10048
TEST_TIME 42 9951
TEST_TIME 43 9917
TEST_TIME 44 9856
TEST_TIME 45 9859
TEST_TIME 46 9932
TEST_TIME 47 9836
TEST_TIME 48 9880
TEST_TIME 49 9922
TEST_TIME 50 9897
TEST_TIME 51 9947
TEST_TIME 52 9984
TEST_TIME 53 9991
TEST_TIME 54 9994
TEST_TIME 55 10050
TEST_TIME 56 9992
TEST_TIME 57 9856
TEST_TIME 58 9729
TEST_TIME 59 9749
TEST_TIME 60 9658
TEST_TIME 61 9572
TEST_TIME 62 9494
TEST_TIME 63 9486
TEST_TIME 64 9446
TEST_TIME 65 9420
TEST_TIME 66 9426
TEST_TIME 67 9435
TEST_TIME 68 9521
TEST_TIME 69 9496
TEST_TIME 70 9507
TEST_TIME 71 9502
TEST_TIME 72 9486
TEST_TIME 73 9493
TEST_TIME 74 9537
TEST_TIME 75 9523
TEST_TIME 76 9496
TEST_TIME 77 9409
TEST_TIME 78 9404
TEST_TIME 79 9334
TEST_TIME 80 9284
TEST_TIME 81 9226
TEST_TIME 82 9213
TEST_TIME 83 9183
TEST_TIME 84 9223
TEST_TIME 85 9201
TEST_TIME 86 9212
TEST_TIME 87 9188
TEST_TIME 88 9151
TEST_TIME 89 9170
TEST_TIME 90 9203
TEST_TIME 91 9364
TEST_TIME 92 9430
TEST_TIME 93 9349
TEST_TIME 94 9291
TEST_TIME 95 9260
TEST_TIME 96 9242
TEST_TIME 97 9254
TEST_TIME 98 9227
TEST_TIME 99 9237
TEST_TIME 100 9211
TEST_TIME 101 9214
TEST_TIME 102 9247
TEST_TIME 103 9275
TEST_TIME 104 9295
TEST_TIME 105 9256
TEST_TIME 106 9250
TEST_TIME 107 9304
TEST_TIME 108 9307
TEST_TIME 109 9278
TEST_TIME 110 9206
TEST_TIME 111 9180
TEST_TIME 112 9139
TEST_TIME 113 9090
TEST_TIME 114 9082
TEST_TIME 115 9054
TEST_TIME 116 9107
TEST_TIME 117 9085
TEST_TIME 118 9084
TEST_TIME 119 9058
TEST_TIME 120 9039

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-06-2021 07:28:27 to 20-06-2021 07:28:30.

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 1515398 1515398 0 0.00 0 20947 7 0 0
doc_list_biud 1515393 1515393 0 0.00 0 39400 33 0 0
sp_add_invoice_to_stock 5137 5057 80 1.55 52 313208 17464 0 80
sp_cancel_adding_invoice 5411 5411 0 0.00 5 69081 985 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 10631 322 0 0
sp_cancel_customer_reserve 532518 532518 0 0.00 1 39409 75 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 4367 76 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 4465 79 0 0
sp_cancel_supplier_invoice 6028 6022 6 0.09 2 22507 1074 0 6
sp_cancel_supplier_order 402 402 0 0.00 8 1224724 125919 0 0
sp_cancel_write_off 10864 10864 0 0.00 1 12609 130 0 0
sp_client_order 20726 20726 0 0.00 4 23670 436 0 0
sp_customer_reserve 464758 464758 0 0.00 0 155755 173 0 0
sp_fill_shopping_cart 31930 31930 0 0.00 1 17240 39 0 0
sp_fill_shopping_cart_clo_res 464756 464756 0 0.00 0 20296 18 0 0
sp_get_clo_for_invoice 5054 5054 0 0.00 0 23560 354 0 0
sp_kill_cost_storno 17386 17386 0 0.00 0 12604 64 0 0
sp_kill_qstorno_ret_qs2qd 1082324 1082324 0 0.00 0 28638 41 0 0
sp_kill_qty_storno 572185 572185 0 0.00 0 39398 87 0 0
sp_lock_selected_doc 20990 20990 0 0.00 0 9815 5 0 0
sp_make_cost_storno 28297 28297 0 0.00 0 17261 59 0 0
sp_multiply_rows_for_pdistr 28294 28294 0 0.00 0 9629 29 0 0
sp_multiply_rows_for_qdistr 20726 20726 0 0.00 0 14762 261 0 0
sp_pay_from_customer 7183 7183 0 0.00 4 17218 106 0 0
sp_pay_to_supplier 5945 5945 0 0.00 5 20631 125 0 0
sp_payment_common 13125 13125 0 0.00 1 12225 22 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 10622 293 0 0
sp_qd_handle_on_invoice_upd_sts 10463 10463 0 0.00 0 23876 390 0 0
sp_qd_handle_on_reserve_upd_sts 10115 10115 0 0.00 0 12424 49 0 0
sp_reserve_write_off 10115 10115 0 0.00 6 17588 175 0 0
sp_supplier_invoice 5615 5615 0 0.00 6 100310 3378 0 0
sp_supplier_order 5599 5599 0 0.00 5 20601 1315 0 0
srv_aggregate_perf_data 131 131 0 0.00 2540 134116 12529 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1857 128440 43860 0 0
srv_find_qd_qs_mism 1036676 1036676 0 0.00 0 20896 6 0 0
srv_make_invnt_saldo 459 459 0 0.00 45 99479 3071 0 0
srv_make_money_saldo 762 762 0 0.00 0 11254 608 0 0
srv_recalc_idx_stat 4 4 0 0.00 137246 436739 310121 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5058 5058 0 0.00 0 17412 169 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 2423 77 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 2801 13 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 2692 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 220 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 703 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 4668 31 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 24946 1508 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 2194 8 0 0
t$perf-norm:sp_client_order 20726 20726 0 0.00 0 20129 20 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 1149 5 0 0
t$perf-norm:sp_pay_from_customer 7183 7183 0 0.00 0 12324 8 0 0
t$perf-norm:sp_pay_to_supplier 5945 5945 0 0.00 0 13005 10 0 0
t$perf-norm:sp_reserve_write_off 10115 10115 0 0.00 0 11253 10 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 5460 15 0 0
t$perf-norm:sp_supplier_order 5599 5599 0 0.00 0 8057 17 0 0
t$perf-norm:srv_aggregate_perf_data 131 131 0 0.00 0 398 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 75 2 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 1452 12 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 14452 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10264 10104 160 1.55 0 13977 19 0 160
v_all_customers 38514 38514 0 0.00 0 71 1 0 0
v_all_suppliers 31158 31158 0 0.00 0 181 1 0 0
v_all_wares 41452 41452 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 840 6 0 0
v_cancel_client_order 3602 3602 0 0.00 0 4444 8 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 4353 12 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 2937 8 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 4438 9 0 0
v_cancel_supplier_invoice 872 860 12 1.37 0 9990 31 0 12
v_cancel_supplier_order 804 804 0 0.00 0 1320 6 0 0
v_cancel_write_off 1990 1990 0 0.00 0 1679 13 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 1687 1 0 0
v_max_id_clo_res 978 978 0 0.00 0 2434 19 0 0
v_max_id_ord_sup 5606 5606 0 0.00 0 4333 6 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 1071 3 0 0
v_max_non_paid_realizn 3558 3558 0 0.00 0 8447 8 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 2273 5 0 0
v_min_id_clo_res 978 978 0 0.00 0 4556 16 0 0
v_min_id_ord_sup 5606 5606 0 0.00 0 9000 11 0 0
v_min_non_paid_invoice 5943 5943 0 0.00 0 8161 13 0 0
v_min_non_paid_realizn 7182 7182 0 0.00 0 8239 15 0 0
v_our_firm 10186 10186 0 0.00 0 37 1 0 0
v_reserve_write_off 20228 20228 0 0.00 0 7249 6 0 0
x_lock_depdocs_on_canc_invoice 11003 11003 0 0.00 0 33809 112 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7896 308 0 0
x_make_qstorno_1000_1200 5599 5599 0 0.00 2 20325 1238 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 3 98385 3231 0 0
x_make_qstorno_2100_3300 464758 464758 0 0.00 0 155753 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-06-2021 07:28:31 to 20-06-2021 07:28:35.

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
04:57:02 2 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
04:58:04 37254 8192000000 9724682704 10022420480 802525024 8.922 100 91 182 0 832389056 7532064 22331968
04:59:46 16306 8192000000 9759825984 10066612224 836470128 9.264 100 91 182 0 871529856 13064144 21995584
05:01:19 20596 8192000000 9809207824 10130423808 868275152 9.583 100 77 155 0 910654624 24792416 17587056
05:02:43 14371 8192000000 9886329488 10217934848 923364400 10.129 100 84 168 1 989170016 47807840 17997776
05:03:59 7840 8192000000 9932385136 10280419328 965586544 10.544 100 81 163 2 1046374160 63517744 17269872
05:05:14 4967 8192000000 9962651408 10321911808 1004084784 10.918 100 73 147 1 1077581904 59069248 14427872
05:06:22 2602 8192000000 10002382448 10369609728 1033736016 11.204 100 75 149 1 1116233552 67587696 14909840
05:07:26 1404 8192000000 10012142224 10381795328 1057333808 11.431 100 95 190 0 1123808272 47726176 18748288
05:08:29 3032 8192000000 10061444272 10441424896 1062786176 11.483 100 69 138 0 1167726416 90898032 14042208
05:09:32 58884 8192000000 9987966256 10405138432 1068167952 11.535 100 32 64 1 1123372864 48029120 7175792
05:11:32 10000 8192000000 9996980736 10401677312 1076079392 11.610 100 35 70 0 1109657216 25893056 7684768
05:12:42 8199 8192000000 9990834176 10395402240 1082193456 11.668 100 42 84 1 1107380688 15095984 10091248
05:13:55 6563 8192000000 10006725248 10410332160 1096576640 11.805 100 40 80 1 1115920080 11512128 7831312
05:15:03 31254 8192000000 10026124992 10454540288 1097725504 11.816 100 50 100 1 1127730064 18913824 11090736
05:16:47 6290 8192000000 10024432992 10432999424 1099872128 11.836 100 46 89 3 1131534320 22553344 9108848
05:17:53 9698 8192000000 9983192576 10387046400 1057464176 11.432 98 26 53 1 1081704608 18486160 5754272
05:19:03 5923 8192000000 9968422832 10366042112 1031620768 11.184 98 28 57 1 1058139616 19934144 6584704
05:20:13 21062 8192000000 9936750912 10325475328 992160976 10.802 95 18 36 0 1025488784 29546800 3781008
05:21:35 8298 8192000000 9943733728 10340704256 1020721360 11.079 100 35 70 0 1059877808 31300832 7855616
05:22:45 8561 8192000000 9951777184 10346565632 1033617664 11.203 100 45 89 1 1070031568 26455776 9958128
05:23:56 79923 8192000000 9961342208 10371670016 1051881168 11.379 100 33 66 1 1074579136 16273920 6424048
05:26:18 8942 8192000000 9982053776 10388692992 1063989168 11.495 100 40 80 1 1101413424 28477680 8946576
05:27:31 16089 8192000000 10012335024 10406113280 1086952960 11.714 100 48 96 2 1120737792 23009328 10775504
05:28:55 26116 8192000000 10050359552 10455408640 1104204976 11.878 100 42 84 1 1154055232 41135664 8714592
05:30:28 68839 8192000000 10109219216 10522996736 1123295808 12.058 100 35 70 1 1205115296 73409728 8409760
05:32:44 46862 8192000000 10157879024 10572345344 1144111632 12.254 100 58 116 2 1272146864 115199536 12835696
05:34:52 6577 8192000000 10189590528 10600214528 1155387664 12.360 100 56 110 2 1291593248 123483984 12721600
05:36:08 3261 8192000000 10224667024 10635849728 1161323104 12.416 100 60 120 0 1331204288 156688736 13192448
05:37:17 5762 8192000000 10257215712 10670194688 1171450976 12.510 100 68 135 0 1362627104 177130368 14045760
05:38:27 4475 8192000000 10264427456 10699653120 1171813808 12.514 100 53 106 2 1391193952 207320896 12059248
05:39:36 6591 8192000000 10340502272 10773864448 1183190896 12.620 100 72 144 0 1439856512 239697920 16967696
05:40:45 2987 8192000000 10322005344 10736844800 1188813760 12.672 100 68 134 5 1424576128 220444112 15318256
05:41:54 1856 8192000000 10369900688 10784526336 1189309552 12.677 100 73 142 0 1473035776 266778528 16947696
05:43:00 5132 8192000000 10374489008 10786308096 1164501456 12.445 100 78 156 0 1474928384 292263696 18163232
05:44:11 3222 8192000000 10427478016 10825871360 1161531904 12.418 100 83 164 1 1519152160 338550576 19069680
05:45:20 2364 8192000000 10427039968 10828038144 1156750288 12.373 100 81 162 0 1527095440 351128896 19216256
05:46:29 9881 8192000000 10456907888 10872680448 1159274320 12.396 100 63 126 1 1564247936 388808800 16164816
05:47:41 10789 8192000000 10466834528 10892103680 1158768896 12.392 100 55 108 2 1558244976 385297440 14178640
05:49:03 17177 8192000000 10481280800 10909827072 1143782480 12.251 99 38 76 0 1560185696 406351344 10051872
05:50:31 6330 8192000000 10476007136 10893434880 1140638880 12.222 100 57 114 0 1565367312 410378480 14349952
05:51:40 12096 8192000000 10525510000 10972176384 1143755136 12.251 100 38 76 0 1622201664 468267008 10179520
05:53:08 13201 8192000000 10505643376 10938298368 1100277760 11.840 99 32 62 0 1577537616 468308208 8951648
05:54:31 12348 8192000000 10525585552 10979614720 1106861312 11.903 100 30 60 0 1627717792 511831536 9024944
05:55:46 32591 8192000000 10052018976 10555637760 1100333376 11.841 99 14 27 0 1193915680 90702528 2879776
05:57:26 32551 8192000000 10103369456 10527322112 1114537680 11.975 99 28 54 2 1178857840 58879104 5441056
05:59:02 39943 8192000000 10087038640 10513731584 1125529744 12.079 99 30 61 0 1170308096 38363872 6414480
06:00:45 94916 8192000000 10089184912 10524602368 1136320448 12.181 100 30 60 0 1184063024 41175888 6566688
06:03:21 30938 8192000000 10087702112 10525278208 1123467488 12.060 99 27 54 0 1169502000 39872224 6162288
06:04:54 55115 8192000000 10129271680 10585161728 1135922528 12.177 100 28 56 1 1232020624 89924288 6173808
06:06:53 79281 8192000000 10111759648 10559315968 1123674288 12.062 98 18 36 1 1178131328 50741248 3715792
06:09:14 128440 8192000000 10110641808 10583457792 1116084976 11.990 97 8 16 0 1188227376 69908624 2233776
06:12:23 74331 8192000000 10122785136 10587332608 1140078592 12.216 99 16 32 2 1199670032 56084896 3506544
06:14:50 81267 8192000000 10098313856 10575417344 1115289792 11.982 97 17 34 0 1209372896 90316160 3766944
06:17:32 12763 8192000000 10090788720 10532532224 1140350912 12.219 100 42 84 3 1192140032 43387168 8401952
06:18:55 71600 8192000000 10101431344 10567618560 1140317344 12.219 99 20 40 0 1183286080 39182992 3785744
06:21:10 59244 8192000000 10099446304 10585317376 1133648432 12.156 99 13 26 0 1205991872 69412064 2931376
06:23:09 97226 8192000000 10088543808 10548621312 1152314880 12.331 100 17 34 0 1210546720 54536160 3695680
06:25:52 50256 8192000000 10082699824 10576654336 1134271856 12.162 98 8 16 0 1190389504 54169184 1948464
06:27:42 61481 8192000000 10060467488 10542604288 1137064496 12.188 99 5 10 0 1176569584 38609072 896016
06:29:44 63464 8192000000 10057501392 10512375808 1080649968 11.654 94 16 30 1 1118062288 34091856 3320464
06:31:52 26067 8192000000 10101869296 10552160256 1129892032 12.120 99 28 55 1 1182329920 46878352 5559536
06:33:18 75112 8192000000 10125314608 10561167360 1094696752 11.787 95 20 41 1 1171655072 72716144 4242176
06:35:44 50411 8192000000 10088294704 10525368320 1106062384 11.895 97 33 66 0 1136615952 23951104 6602464
06:37:39 26602 8192000000 10088374928 10537091072 1120688672 12.033 99 34 68 1 1168286176 41059264 6538240
06:39:08 22797 8192000000 10086368288 10524971008 1119316752 12.021 99 24 48 1 1186187760 62333408 4537600
06:40:31 42893 8192000000 10039462656 10464468992 1037638016 11.242 92 31 62 2 1077851168 34195824 6017328
06:42:16 83579 8192000000 10077617824 10548015104 1104533968 11.881 100 34 68 2 1186532336 74860000 7138368
06:45:43 58321 8192000000 10047148528 10506661888 1083927072 11.685 97 4 8 0 1115374816 30673808 773936
06:47:46 92238 8192000000 10108688720 10562142208 1106509520 11.899 98 8 16 0 1175853792 66880816 2463456
06:50:23 18961 8192000000 10072218240 10500386816 1109338736 11.926 99 34 68 1 1145533696 28916624 7278336
06:51:42 60185 8192000000 10079488176 10539524096 1124527056 12.070 100 22 44 0 1183749616 54698592 4523968
06:53:42 51357 8192000000 10144979760 10590179328 1124887632 12.073 99 22 44 1 1237323760 107819600 4616528
06:55:38 29303 8192000000 10176271376 10601414656 1114742432 11.977 98 34 68 2 1248795680 126593376 7459872
06:57:20 66736 8192000000 10095505184 10618675200 1130895424 12.130 100 9 18 0 1221948864 89371120 1682320
06:59:28 98608 8192000000 10080907376 10545303552 1129921232 12.121 99 18 36 1 1172343040 38830784 3591024
07:02:09 73481 8192000000 10101802400 10554568704 1146109984 12.273 100 13 26 0 1197794528 49496688 2187856
07:04:23 92398 8192000000 10099234144 10581729280 1144972000 12.262 100 21 42 1 1212149376 62206768 4970608
07:07:01 70041 8192000000 10119554464 10554937344 1140808256 12.223 99 23 46 0 1201722336 56265008 4649072
07:09:15 66741 8192000000 10102595632 10555166720 1095229296 11.792 95 7 14 0 1135863184 39273344 1360544
07:11:23 23900 8192000000 10083195536 10506211328 1119502976 12.022 98 41 80 0 1149074784 21440688 8131120
07:12:48 14780 8192000000 10135073808 10570350592 1151133136 12.320 100 62 119 0 1223649872 60040000 12476736
07:14:04 24077 8192000000 10088534640 10543185920 1101053520 11.848 96 17 34 1 1135053696 30427680 3572496
07:15:28 98969 8192000000 10076407824 10536153088 1138062784 12.197 100 20 41 0 1187747056 45962176 3722096
07:18:18 57329 8192000000 10067647808 10519281664 1130126352 12.123 99 11 22 0 1163465968 31105904 2233712
07:20:15 47285 8192000000 10112167232 10588102656 1140753312 12.223 100 14 28 0 1192650016 48887440 3009264
07:22:04 74894 8192000000 10043717776 10498691072 1057013536 11.428 93 10 20 0 1114276208 54911744 2350928
07:24:35 47369 8192000000 10110855616 10553057280 1107039984 11.904 97 23 47 0 1171633744 59787136 4806624
07:26:24 52867 8192000000 9504791488 9934622720 575628912 6.565 50 16 29 1 670802240 91557216 3616112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-06-2021 07:28:37 to 20-06-2021 07:28:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-06-2021 07:28:53 to 20-06-2021 07:28:55.

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          235861
MON$OLDEST_ACTIVE               238022
MON$OLDEST_SNAPSHOT             238022
MON$NEXT_TRANSACTION            238022
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                       2097728
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 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 ---
:78: 
:79: mscs-test-161.budzdorov.ru    Sun Jun 20 07:27:46 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
--- end of diff output ---

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

20.06.21 07:28:56. 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-20 07:28:59 No severe PSQL-related problems occured
20.06.21 07:29:00. End of report.