Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.10.2021 10:39
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 15933392 kB
MemAvailable 30894228 kB
Buffers 0 kB
Cached 15806660 kB
SwapCached 4476 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-10-30 10:37:08
DTS_END                         2021-10-30 10:37:08.4760
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/38462
TRN_ID                          239663
ATT_ID                          677
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: 9237.11 26757 1108453
customer order: creation 168.33 410 20199
customer order: refuse 14.08 317 1690
order to supplier: creation 46.23 1469 5547
order to supplier: removal 3.35 115543 402
invoice (draft): creation 44.74 3849 5369
invoice (draft): removal 52.88 876 6345
invoice accept: apply 41.03 18025 4924
invoice accept: cancel 48.31 833 5797
customer reserve: creation 3808.26 176 456991
customer reserve: removal 4709.87 65 565184
realization accept: apply 83.49 212 10019
realization accept: cancel 88.97 124 10676
payment from customer: creation 59.37 114 7124
payment from customer: removal 5.78 85 694
payment to supplier: creation 48.43 145 5811
payment to supplier: removal 3.31 101 397
service: total inventory turnovers 3.66 3508 439
service: total monetary turnovers 5.97 656 716
service: aggregate perf. data 1.04 12799 125
service: refresh index statistics 0.03 375825 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-10-2021 10:39:31 to 30-10-2021 10:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3800
TEST_TIME 2 3595
TEST_TIME 3 3237
TEST_TIME 4 3896
TEST_TIME 5 4574
TEST_TIME 6 5277
TEST_TIME 7 6326
TEST_TIME 8 6271
TEST_TIME 9 6433
TEST_TIME 10 6684
TEST_TIME 11 7290
TEST_TIME 12 7390
TEST_TIME 13 7633
TEST_TIME 14 7845
TEST_TIME 15 7851
TEST_TIME 16 8348
TEST_TIME 17 8710
TEST_TIME 18 9317
TEST_TIME 19 9574
TEST_TIME 20 9458
TEST_TIME 21 9595
TEST_TIME 22 9845
TEST_TIME 23 9830
TEST_TIME 24 10319
TEST_TIME 25 10075
TEST_TIME 26 10535
TEST_TIME 27 10810
TEST_TIME 28 11503
TEST_TIME 29 11367
TEST_TIME 30 11211
TEST_TIME 31 11102
TEST_TIME 32 11260
TEST_TIME 33 11097
TEST_TIME 34 10877
TEST_TIME 35 10671
TEST_TIME 36 10607
TEST_TIME 37 10519
TEST_TIME 38 10395
TEST_TIME 39 10405
TEST_TIME 40 10338
TEST_TIME 41 10554
TEST_TIME 42 10405
TEST_TIME 43 10232
TEST_TIME 44 10174
TEST_TIME 45 10164
TEST_TIME 46 10200
TEST_TIME 47 10121
TEST_TIME 48 10033
TEST_TIME 49 10062
TEST_TIME 50 10022
TEST_TIME 51 10127
TEST_TIME 52 10096
TEST_TIME 53 10142
TEST_TIME 54 10027
TEST_TIME 55 10170
TEST_TIME 56 10180
TEST_TIME 57 10184
TEST_TIME 58 10226
TEST_TIME 59 10259
TEST_TIME 60 10133
TEST_TIME 61 10025
TEST_TIME 62 9924
TEST_TIME 63 9935
TEST_TIME 64 9911
TEST_TIME 65 9819
TEST_TIME 66 9813
TEST_TIME 67 9735
TEST_TIME 68 9725
TEST_TIME 69 9739
TEST_TIME 70 9714
TEST_TIME 71 9733
TEST_TIME 72 9686
TEST_TIME 73 9615
TEST_TIME 74 9756
TEST_TIME 75 9889
TEST_TIME 76 9944
TEST_TIME 77 10000
TEST_TIME 78 9967
TEST_TIME 79 9865
TEST_TIME 80 9810
TEST_TIME 81 9768
TEST_TIME 82 9721
TEST_TIME 83 9664
TEST_TIME 84 9644
TEST_TIME 85 9577
TEST_TIME 86 9607
TEST_TIME 87 9550
TEST_TIME 88 9531
TEST_TIME 89 9494
TEST_TIME 90 9462
TEST_TIME 91 9550
TEST_TIME 92 9630
TEST_TIME 93 9584
TEST_TIME 94 9628
TEST_TIME 95 9695
TEST_TIME 96 9721
TEST_TIME 97 9665
TEST_TIME 98 9597
TEST_TIME 99 9570
TEST_TIME 100 9522
TEST_TIME 101 9512
TEST_TIME 102 9448
TEST_TIME 103 9482
TEST_TIME 104 9430
TEST_TIME 105 9436
TEST_TIME 106 9454
TEST_TIME 107 9411
TEST_TIME 108 9451
TEST_TIME 109 9451
TEST_TIME 110 9487
TEST_TIME 111 9474
TEST_TIME 112 9483
TEST_TIME 113 9512
TEST_TIME 114 9481
TEST_TIME 115 9450
TEST_TIME 116 9397
TEST_TIME 117 9387
TEST_TIME 118 9357
TEST_TIME 119 9314
TEST_TIME 120 9241

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-10-2021 10:39:34 to 30-10-2021 10:39:38.

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 1531073 1531073 0 0.00 0 25781 7 0 0
doc_list_biud 1531073 1531073 0 0.00 0 45551 30 0 0
sp_add_invoice_to_stock 5054 4924 130 2.57 49 185992 18026 0 130
sp_cancel_adding_invoice 5797 5797 0 0.00 6 52882 834 0 0
sp_cancel_client_order 1690 1690 0 0.00 5 11250 318 0 0
sp_cancel_customer_reserve 565184 565184 0 0.00 1 23085 66 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 7926 86 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 5389 102 0 0
sp_cancel_supplier_invoice 6353 6345 8 0.12 3 45557 877 0 8
sp_cancel_supplier_order 402 402 0 0.00 13 1010497 115543 0 0
sp_cancel_write_off 10676 10676 0 0.00 1 10135 125 0 0
sp_client_order 20199 20199 0 0.00 4 26730 411 0 0
sp_customer_reserve 456991 456991 0 0.00 0 152610 177 0 0
sp_fill_shopping_cart 31104 31104 0 0.00 1 23709 48 0 0
sp_fill_shopping_cart_clo_res 456987 456987 0 0.00 0 19257 16 0 0
sp_get_clo_for_invoice 4918 4918 0 0.00 0 28557 366 0 0
sp_kill_cost_storno 17564 17564 0 0.00 0 9377 57 0 0
sp_kill_qstorno_ret_qs2qd 1147791 1147791 0 0.00 0 45549 35 0 0
sp_kill_qty_storno 605031 605031 0 0.00 0 45551 76 0 0
sp_lock_selected_doc 20584 20584 0 0.00 0 11181 7 0 0
sp_make_cost_storno 27872 27872 0 0.00 0 25781 59 0 0
sp_multiply_rows_for_pdistr 27872 27872 0 0.00 0 9940 34 0 0
sp_multiply_rows_for_qdistr 20199 20199 0 0.00 0 26164 227 0 0
sp_pay_from_customer 7124 7124 0 0.00 4 15720 114 0 0
sp_pay_to_supplier 5811 5811 0 0.00 5 25785 145 0 0
sp_payment_common 12935 12935 0 0.00 1 6655 27 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 8417 268 0 0
sp_qd_handle_on_invoice_upd_sts 10715 10715 0 0.00 0 22701 351 0 0
sp_qd_handle_on_reserve_upd_sts 10019 10019 0 0.00 0 20202 75 0 0
sp_reserve_write_off 10019 10019 0 0.00 7 20231 213 0 0
sp_supplier_invoice 5369 5369 0 0.00 6 96316 3850 0 0
sp_supplier_order 5547 5547 0 0.00 6 74229 1469 0 0
srv_aggregate_perf_data 125 125 0 0.00 2581 119644 12799 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1367 159929 48418 0 0
srv_find_qd_qs_mism 1061045 1061045 0 0.00 0 20976 6 0 0
srv_make_invnt_saldo 439 439 0 0.00 59 103568 3509 0 0
srv_make_money_saldo 716 716 0 0.00 1 11530 656 0 0
srv_recalc_idx_stat 4 4 0 0.00 271717 492362 375826 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4925 4925 0 0.00 0 7895 147 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 6132 96 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 8294 16 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1434 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 1254 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 616 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 1115 18 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 24694 1668 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 8878 28 0 0
t$perf-norm:sp_client_order 20199 20199 0 0.00 0 7864 19 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 1156 6 0 0
t$perf-norm:sp_pay_from_customer 7124 7124 0 0.00 0 5949 12 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 5860 11 0 0
t$perf-norm:sp_reserve_write_off 10019 10019 0 0.00 0 19405 12 0 0
t$perf-norm:sp_supplier_invoice 5369 5369 0 0.00 0 10013 22 0 0
t$perf-norm:sp_supplier_order 5547 5547 0 0.00 0 7980 15 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 1539 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1005 18 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 517 8 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 4686 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10092 9832 260 2.57 0 5345 16 0 260
v_all_customers 37156 37156 0 0.00 0 63 1 0 0
v_all_suppliers 30148 30148 0 0.00 0 18 1 0 0
v_all_wares 40396 40396 0 0.00 0 143 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2075 9 0 0
v_cancel_client_order 3380 3380 0 0.00 0 1743 4 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 2052 7 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 437 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 2858 10 0 0
v_cancel_supplier_invoice 818 802 16 1.95 0 186 12 0 16
v_cancel_supplier_order 804 804 0 0.00 0 501 4 0 0
v_cancel_write_off 1948 1948 0 0.00 0 4295 19 0 0
v_max_id_clo_ord 5547 5547 0 0.00 0 1382 2 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 5237 30 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 4901 6 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 83 2 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 1481 4 0 0
v_min_id_clo_ord 5547 5547 0 0.00 0 1071 5 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 2900 10 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 7922 14 0 0
v_min_non_paid_invoice 5811 5811 0 0.00 0 5959 18 0 0
v_min_non_paid_realizn 7123 7123 0 0.00 0 6651 22 0 0
v_our_firm 9926 9926 0 0.00 0 13 1 0 0
v_reserve_write_off 20038 20038 0 0.00 0 3059 5 0 0
x_lock_depdocs_on_canc_invoice 11741 11741 0 0.00 0 15333 107 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 2575 303 0 0
x_make_qstorno_1000_1200 5547 5547 0 0.00 2 74218 1383 0 0
x_make_qstorno_1200_2000 5369 5369 0 0.00 3 96306 3669 0 0
x_make_qstorno_2100_3300 456991 456991 0 0.00 0 152609 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-10-2021 10:39:39 to 30-10-2021 10:39:43.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 27140 8192000000 9733310064 10032914432 811753664 9.015 100 90 180 0 840941024 8056832 21130528
08:09:44 23613 8192000000 9782260864 10093641728 855155216 9.452 100 85 170 1 888836032 14268832 19411984
08:11:10 17651 8192000000 9812212928 10132758528 877484928 9.675 100 90 180 1 923858368 25479472 20893968
08:12:31 11981 8192000000 9874130880 10209284096 924571568 10.141 100 81 163 1 981479456 39486032 17421856
08:13:47 8698 8192000000 9934189712 10283614208 957848464 10.468 100 81 161 2 1043820416 69502432 16469520
08:14:59 4128 8192000000 9955851600 10314158080 989765056 10.779 100 81 161 0 1068321392 62188512 16367824
08:16:07 4341 8192000000 9991972000 10357678080 1021999744 11.091 100 73 141 3 1105290064 69002288 14288032
08:17:14 2034 8192000000 10029367328 10401411072 1054063792 11.400 100 91 180 1 1147032624 74813632 18155200
08:18:18 33170 8192000000 10020195264 10402672640 1067440848 11.528 100 64 128 0 1135290144 53595760 14253536
08:19:51 6606 8192000000 10013088320 10404171776 1073580160 11.586 100 47 95 0 1122273760 38609824 10083776
08:21:00 4466 8192000000 9998677744 10388992000 1077834432 11.627 100 47 95 0 1104980480 17414928 9731120
08:22:05 8998 8192000000 10003504176 10407796736 1088847808 11.732 100 28 56 1 1108750608 14458992 5443808
08:23:14 30095 8192000000 10015928752 10406092800 1092030064 11.762 100 64 127 0 1114073392 7407168 14636160
08:24:44 4332 8192000000 10027681280 10425569280 1098670976 11.825 100 53 105 1 1132077952 22102416 11304560
08:25:50 6467 8192000000 10006532880 10418323456 1088391376 11.727 99 22 44 1 1116851824 23443536 5016912
08:26:59 9079 8192000000 10003642960 10400833536 1077767056 11.626 98 28 56 0 1115132720 31462368 5903296
08:28:09 31757 8192000000 9983016752 10386505728 1060047152 11.457 98 42 84 0 1090836576 21281664 9507760
08:29:48 8890 8192000000 9955314192 10339721216 1008468256 10.961 97 36 70 0 1041739616 25680720 7590640
08:31:00 8101 8192000000 9943152320 10327982080 998548896 10.864 98 32 63 2 1026975600 21379008 7047696
08:32:10 6065 8192000000 9950102928 10328997888 1029116336 11.160 100 39 78 0 1049841040 11187504 9537200
08:33:18 33150 8192000000 9968102032 10356539392 1042518224 11.289 100 47 94 0 1065922096 12103264 11300608
08:34:55 3919 8192000000 9983551664 10364014592 1057423584 11.432 100 53 107 1 1092182560 22833008 11925968
08:35:59 5148 8192000000 9962630048 10358620160 1067559952 11.529 100 24 47 0 1092455728 17220032 7675744
08:37:04 12193 8192000000 9992276560 10390331392 1083967248 11.685 100 31 64 0 1104638544 13816528 6854768
08:38:40 85492 8192000000 10060472624 10465869824 1113045136 11.961 100 32 64 2 1160656304 40155504 7455664
08:41:07 17431 8192000000 10095595568 10500001792 1129232768 12.114 100 34 68 1 1200538016 64493200 6812048
08:42:29 23184 8192000000 10168443872 10585812992 1142354336 12.238 100 45 91 0 1278502640 125643136 10505168
08:43:58 9979 8192000000 10183510768 10586914816 1137088800 12.188 100 63 125 1 1291033376 140566480 13378096
08:45:12 2302 8192000000 10193351856 10595667968 1153162240 12.339 99 71 141 0 1298972112 130570672 15239200
08:46:17 8710 8192000000 10226581872 10638364672 1164828496 12.448 100 59 118 1 1332322272 153944160 13549616
08:47:30 17349 8192000000 10238257904 10679209984 1167018192 12.469 100 53 106 2 1340957488 161917520 12021776
08:48:50 5198 8192000000 10224476960 10636185600 1161526480 12.418 100 63 126 1 1334469376 159126960 13815936
08:50:00 1365 8192000000 10266246144 10663497728 1148784640 12.298 100 90 181 0 1360963968 192199744 19979584
08:51:06 1406 8192000000 10277900176 10678423552 1152367232 12.332 100 82 163 2 1383590544 211426432 19796880
08:52:09 8039 8192000000 10309005584 10708336640 1137873536 12.196 100 77 154 2 1405115616 249777152 17464928
08:53:22 3501 8192000000 10350291696 10750775296 1147404992 12.285 100 77 154 2 1451680496 286089008 18186496
08:54:28 17049 8192000000 10321575008 10741846016 1151648512 12.325 99 44 87 1 1417087184 254533616 10905056
08:55:48 5139 8192000000 10285850960 10703941632 1143455840 12.248 99 60 121 2 1372792064 214695280 14640944
08:57:00 7317 8192000000 10296985536 10727141376 1156275024 12.368 100 52 103 2 1405059232 236811696 11972512
08:58:14 11468 8192000000 10344084912 10798665728 1145736400 12.269 99 41 82 1 1423460192 267498576 10225216
08:59:29 4230 8192000000 10360457536 10789347328 1144103744 12.254 100 66 132 1 1466166432 305346800 16715888
09:00:42 12778 8192000000 10339499008 10781929472 1132741280 12.147 99 37 74 0 1440887232 298798624 9347328
09:01:57 23121 8192000000 10292371968 10754179072 1131493664 12.135 100 25 50 1 1382416816 244568128 6355024
09:03:22 17562 8192000000 10281737312 10712215552 1118950144 12.017 100 21 42 1 1376551552 252144336 5457072
09:06:00 60324 8192000000 10070846016 10488774656 1107998976 11.913 98 29 56 0 1146029648 32505216 5525456
09:08:11 47210 8192000000 10140663168 10596474880 1116920176 11.998 98 17 32 0 1212275120 92212304 3142640
09:10:00 130555 8192000000 10082235152 10536906752 1140673648 12.222 100 24 48 0 1179519296 33431520 5414128
09:13:26 25939 8192000000 10078236768 10522615808 1124731984 12.072 98 31 62 1 1169415136 38577968 6105184
09:15:03 63366 8192000000 10196890480 10668273664 1137976912 12.196 98 35 69 0 1238745888 93086864 7682112
09:17:09 159929 8192000000 10108674064 10570346496 1143931456 12.252 99 23 46 0 1245697936 96730224 5036256
09:20:49 71164 8192000000 10155893680 10646757376 1165909904 12.459 100 13 26 1 1253258320 84292336 3056080
09:23:00 65421 8192000000 10065536448 10516676608 1111264800 11.944 96 20 40 0 1144563616 28810384 4488432
09:25:06 47284 8192000000 10112666448 10555863040 1142339488 12.238 99 43 86 0 1182530544 30861792 9329264
09:26:55 84339 8192000000 10090003120 10582962176 1149698928 12.307 99 16 29 2 1234132000 81150512 3282560
09:29:22 29964 8192000000 10121313744 10555535360 1103674496 11.872 95 22 44 0 1140979744 32782016 4523232
09:31:16 56448 8192000000 10156033440 10595352576 1130391856 12.125 98 27 54 0 1218642864 82352048 5898960
09:33:27 82098 8192000000 10129830448 10585931776 1104257712 11.878 96 26 52 0 1181107632 70840992 6008928
09:35:56 69098 8192000000 10069566608 10531463168 1102280304 11.859 97 10 20 1 1171903968 67405072 2218592
09:38:49 65835 8192000000 10067497776 10505977856 1137147184 12.189 100 24 48 1 1177897648 36182672 4567792
09:40:59 65415 8192000000 10053339104 10523041792 1111455088 11.946 97 29 59 1 1151325920 33071280 6799552
09:43:05 42083 8192000000 10048944096 10513985536 1112662912 11.958 99 11 22 0 1150571584 35862272 2046400
09:44:50 40769 8192000000 10101114816 10537869312 1103035648 11.866 97 21 42 0 1164196256 56676432 4484176
09:46:32 44260 8192000000 10110556656 10547953664 1132409840 12.144 99 36 72 1 1212348288 71239728 8698720
09:48:16 72914 8192000000 10165584624 10621890560 1126319456 12.087 99 38 76 1 1259348656 124544880 8484320
09:50:29 61351 8192000000 10288697440 10729832448 1125656464 12.080 98 28 56 2 1326302256 193442096 7203696
09:52:48 44687 8192000000 10105959888 10637541376 1108070800 11.914 98 15 30 1 1215944624 104756288 3117536
09:55:14 19882 8192000000 10075062496 10515353600 1126266928 12.086 100 30 60 0 1179431584 47174640 5990016
09:56:36 79465 8192000000 10068709696 10517172224 1094903872 11.789 97 35 70 1 1143485872 41385072 7196928
09:58:57 55149 8192000000 10056703632 10530193408 1112796880 11.959 99 11 21 1 1161731200 46854176 2080144
10:00:53 50470 8192000000 10048042992 10522374144 1119151632 12.019 99 9 19 1 1155933856 35135152 1647072
10:02:44 53897 8192000000 10082868016 10565873664 1130037024 12.122 100 21 42 1 1201238832 66666368 4535440
10:05:19 72855 8192000000 10149051392 10601000960 1145180448 12.264 99 21 42 0 1219730224 69867952 4681824
10:07:32 121674 8192000000 10080616640 10584383488 1120323664 12.030 96 21 42 1 1236085568 111153296 4608608
10:10:36 60086 8192000000 10138275184 10642579456 1161376800 12.416 100 31 62 0 1233627040 66139120 6111120
10:13:02 58980 8192000000 10069979744 10544926720 1100577040 11.843 96 15 30 0 1143476176 39704112 3195024
10:15:10 117273 8192000000 10075367392 10555748352 1098602496 11.824 96 25 50 1 1181928624 78008240 5317888
10:18:09 70454 8192000000 10083373728 10574917632 1127711440 12.100 99 10 20 1 1205535360 75706384 2117536
10:20:51 48809 8192000000 10112117824 10558939136 1145616992 12.268 100 18 36 1 1201021056 51068432 4335632
10:22:41 77012 8192000000 10092265280 10546614272 1128964688 12.112 98 16 32 0 1188721488 56470688 3286112
10:25:07 96108 8192000000 10096495072 10580893696 1100282560 11.840 96 18 36 0 1167839376 63695584 3861232
10:27:45 55176 8192000000 10078172992 10528956416 1144905952 12.262 100 20 40 1 1178392528 30070976 3415600
10:29:41 74836 8192000000 10086874496 10547105792 1093342848 11.774 96 53 106 1 1173426128 69811920 10271360
10:31:57 102326 8192000000 10060133504 10494287872 1097856512 11.817 96 27 54 0 1169969968 66689152 5424304
10:34:48 27534 8192000000 10051532880 10501808128 1114540624 11.975 99 20 39 1 1155560592 37079728 3940240
10:36:21 34549 8192000000 10062041856 10528849920 1096238112 11.802 97 6 12 0 1146428800 49443632 747056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-10-2021 10:39:44 to 30-10-2021 10:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-10-2021 10:40:00 to 30-10-2021 10:40:02.

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          237198
MON$OLDEST_ACTIVE               239788
MON$OLDEST_SNAPSHOT             239788
MON$NEXT_TRANSACTION            239788
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                       2056416
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2648 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2648 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2648 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2648 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Oct 30 10:37:13 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Oct 30 10:37:14 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

30.10.21 10:40:03. 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-10-30 10:40:05 No severe PSQL-related problems occured
30.10.21 10:40:07. End of report.