Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2020 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 16678384 kB
MemAvailable 31394840 kB
Buffers 0 kB
Cached 14949576 kB
SwapCached 3456 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 2020-11-14 10:26:50
DTS_END                         2020-11-14 10:26:50.2420
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/50218
TRN_ID                          232994
ATT_ID                          656
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: 9240.70 25652 1108884
customer order: creation 163.55 415 19626
customer order: refuse 14.43 311 1731
order to supplier: creation 45.13 1294 5415
order to supplier: removal 3.53 121346 423
invoice (draft): creation 44.24 3574 5309
invoice (draft): removal 54.03 1016 6483
invoice accept: apply 39.91 17499 4789
invoice accept: cancel 49.08 950 5889
customer reserve: creation 3730.49 167 447659
customer reserve: removal 4796.28 69 575554
realization accept: apply 80.45 180 9654
realization accept: cancel 97.30 111 11676
payment from customer: creation 57.37 105 6884
payment from customer: removal 5.81 102 697
payment to supplier: creation 45.72 135 5486
payment to supplier: removal 3.28 117 393
service: total inventory turnovers 3.83 3618 459
service: total monetary turnovers 5.58 658 669
service: aggregate perf. data 0.70 22610 84
service: refresh index statistics 0.03 338756 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2020 10:27:56 to 14-11-2020 10:27:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1703
TEST_TIME 2 2037
TEST_TIME 3 2740
TEST_TIME 4 3436
TEST_TIME 5 3835
TEST_TIME 6 4387
TEST_TIME 7 4661
TEST_TIME 8 5023
TEST_TIME 9 4895
TEST_TIME 10 4904
TEST_TIME 11 5554
TEST_TIME 12 5803
TEST_TIME 13 6034
TEST_TIME 14 5928
TEST_TIME 15 6358
TEST_TIME 16 6392
TEST_TIME 17 6845
TEST_TIME 18 6980
TEST_TIME 19 7259
TEST_TIME 20 7207
TEST_TIME 21 8243
TEST_TIME 22 8391
TEST_TIME 23 8545
TEST_TIME 24 8725
TEST_TIME 25 8625
TEST_TIME 26 8793
TEST_TIME 27 9318
TEST_TIME 28 10865
TEST_TIME 29 10758
TEST_TIME 30 10615
TEST_TIME 31 10690
TEST_TIME 32 10522
TEST_TIME 33 10347
TEST_TIME 34 10305
TEST_TIME 35 10038
TEST_TIME 36 10156
TEST_TIME 37 10268
TEST_TIME 38 10119
TEST_TIME 39 10057
TEST_TIME 40 9961
TEST_TIME 41 10143
TEST_TIME 42 10031
TEST_TIME 43 9980
TEST_TIME 44 10019
TEST_TIME 45 9904
TEST_TIME 46 9912
TEST_TIME 47 9872
TEST_TIME 48 10052
TEST_TIME 49 10020
TEST_TIME 50 9996
TEST_TIME 51 9979
TEST_TIME 52 9908
TEST_TIME 53 9920
TEST_TIME 54 10088
TEST_TIME 55 10247
TEST_TIME 56 10216
TEST_TIME 57 10094
TEST_TIME 58 9939
TEST_TIME 59 9922
TEST_TIME 60 9861
TEST_TIME 61 9778
TEST_TIME 62 9689
TEST_TIME 63 9699
TEST_TIME 64 9694
TEST_TIME 65 9635
TEST_TIME 66 9669
TEST_TIME 67 9686
TEST_TIME 68 9686
TEST_TIME 69 9684
TEST_TIME 70 9734
TEST_TIME 71 9743
TEST_TIME 72 9761
TEST_TIME 73 9766
TEST_TIME 74 9828
TEST_TIME 75 9908
TEST_TIME 76 9888
TEST_TIME 77 9828
TEST_TIME 78 9738
TEST_TIME 79 9660
TEST_TIME 80 9600
TEST_TIME 81 9588
TEST_TIME 82 9591
TEST_TIME 83 9602
TEST_TIME 84 9569
TEST_TIME 85 9517
TEST_TIME 86 9549
TEST_TIME 87 9562
TEST_TIME 88 9516
TEST_TIME 89 9546
TEST_TIME 90 9576
TEST_TIME 91 9648
TEST_TIME 92 9619
TEST_TIME 93 9626
TEST_TIME 94 9585
TEST_TIME 95 9515
TEST_TIME 96 9456
TEST_TIME 97 9403
TEST_TIME 98 9416
TEST_TIME 99 9406
TEST_TIME 100 9389
TEST_TIME 101 9384
TEST_TIME 102 9373
TEST_TIME 103 9413
TEST_TIME 104 9387
TEST_TIME 105 9478
TEST_TIME 106 9493
TEST_TIME 107 9477
TEST_TIME 108 9559
TEST_TIME 109 9548
TEST_TIME 110 9517
TEST_TIME 111 9517
TEST_TIME 112 9439
TEST_TIME 113 9397
TEST_TIME 114 9349
TEST_TIME 115 9296
TEST_TIME 116 9278
TEST_TIME 117 9258
TEST_TIME 118 9282
TEST_TIME 119 9281
TEST_TIME 120 9244

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-11-2020 10:27:59 to 14-11-2020 10:28:03.

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 1522972 1522972 0 0.00 0 16487 6 0 0
doc_list_biud 1522972 1522972 0 0.00 0 79673 33 0 0
sp_add_invoice_to_stock 4916 4789 127 2.58 19 170683 17499 0 127
sp_cancel_adding_invoice 5889 5889 0 0.00 5 72980 951 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 8859 311 0 0
sp_cancel_customer_reserve 575554 575554 0 0.00 1 79674 70 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 14592 102 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5175 117 0 0
sp_cancel_supplier_invoice 6494 6483 11 0.16 2 53185 1017 0 11
sp_cancel_supplier_order 423 423 0 0.00 6 1109258 121346 0 0
sp_cancel_write_off 11676 11676 0 0.00 1 14969 112 0 0
sp_client_order 19626 19626 0 0.00 4 35220 415 0 0
sp_customer_reserve 447659 447659 0 0.00 0 50031 167 0 0
sp_fill_shopping_cart 30333 30333 0 0.00 1 20158 42 0 0
sp_fill_shopping_cart_clo_res 447655 447655 0 0.00 0 26106 19 0 0
sp_get_clo_for_invoice 4785 4785 0 0.00 0 41155 448 0 0
sp_kill_cost_storno 18655 18655 0 0.00 0 16480 63 0 0
sp_kill_qstorno_ret_qs2qd 1169690 1169690 0 0.00 0 79668 38 0 0
sp_kill_qty_storno 616195 616195 0 0.00 0 79673 81 0 0
sp_lock_selected_doc 20168 20168 0 0.00 0 8664 8 0 0
sp_make_cost_storno 26809 26809 0 0.00 0 15369 53 0 0
sp_multiply_rows_for_pdistr 26809 26809 0 0.00 0 7151 30 0 0
sp_multiply_rows_for_qdistr 19626 19626 0 0.00 0 35169 250 0 0
sp_pay_from_customer 6884 6884 0 0.00 4 10555 106 0 0
sp_pay_to_supplier 5486 5486 0 0.00 5 13110 135 0 0
sp_payment_common 12370 12370 0 0.00 1 10551 24 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 8852 277 0 0
sp_qd_handle_on_invoice_upd_sts 10674 10674 0 0.00 0 17651 378 0 0
sp_qd_handle_on_reserve_upd_sts 9654 9654 0 0.00 0 13111 43 0 0
sp_reserve_write_off 9654 9654 0 0.00 7 17735 180 0 0
sp_supplier_invoice 5309 5309 0 0.00 6 81576 3574 0 0
sp_supplier_order 5415 5415 0 0.00 5 56595 1294 0 0
srv_aggregate_perf_data 84 84 0 0.00 3070 436310 22610 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1231 125546 47353 0 0
srv_find_qd_qs_mism 1062862 1062862 0 0.00 0 11259 5 0 0
srv_make_invnt_saldo 459 459 0 0.00 46 100633 3619 0 0
srv_make_money_saldo 669 669 0 0.00 1 17124 658 0 0
srv_recalc_idx_stat 4 4 0 0.00 181080 531280 338757 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4789 4789 0 0.00 0 15563 179 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 7230 110 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 3458 11 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 21216 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 296 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 923 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 244 7 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 23921 1472 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 4722 11 0 0
t$perf-norm:sp_client_order 19626 19626 0 0.00 0 20627 20 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 2096 6 0 0
t$perf-norm:sp_pay_from_customer 6884 6884 0 0.00 0 3064 7 0 0
t$perf-norm:sp_pay_to_supplier 5486 5486 0 0.00 0 7832 9 0 0
t$perf-norm:sp_reserve_write_off 9654 9654 0 0.00 0 4606 8 0 0
t$perf-norm:sp_supplier_invoice 5309 5309 0 0.00 0 6630 17 0 0
t$perf-norm:sp_supplier_order 5415 5415 0 0.00 0 4166 13 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 478 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 3205 10 0 0
t$perf-norm:srv_make_money_saldo 669 669 0 0.00 0 697 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 96 26 0 0
v_add_invoice_to_stock 9824 9570 254 2.58 0 6524 21 0 254
v_all_customers 36038 36038 0 0.00 0 21 1 0 0
v_all_suppliers 29434 29434 0 0.00 0 91 1 0 0
v_all_wares 39252 39252 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 720 7 0 0
v_cancel_client_order 3462 3462 0 0.00 0 4415 9 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 5920 12 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 6207 11 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 4242 14 0 0
v_cancel_supplier_invoice 798 776 22 2.75 0 6111 35 0 22
v_cancel_supplier_order 846 846 0 0.00 0 872 5 0 0
v_cancel_write_off 1948 1948 0 0.00 0 1627 13 0 0
v_max_id_clo_ord 5414 5414 0 0.00 0 2193 3 0 0
v_max_id_clo_res 937 937 0 0.00 0 1629 12 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 4594 5 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 827 3 0 0
v_max_non_paid_realizn 3607 3607 0 0.00 0 3003 5 0 0
v_min_id_clo_ord 5414 5414 0 0.00 0 3396 6 0 0
v_min_id_clo_res 937 937 0 0.00 0 6385 25 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 5782 6 0 0
v_min_non_paid_invoice 5486 5486 0 0.00 0 6409 14 0 0
v_min_non_paid_realizn 6884 6884 0 0.00 0 10546 17 0 0
v_our_firm 9768 9768 0 0.00 0 14 1 0 0
v_reserve_write_off 19308 19308 0 0.00 0 5619 9 0 0
x_lock_depdocs_on_canc_invoice 11984 11984 0 0.00 0 27795 118 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 5708 353 0 0
x_make_qstorno_1000_1200 5415 5415 0 0.00 2 56585 1208 0 0
x_make_qstorno_1200_2000 5309 5309 0 0.00 3 81569 3411 0 0
x_make_qstorno_2100_3300 447659 447659 0 0.00 0 37030 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2020 10:28:03 to 14-11-2020 10:28:07.

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:56:54 1 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:55 33213 8192000000 9724620672 10021969920 800730528 8.904 100 83 166 1 828228960 7206688 20291744
07:59:33 23024 8192000000 9761853376 10067881984 835885120 9.258 100 86 172 0 868731504 12065824 20780560
08:01:14 20074 8192000000 9816770384 10139729920 884246656 9.742 100 76 151 1 922096320 20739328 17110336
08:02:39 14963 8192000000 9875660416 10212720640 930266256 10.197 100 71 142 1 981265328 36139008 14860064
08:04:00 8112 8192000000 9926624560 10275811328 967165840 10.559 100 71 140 1 1039668160 58457568 14044752
08:05:11 5682 8192000000 9980597536 10332696576 993722080 10.818 100 81 155 0 1084075792 75132320 15221392
08:06:19 2652 8192000000 9994928352 10356817920 1028505744 11.154 100 89 176 2 1112924304 66813856 17604704
08:07:23 2042 8192000000 10029791264 10399268864 1054642352 11.405 100 88 177 0 1137362992 65242528 17478112
08:08:26 1535 8192000000 10063780448 10433073152 1070305616 11.555 100 95 188 0 1179344416 88705472 20333328
08:09:30 2495 8192000000 10034031552 10421223424 1074211376 11.592 100 58 114 1 1143861136 58153600 11496160
08:10:35 10084 8192000000 9993440496 10388176896 1086020480 11.705 100 17 35 1 1112291456 21592960 4678016
08:11:47 60754 8192000000 10007446432 10403364864 1096271440 11.802 100 30 60 1 1115064288 11724032 7068816
08:13:48 9949 8192000000 10033544352 10446487552 1097971728 11.818 100 53 105 1 1137701392 28169136 11560528
08:15:01 4795 8192000000 10034088064 10439614464 1104653088 11.882 100 38 77 0 1151238672 38913904 7671680
08:16:07 6592 8192000000 10013757952 10409517056 1097910544 11.818 100 37 72 3 1127958688 21064416 8983728
08:17:16 56092 8192000000 9984441584 10390622208 1020888176 11.081 93 23 46 1 1040069440 14128528 5052736
08:19:12 7133 8192000000 9939411104 10319065088 998154816 10.861 95 28 56 0 1035473568 30997968 6320784
08:20:20 7983 8192000000 9928735792 10309816320 999798432 10.877 99 32 64 1 1031273808 24261360 7214016
08:21:31 16472 8192000000 9905064192 10288816128 977400416 10.659 96 24 48 0 1007956208 24430368 6125424
08:22:50 15899 8192000000 9947231696 10348531712 1031681824 11.185 100 27 54 3 1068690912 30831568 6177520
08:24:07 9560 8192000000 9963183632 10366177280 1054557328 11.404 100 37 74 3 1086141024 22571840 9011856
08:25:18 5710 8192000000 9969853872 10363740160 1059801072 11.455 100 40 80 1 1089112128 18933072 10377984
08:26:26 112642 8192000000 9996795344 10405031936 1069600464 11.548 100 34 67 3 1106090400 29260064 7229872
08:29:25 15166 8192000000 10040870144 10438209536 1097191056 11.811 100 47 93 2 1142015584 34596592 10227936
08:30:44 34229 8192000000 10122060288 10526195712 1114679104 11.977 100 53 105 1 1210843760 85414384 10750272
08:32:23 11281 8192000000 10135885536 10547208192 1130870304 12.130 100 42 84 0 1251808064 111615424 9322336
08:33:39 9544 8192000000 10184355056 10596020224 1137824752 12.195 100 52 102 0 1291643264 142181184 11637328
08:34:59 6975 8192000000 10208552704 10612748288 1144459808 12.257 100 57 110 2 1309589792 154007104 11122880
08:36:09 10365 8192000000 10254184096 10667294720 1161697552 12.419 100 58 115 2 1360068416 186118480 12252384
08:37:24 1540 8192000000 10268660256 10666409984 1164174592 12.442 99 88 175 0 1363686816 180689376 18822848
08:38:30 1294 8192000000 10298835712 10696204288 1170125728 12.498 99 77 152 1 1395372240 208523040 16723472
08:39:35 2671 8192000000 10341073600 10753851392 1166084176 12.460 100 80 160 0 1434883776 250624688 18174912
08:40:40 5743 8192000000 10348635280 10755092480 1169780400 12.495 100 70 139 2 1452232928 266708400 15744128
08:41:55 1227 8192000000 10384252800 10783940608 1173555088 12.530 100 90 178 4 1482547312 288763904 20228320
08:43:02 1353 8192000000 10432692864 10829000704 1169527216 12.492 100 87 173 0 1531409792 342244048 19638528
08:44:09 1382 8192000000 10412088320 10809151488 1158484416 12.389 99 86 170 0 1499852992 321036864 20331712
08:45:16 3409 8192000000 10399284640 10811211776 1141151744 12.226 99 79 157 1 1493538208 333714032 18672432
08:46:25 1357 8192000000 10442480256 10849148928 1154141328 12.348 100 71 140 1 1542563600 371307584 17114688
08:47:41 11109 8192000000 10357259936 10821058560 1161781104 12.420 100 51 102 1 1472046480 297714976 12550400
08:48:55 7194 8192000000 10355649696 10818707456 1161564992 12.418 100 43 86 4 1472797872 300043728 11189152
08:50:09 13945 8192000000 10375135456 10809966592 1140181408 12.217 99 33 66 0 1455256256 306165568 8909280
08:51:28 12303 8192000000 10375078208 10818789376 1131818480 12.139 99 31 62 0 1475655840 335773968 8063392
08:52:45 12545 8192000000 10419153776 10872029184 1136619136 12.184 100 36 73 0 1516801168 370647424 9534608
08:53:59 34121 8192000000 10135026384 10690494464 1127408976 12.097 99 17 34 1 1344310480 212433104 4468400
08:55:35 110604 8192000000 10067876768 10535571456 1087301264 11.717 97 18 34 1 1134176464 43202912 3672288
08:58:27 94832 8192000000 10120710064 10561413120 1087228848 11.716 96 24 48 1 1175765520 83027776 5508896
09:01:02 100024 8192000000 10138923312 10632318976 1093014144 11.771 98 21 42 0 1264004752 165892672 5097936
09:04:32 44572 8192000000 10130794160 10560425984 1115872080 11.988 99 25 50 1 1187956768 67132832 4951856
09:06:18 60003 8192000000 10137480320 10637570048 1132949968 12.149 100 21 42 0 1277119088 139155872 5013248
09:08:22 61733 8192000000 10113048064 10560020480 1137643104 12.193 100 17 33 1 1217228528 75671600 3913824
09:10:34 39335 8192000000 10070745344 10516959232 1130586032 12.127 99 8 18 0 1183451616 51143184 1722400
09:12:20 117019 8192000000 10102734320 10624655360 1136360768 12.181 99 19 38 0 1224474208 83929264 4184176
09:15:22 33903 8192000000 10110578736 10572738560 1147533392 12.286 100 22 44 0 1204926768 52894000 4499376
09:16:56 28782 8192000000 10099735456 10560745472 1145909024 12.271 100 42 81 2 1205493760 50743792 8840944
09:18:35 56274 8192000000 10143466752 10602909696 1140987504 12.225 99 26 52 0 1262348208 115885728 5474976
09:20:32 49901 8192000000 10122399680 10596450304 1145169296 12.264 99 17 34 1 1224763952 76223680 3370976
09:22:22 46587 8192000000 10104439968 10539573248 1128143296 12.104 98 22 44 2 1178635744 46247600 4244848
09:24:13 90863 8192000000 10080066224 10532864000 1134253120 12.161 99 32 64 3 1215293392 73642576 7397696
09:26:51 47340 8192000000 10096569648 10580754432 1146785792 12.279 100 9 18 0 1210637280 61825728 2025760
09:28:39 62398 8192000000 10074252272 10561454080 1101995376 11.857 96 13 26 1 1157762560 52377920 3389264
09:30:44 93573 8192000000 10064372416 10538471424 1051118272 11.371 92 13 27 1 1108612416 54783408 2710736
09:33:25 58906 8192000000 10093434880 10533097472 1087274704 11.717 96 30 58 1 1125954528 32085056 6594768
09:35:46 40796 8192000000 10085013952 10537521152 1098034032 11.819 97 29 58 0 1160489360 56664672 5790656
09:37:29 74484 8192000000 10081269696 10557333504 1060243616 11.459 94 18 36 0 1142636224 78579760 3812848
09:39:44 22482 8192000000 10118923424 10553667584 1126612560 12.089 100 40 80 0 1200934080 66211648 8109872
09:41:07 54674 8192000000 10068800640 10509312000 1107291168 11.907 97 13 26 1 1145985376 36428816 2265392
09:43:10 125546 8192000000 10079504752 10532945920 1113124736 11.962 98 20 40 2 1167358992 49971424 4262832
09:46:24 58590 8192000000 10070050064 10530201600 1069720368 11.549 96 8 15 0 1149313088 77998896 1593824
09:48:24 67440 8192000000 10062309888 10543767552 1090780464 11.750 98 8 16 0 1164653392 72017552 1855376
09:50:32 45927 8192000000 10047114816 10475859968 1061970560 11.475 95 18 36 0 1094327728 28558192 3798976
09:52:28 58218 8192000000 10077850144 10519949312 1088176192 11.725 97 23 46 4 1127432000 33805152 5450656
09:54:26 68393 8192000000 10124093488 10616922112 1086466224 11.709 96 28 56 1 1189057776 96346736 6244816
09:56:42 46365 8192000000 10067962848 10536259584 1100797600 11.845 99 21 41 1 1175418400 70428176 4192624
09:58:28 14507 8192000000 10056055712 10484625408 1110397472 11.936 100 36 72 1 1150843008 33335872 7109664
09:59:51 108475 8192000000 10052016144 10505056256 1109461584 11.927 99 17 33 2 1145643600 31818640 4363376
10:03:00 64700 8192000000 10095744304 10558140416 1128473120 12.107 100 9 18 0 1187107184 56654480 1979584
10:05:09 53058 8192000000 10071825312 10539614208 1136614304 12.184 100 21 42 0 1187935600 46796704 4524592
10:07:04 57530 8192000000 10119360320 10581766144 1137408368 12.191 99 19 37 3 1212868592 71442640 4017584
10:09:02 87058 8192000000 10141501664 10611101696 1145354112 12.266 99 21 41 1 1233251216 83700064 4197040
10:11:33 56128 8192000000 10082814848 10587725824 1114719152 11.977 98 14 28 1 1206402464 88896000 2787312
10:13:29 8749 8192000000 10098543360 10521845760 1140532640 12.221 100 62 124 1 1182493440 27863104 14097696
10:14:43 91785 8192000000 10074565248 10512322560 1094377760 11.784 96 13 26 1 1135773408 38281184 3114464
10:17:20 112847 8192000000 10063071936 10518437888 1124416224 12.069 99 16 33 2 1181714752 53872784 3425744
10:20:31 78233 8192000000 10082203728 10568245248 1136912800 12.186 100 9 18 0 1172548224 33635792 1999632
10:22:49 55506 8192000000 10098342720 10559823872 1095545344 11.795 96 18 36 1 1148000912 48562768 3892800
10:24:59 70932 8192000000 10072625616 10543267840 1073546208 11.586 95 13 26 0 1109695504 33520384 2628912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-11-2020 10:28:09 to 14-11-2020 10:28:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-11-2020 10:28:25 to 14-11-2020 10:28:27.

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          232639
MON$OLDEST_ACTIVE               234452
MON$OLDEST_SNAPSHOT             234452
MON$NEXT_TRANSACTION            234452
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                       2105152
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             797
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Nov 14 10:26:56 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Nov 14 10:26:56 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Nov 14 10:26:57 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Nov 14 10:26:57 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Nov 14 10:26:58 2020
: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'

14.11.20 10:28:28. 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
2020-11-14 10:28:31 No severe PSQL-related problems occured
14.11.20 10:28:32. End of report.