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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16649864 kB
MemAvailable 31067656 kB
Buffers 0 kB
Cached 15003704 kB
SwapCached 3268 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-26 10:26:57
DTS_END                         2021-02-26 10:26:57.7640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37240
TRN_ID                          221649
ATT_ID                          585
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: 8226.13 27290 987135
customer order: creation 155.25 437 18630
customer order: refuse 13.60 391 1632
order to supplier: creation 41.91 1533 5029
order to supplier: removal 3.13 138164 375
invoice (draft): creation 42.41 4353 5089
invoice (draft): removal 46.80 1187 5616
invoice accept: apply 37.97 19926 4556
invoice accept: cancel 42.41 1187 5089
customer reserve: creation 3458.96 196 415075
customer reserve: removal 4113.83 83 493659
realization accept: apply 75.78 192 9093
realization accept: cancel 78.25 128 9390
payment from customer: creation 53.85 115 6462
payment from customer: removal 5.19 89 623
payment to supplier: creation 43.48 127 5218
payment to supplier: removal 3.18 73 382
service: total inventory turnovers 3.59 4220 431
service: total monetary turnovers 5.73 735 688
service: aggregate perf. data 0.78 19383 94
service: refresh index statistics 0.03 353286 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-02-2021 10:28:14 to 26-02-2021 10:28:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2907
TEST_TIME 2 3367
TEST_TIME 3 3244
TEST_TIME 4 4534
TEST_TIME 5 4758
TEST_TIME 6 4721
TEST_TIME 7 4865
TEST_TIME 8 5377
TEST_TIME 9 5444
TEST_TIME 10 5448
TEST_TIME 11 5636
TEST_TIME 12 5858
TEST_TIME 13 5854
TEST_TIME 14 5980
TEST_TIME 15 6112
TEST_TIME 16 6331
TEST_TIME 17 6381
TEST_TIME 18 6647
TEST_TIME 19 6840
TEST_TIME 20 6741
TEST_TIME 21 6838
TEST_TIME 22 6803
TEST_TIME 23 7060
TEST_TIME 24 7319
TEST_TIME 25 7209
TEST_TIME 26 7359
TEST_TIME 27 7542
TEST_TIME 28 8156
TEST_TIME 29 8426
TEST_TIME 30 9857
TEST_TIME 31 10064
TEST_TIME 32 9818
TEST_TIME 33 9869
TEST_TIME 34 9973
TEST_TIME 35 9760
TEST_TIME 36 9680
TEST_TIME 37 9544
TEST_TIME 38 9388
TEST_TIME 39 9271
TEST_TIME 40 9176
TEST_TIME 41 9099
TEST_TIME 42 8982
TEST_TIME 43 8977
TEST_TIME 44 9110
TEST_TIME 45 9071
TEST_TIME 46 9035
TEST_TIME 47 8962
TEST_TIME 48 8938
TEST_TIME 49 8803
TEST_TIME 50 8830
TEST_TIME 51 8743
TEST_TIME 52 8710
TEST_TIME 53 8605
TEST_TIME 54 8544
TEST_TIME 55 8585
TEST_TIME 56 8488
TEST_TIME 57 8408
TEST_TIME 58 8331
TEST_TIME 59 8289
TEST_TIME 60 8213
TEST_TIME 61 8148
TEST_TIME 62 8180
TEST_TIME 63 8210
TEST_TIME 64 8243
TEST_TIME 65 8218
TEST_TIME 66 8282
TEST_TIME 67 8332
TEST_TIME 68 8304
TEST_TIME 69 8378
TEST_TIME 70 8370
TEST_TIME 71 8397
TEST_TIME 72 8412
TEST_TIME 73 8456
TEST_TIME 74 8573
TEST_TIME 75 8605
TEST_TIME 76 8543
TEST_TIME 77 8444
TEST_TIME 78 8406
TEST_TIME 79 8455
TEST_TIME 80 8450
TEST_TIME 81 8390
TEST_TIME 82 8327
TEST_TIME 83 8356
TEST_TIME 84 8361
TEST_TIME 85 8398
TEST_TIME 86 8402
TEST_TIME 87 8427
TEST_TIME 88 8422
TEST_TIME 89 8457
TEST_TIME 90 8440
TEST_TIME 91 8472
TEST_TIME 92 8468
TEST_TIME 93 8549
TEST_TIME 94 8551
TEST_TIME 95 8551
TEST_TIME 96 8473
TEST_TIME 97 8429
TEST_TIME 98 8393
TEST_TIME 99 8390
TEST_TIME 100 8339
TEST_TIME 101 8306
TEST_TIME 102 8298
TEST_TIME 103 8365
TEST_TIME 104 8361
TEST_TIME 105 8370
TEST_TIME 106 8360
TEST_TIME 107 8368
TEST_TIME 108 8404
TEST_TIME 109 8409
TEST_TIME 110 8456
TEST_TIME 111 8456
TEST_TIME 112 8436
TEST_TIME 113 8377
TEST_TIME 114 8327
TEST_TIME 115 8331
TEST_TIME 116 8281
TEST_TIME 117 8234
TEST_TIME 118 8259
TEST_TIME 119 8232
TEST_TIME 120 8229

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-02-2021 10:28:18 to 26-02-2021 10:28:21.

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 1371163 1371163 0 0.00 0 14951 7 0 0
doc_list_biud 1371163 1371163 0 0.00 0 80046 38 0 0
sp_add_invoice_to_stock 4708 4556 152 3.22 20 173571 19926 0 152
sp_cancel_adding_invoice 5089 5089 0 0.00 4 109219 1187 0 0
sp_cancel_client_order 1632 1632 0 0.00 4 10234 391 0 0
sp_cancel_customer_reserve 493659 493659 0 0.00 1 80047 83 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 3394 90 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 2 5272 74 0 0
sp_cancel_supplier_invoice 5625 5616 9 0.16 2 40988 1187 0 9
sp_cancel_supplier_order 375 375 0 0.00 8 1230099 138164 0 0
sp_cancel_write_off 9390 9390 0 0.00 1 11144 129 0 0
sp_client_order 18630 18630 0 0.00 4 17806 438 0 0
sp_customer_reserve 415075 415075 0 0.00 0 56183 196 0 0
sp_fill_shopping_cart 28742 28742 0 0.00 1 27429 54 0 0
sp_fill_shopping_cart_clo_res 415062 415062 0 0.00 0 30468 23 0 0
sp_get_clo_for_invoice 4543 4543 0 0.00 0 55966 513 0 0
sp_kill_cost_storno 15484 15484 0 0.00 0 14919 67 0 0
sp_kill_qstorno_ret_qs2qd 1002699 1002699 0 0.00 0 80045 45 0 0
sp_kill_qty_storno 529397 529397 0 0.00 0 80046 97 0 0
sp_lock_selected_doc 18939 18939 0 0.00 0 11126 6 0 0
sp_make_cost_storno 25316 25316 0 0.00 0 8697 62 0 0
sp_multiply_rows_for_pdistr 25316 25316 0 0.00 0 7227 35 0 0
sp_multiply_rows_for_qdistr 18630 18630 0 0.00 0 15439 259 0 0
sp_pay_from_customer 6462 6462 0 0.00 4 11800 115 0 0
sp_pay_to_supplier 5218 5218 0 0.00 5 11508 127 0 0
sp_payment_common 11680 11680 0 0.00 1 8204 21 0 0
sp_qd_handle_on_cancel_clo 1632 1632 0 0.00 0 10223 361 0 0
sp_qd_handle_on_invoice_upd_sts 9632 9632 0 0.00 0 40554 478 0 0
sp_qd_handle_on_reserve_upd_sts 9093 9093 0 0.00 0 32764 66 0 0
sp_reserve_write_off 9093 9093 0 0.00 7 33186 193 0 0
sp_supplier_invoice 5089 5089 0 0.00 7 97607 4353 0 0
sp_supplier_order 5029 5029 0 0.00 5 57201 1534 0 0
srv_aggregate_perf_data 94 94 0 0.00 2694 475294 19384 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1219 151738 44505 0 0
srv_find_qd_qs_mism 943946 943946 0 0.00 0 13752 6 0 0
srv_make_invnt_saldo 431 431 0 0.00 45 137068 4221 0 0
srv_make_money_saldo 688 688 0 0.00 0 18674 735 0 0
srv_recalc_idx_stat 4 4 0 0.00 196759 647428 353287 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4556 4556 0 0.00 0 10706 187 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 2895 122 0 0
t$perf-norm:sp_cancel_client_order 1632 1632 0 0.00 0 2904 13 0 0
t$perf-norm:sp_cancel_customer_reserve 596 596 0 0.00 0 1874 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 5381 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 630 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 759 15 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 27466 1674 0 0
t$perf-norm:sp_cancel_write_off 878 878 0 0.00 0 3004 9 0 0
t$perf-norm:sp_client_order 18630 18630 0 0.00 0 6702 18 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 2919 11 0 0
t$perf-norm:sp_pay_from_customer 6462 6462 0 0.00 0 8954 14 0 0
t$perf-norm:sp_pay_to_supplier 5218 5218 0 0.00 0 5487 12 0 0
t$perf-norm:sp_reserve_write_off 9093 9093 0 0.00 0 6348 9 0 0
t$perf-norm:sp_supplier_invoice 5089 5089 0 0.00 0 2292 15 0 0
t$perf-norm:sp_supplier_order 5029 5029 0 0.00 0 7063 22 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 1042 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 852 12 0 0
t$perf-norm:srv_make_money_saldo 688 688 0 0.00 0 218 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 10 7 0 0
v_add_invoice_to_stock 9390 9086 304 3.23 0 2736 16 0 304
v_all_customers 34124 34124 0 0.00 0 17 1 0 0
v_all_suppliers 27448 27448 0 0.00 0 51 1 0 0
v_all_wares 37260 37260 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 2082 12 0 0
v_cancel_client_order 3264 3264 0 0.00 0 2756 9 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 1831 9 0 0
v_cancel_customer_reserve 1192 1192 0 0.00 0 1351 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 404 4 0 0
v_cancel_supplier_invoice 854 836 18 2.10 0 1376 17 0 18
v_cancel_supplier_order 750 750 0 0.00 0 772 6 0 0
v_cancel_write_off 1756 1756 0 0.00 0 3287 16 0 0
v_max_id_clo_ord 5029 5029 0 0.00 0 775 1 0 0
v_max_id_clo_res 930 930 0 0.00 0 1679 11 0 0
v_max_id_ord_sup 5083 5083 0 0.00 0 5546 8 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 198 2 0 0
v_max_non_paid_realizn 3320 3320 0 0.00 0 1215 3 0 0
v_min_id_clo_ord 5029 5029 0 0.00 0 2305 6 0 0
v_min_id_clo_res 930 930 0 0.00 0 4038 15 0 0
v_min_id_ord_sup 5083 5083 0 0.00 0 2212 4 0 0
v_min_non_paid_invoice 5218 5218 0 0.00 0 6097 12 0 0
v_min_non_paid_realizn 6462 6462 0 0.00 0 8103 16 0 0
v_our_firm 9420 9420 0 0.00 0 15 1 0 0
v_reserve_write_off 18186 18186 0 0.00 0 2708 5 0 0
x_lock_depdocs_on_canc_invoice 10287 10287 0 0.00 0 20602 141 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 25013 527 0 0
x_make_qstorno_1000_1200 5029 5029 0 0.00 2 57004 1443 0 0
x_make_qstorno_1200_2000 5089 5089 0 0.00 3 97598 4143 0 0
x_make_qstorno_2100_3300 415075 415075 0 0.00 0 45421 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-02-2021 10:28:22 to 26-02-2021 10:28:26.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:57:01 1081 8192000000 8949239808 9010057216 10165328 0.123 3 2 4 0 10542944 118384 259232
07:58:04 33011 8192000000 9724448016 10021203968 796692112 8.863 100 90 179 0 826400976 7586672 22122192
07:59:43 35461 8192000000 9762186912 10067001344 822900160 9.128 100 86 172 2 857328224 13893680 20534384
08:01:27 25242 8192000000 9819580176 10136395776 865095840 9.551 100 80 160 0 909175168 25668800 18410528
08:02:59 12984 8192000000 9883172176 10213666816 916039552 10.057 100 78 157 1 980895184 47997840 16857792
08:04:16 8234 8192000000 9924867168 10268069888 952754400 10.418 100 85 170 2 1036635264 65371568 18509296
08:05:27 6573 8192000000 9971894128 10326708224 987676800 10.759 100 78 154 0 1075322480 71498528 16147152
08:06:35 2718 8192000000 10037992784 10395770880 1010587744 10.981 100 91 182 1 1129267232 100180208 18499280
08:07:39 2458 8192000000 10022120256 10385145856 1034662784 11.213 100 86 169 0 1116113744 64094272 17356688
08:08:43 1439 8192000000 10036965936 10405064704 1054498480 11.404 100 93 190 1 1132315456 58392288 19424688
08:09:48 10565 8192000000 9979242448 10365677568 1055169248 11.410 100 32 63 2 1100383904 38033024 7181632
08:10:59 5856 8192000000 10003537664 10398584832 1064063360 11.495 100 42 83 2 1103563264 31158496 8341408
08:12:05 3123 8192000000 10000505680 10389917696 1071841584 11.570 100 50 96 1 1106951200 24508928 10600688
08:13:11 64568 8192000000 10004636176 10411409408 1073769840 11.588 100 42 82 2 1094117552 11827968 8519744
08:15:23 4038 8192000000 10026112960 10419302400 1083732240 11.683 100 43 86 0 1124849232 31096528 10020464
08:16:28 3877 8192000000 10005185392 10393231360 1071049904 11.562 99 57 112 1 1102059536 17520880 13488752
08:17:37 5874 8192000000 9990157328 10388709376 1056759856 11.425 98 34 68 0 1079702960 15089776 7853328
08:18:47 13060 8192000000 9992734080 10399969280 1054590784 11.405 99 26 52 0 1088930432 29464272 4875376
08:20:00 5459 8192000000 9990463392 10379898880 1020133040 11.073 99 48 95 1 1053456400 23226576 10096784
08:21:07 4791 8192000000 9928085776 10308632576 980212432 10.686 96 26 52 1 1025202144 39052640 5937072
08:22:13 57369 8192000000 9932399808 10308182016 973374608 10.620 95 42 84 1 1000619200 15970256 11274336
08:24:11 4443 8192000000 9975320592 10360086528 1039368000 11.259 99 41 81 0 1075996832 27869024 8759808
08:25:16 7146 8192000000 9980097280 10382540800 1055994720 11.418 100 44 87 1 1093077616 27652224 9430672
08:26:26 5519 8192000000 9988338272 10382069760 1065448432 11.509 100 40 79 3 1099271808 25242704 8580672
08:27:35 50950 8192000000 10019139200 10416930816 1084655280 11.692 100 54 106 1 1113519088 17149808 11714000
08:29:33 33691 8192000000 10098286896 10507710464 1109354848 11.926 100 54 107 0 1196533808 76760624 10418336
08:31:09 14081 8192000000 10084927024 10490068992 1123404560 12.059 100 47 94 0 1182720304 49129584 10186160
08:32:29 6652 8192000000 10116884112 10527784960 1141251408 12.227 100 54 108 0 1226203520 73829680 11122432
08:33:47 15624 8192000000 10162196752 10564714496 1141903040 12.233 100 62 122 0 1266342496 111135184 13304272
08:35:06 19463 8192000000 10138529888 10555109376 1142056432 12.235 99 54 108 2 1244072640 90165552 11850656
08:36:31 6597 8192000000 10190663840 10607595520 1155044480 12.357 99 66 130 4 1299528496 130468832 14015184
08:37:43 5045 8192000000 10219861472 10641477632 1172733488 12.522 100 61 122 1 1327774848 141748896 13292464
08:38:54 1596 8192000000 10243736032 10648186880 1175582240 12.549 100 86 172 0 1348347600 154362240 18403120
08:40:03 6068 8192000000 10292341520 10700611584 1170542704 12.502 99 74 147 1 1381748256 195068960 16136592
08:41:17 1434 8192000000 10294650992 10695958528 1159605328 12.400 100 81 160 1 1396554416 218161728 18787360
08:42:23 1217 8192000000 10323815600 10723926016 1153918336 12.346 99 83 161 0 1422355200 251385392 17051472
08:43:32 8172 8192000000 10352925584 10764042240 1156012016 12.366 100 73 146 0 1452324752 279426080 16886656
08:44:48 3131 8192000000 10344919200 10764808192 1169842960 12.495 100 80 160 1 1460791376 272188336 18760080
08:45:57 5423 8192000000 10382736976 10790936576 1154037392 12.347 99 63 125 0 1472449088 303721632 14690064
08:47:09 3875 8192000000 10435111712 10844094464 1162838704 12.430 99 63 123 0 1545626672 367629152 15158816
08:48:19 5289 8192000000 10506419936 10918301696 1169297920 12.490 100 66 132 2 1602646720 416663264 16685536
08:49:28 9662 8192000000 10493610800 10914168832 1155440928 12.361 99 50 98 0 1581613408 413954960 12217520
08:50:47 8000 8192000000 10542393872 11007049728 1165943328 12.459 100 51 102 0 1626828352 448063280 12821744
08:52:00 20428 8192000000 10571922800 11016892416 1162754352 12.429 100 43 86 1 1676904016 502513552 11636112
08:53:30 23470 8192000000 10552920048 11005906944 1154883600 12.355 100 36 72 0 1657708240 492556400 10268240
08:55:00 12820 8192000000 10461962624 10900541440 1120996928 12.036 100 23 46 0 1565630752 437589008 7044816
08:56:15 16459 8192000000 10312677920 10811936768 1096096256 11.801 97 37 74 0 1430961824 325681344 9184224
08:57:39 31223 8192000000 10123763184 10551570432 1137218176 12.189 100 22 45 1 1197119216 54849936 5051104
08:59:11 33159 8192000000 10109494368 10555138048 1117907776 12.007 100 23 46 0 1215386320 92278992 5199552
09:00:49 40110 8192000000 10058982368 10483576832 1116526096 11.994 100 43 83 2 1155931040 30803136 8601808
09:05:30 32203 8192000000 10140498432 10581921792 1149919232 12.309 100 25 50 1 1234650544 79576560 5154752
09:07:04 59995 8192000000 10156523136 10587717632 1120577824 12.032 98 18 36 0 1238968160 114116928 4273408
09:09:22 73082 8192000000 10118073104 10634567680 1125532048 12.079 98 24 48 1 1251924848 120893200 5499600
09:11:36 49817 8192000000 10114650576 10587652096 1118708688 12.015 99 15 30 1 1201303200 79130704 3463808
09:13:46 98589 8192000000 10074009808 10538569728 1125502960 12.079 99 17 34 1 1198187888 68924384 3760544
09:16:27 27694 8192000000 10093291712 10549633024 1137454224 12.192 100 40 80 1 1198022208 52594320 7973664
09:18:05 67537 8192000000 10096940128 10571235328 1132205344 12.142 99 10 20 0 1177766528 42952672 2608512
09:20:59 57344 8192000000 10061881808 10559934464 1124564992 12.070 98 13 26 0 1179572400 51895472 3111936
09:22:56 102418 8192000000 10062800096 10541740032 1148574640 12.296 100 14 28 0 1172358768 20704208 3079920
09:25:40 58079 8192000000 10073540272 10529726464 1118377168 12.012 97 16 31 1 1159416736 37961888 3077680
09:27:44 60736 8192000000 10097474400 10615652352 1113493216 11.965 97 13 26 1 1204266240 88004464 2768560
09:29:48 58059 8192000000 10045346880 10506784768 1102379072 11.860 97 17 34 0 1124948688 19047504 3522112
09:31:48 55260 8192000000 10072661328 10551504896 1079040864 11.638 95 25 51 0 1145090336 60488048 5561424
09:33:51 31213 8192000000 10072007712 10503327744 1135840784 12.176 100 38 75 2 1174322720 30197040 8284896
09:35:24 110320 8192000000 10075484736 10531033088 1068302512 11.536 93 11 22 0 1124713776 54229712 2181552
09:38:15 41022 8192000000 10088143936 10523136000 1114933440 11.979 98 20 41 1 1177208512 57356240 4918832
09:40:06 45652 8192000000 10083064896 10580811776 1108469952 11.918 99 13 26 1 1183841984 72571920 2800112
09:43:01 151738 8192000000 10111802416 10604457984 1136772688 12.185 100 31 62 1 1203665648 60563600 6329360
09:46:34 82690 8192000000 10081781840 10619650048 1097991248 11.819 97 7 14 0 1165128432 65789904 1347280
09:49:00 50327 8192000000 10044766432 10521427968 1121847920 12.044 100 16 32 0 1182422112 56691872 3882320
09:50:50 64231 8192000000 10065538944 10538848256 1095903168 11.799 97 12 24 0 1148474832 50026400 2545264
09:53:05 68568 8192000000 10088443584 10553569280 1121862336 12.045 99 21 42 1 1183059936 56836496 4361104
09:55:15 60605 8192000000 10072329344 10502930432 1095549904 11.795 96 22 44 2 1149484080 49686528 4247648
09:57:18 54091 8192000000 10079078272 10529927168 1086132224 11.706 95 17 34 0 1122576240 33047648 3396368
09:59:12 64458 8192000000 10069976944 10540417024 1102710336 11.863 97 20 39 2 1164092304 57744720 3637248
10:01:17 118736 8192000000 10049815888 10514280448 1117011888 11.999 98 19 38 0 1148675648 27670496 3993264
10:04:36 62602 8192000000 10088011472 10571632640 1126209520 12.086 99 10 20 1 1181130560 52952416 1968624
10:06:40 66844 8192000000 10127039760 10566041600 1119313968 12.021 98 17 34 0 1192921232 69517856 4089408
10:09:19 73272 8192000000 10070650608 10536013824 1111901712 11.950 98 15 30 0 1154949232 39959200 3088320
10:11:44 73643 8192000000 10118484848 10561470464 1119844608 12.026 98 19 38 1 1167223264 43430560 3948096
10:14:15 42367 8192000000 10040668368 10482073600 1069608608 11.548 94 25 50 0 1106872048 32107168 5156272
10:15:58 20175 8192000000 10135652064 10618871808 1130359360 12.125 100 11 22 2 1231933136 99394560 2179216
10:17:23 56985 8192000000 10058011360 10506534912 1077618112 11.625 95 17 34 0 1122090128 41188720 3283296
10:19:21 135287 8192000000 10063219536 10535669760 1111050176 11.942 99 26 52 0 1192802368 75764864 5987328
10:23:26 53138 8192000000 10075570192 10553679872 1123944784 12.064 100 11 22 0 1188240816 61900752 2395280
10:25:43 46851 8192000000 10107629760 10565488640 1135730368 12.175 100 23 46 1 1185234448 44534960 4969120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-02-2021 10:28:28 to 26-02-2021 10:28:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 152 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-02-2021 10:28:44 to 26-02-2021 10:28:46.

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          220140
MON$OLDEST_ACTIVE               222535
MON$OLDEST_SNAPSHOT             222535
MON$NEXT_TRANSACTION            222535
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                       2076000
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

26.02.21 10:28:47. 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-26 10:28:49 No severe PSQL-related problems occured
26.02.21 10:28:50. End of report.