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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16713852 kB
MemAvailable 31289968 kB
Buffers 20 kB
Cached 14851428 kB
SwapCached 4864 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-01-09 04:26:51
DTS_END                         2021-01-09 04:26:51.3940
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/58510
TRN_ID                          224464
ATT_ID                          674
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: 8548.41 25775 1025809
customer order: creation 156.48 426 18777
customer order: refuse 13.59 358 1631
order to supplier: creation 41.08 1463 4929
order to supplier: removal 3.36 106516 403
invoice (draft): creation 41.34 4012 4961
invoice (draft): removal 49.31 902 5917
invoice accept: apply 37.58 19359 4510
invoice accept: cancel 44.72 908 5366
customer reserve: creation 3469.80 189 416376
customer reserve: removal 4419.27 65 530313
realization accept: apply 76.68 192 9201
realization accept: cancel 79.21 115 9505
payment from customer: creation 53.57 122 6428
payment from customer: removal 5.20 82 624
payment to supplier: creation 44.08 145 5290
payment to supplier: removal 3.36 100 403
service: total inventory turnovers 3.53 3313 423
service: total monetary turnovers 5.40 588 648
service: aggregate perf. data 0.83 17528 99
service: refresh index statistics 0.04 359110 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2021 04:28:55 to 09-01-2021 04:28:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2619
TEST_TIME 2 2996
TEST_TIME 3 3452
TEST_TIME 4 3732
TEST_TIME 5 3837
TEST_TIME 6 4691
TEST_TIME 7 4869
TEST_TIME 8 5221
TEST_TIME 9 5592
TEST_TIME 10 5792
TEST_TIME 11 5818
TEST_TIME 12 6082
TEST_TIME 13 6206
TEST_TIME 14 6585
TEST_TIME 15 6520
TEST_TIME 16 6750
TEST_TIME 17 7167
TEST_TIME 18 7582
TEST_TIME 19 7872
TEST_TIME 20 8096
TEST_TIME 21 8318
TEST_TIME 22 8185
TEST_TIME 23 8239
TEST_TIME 24 8163
TEST_TIME 25 8514
TEST_TIME 26 8935
TEST_TIME 27 9674
TEST_TIME 28 10369
TEST_TIME 29 10125
TEST_TIME 30 9905
TEST_TIME 31 9820
TEST_TIME 32 9980
TEST_TIME 33 9826
TEST_TIME 34 9812
TEST_TIME 35 9681
TEST_TIME 36 9635
TEST_TIME 37 9627
TEST_TIME 38 9463
TEST_TIME 39 9334
TEST_TIME 40 9227
TEST_TIME 41 9168
TEST_TIME 42 9051
TEST_TIME 43 8989
TEST_TIME 44 8846
TEST_TIME 45 9083
TEST_TIME 46 9005
TEST_TIME 47 8918
TEST_TIME 48 8976
TEST_TIME 49 9055
TEST_TIME 50 9003
TEST_TIME 51 8955
TEST_TIME 52 9048
TEST_TIME 53 9017
TEST_TIME 54 9228
TEST_TIME 55 9148
TEST_TIME 56 9248
TEST_TIME 57 9276
TEST_TIME 58 9136
TEST_TIME 59 9008
TEST_TIME 60 9031
TEST_TIME 61 8927
TEST_TIME 62 8860
TEST_TIME 63 8776
TEST_TIME 64 8681
TEST_TIME 65 8697
TEST_TIME 66 8629
TEST_TIME 67 8591
TEST_TIME 68 8593
TEST_TIME 69 8596
TEST_TIME 70 8610
TEST_TIME 71 8613
TEST_TIME 72 8706
TEST_TIME 73 8725
TEST_TIME 74 8738
TEST_TIME 75 8753
TEST_TIME 76 8831
TEST_TIME 77 8809
TEST_TIME 78 8820
TEST_TIME 79 8838
TEST_TIME 80 8759
TEST_TIME 81 8694
TEST_TIME 82 8670
TEST_TIME 83 8652
TEST_TIME 84 8576
TEST_TIME 85 8508
TEST_TIME 86 8528
TEST_TIME 87 8507
TEST_TIME 88 8583
TEST_TIME 89 8603
TEST_TIME 90 8584
TEST_TIME 91 8577
TEST_TIME 92 8640
TEST_TIME 93 8654
TEST_TIME 94 8722
TEST_TIME 95 8697
TEST_TIME 96 8701
TEST_TIME 97 8683
TEST_TIME 98 8638
TEST_TIME 99 8594
TEST_TIME 100 8538
TEST_TIME 101 8558
TEST_TIME 102 8542
TEST_TIME 103 8520
TEST_TIME 104 8543
TEST_TIME 105 8552
TEST_TIME 106 8562
TEST_TIME 107 8539
TEST_TIME 108 8586
TEST_TIME 109 8587
TEST_TIME 110 8619
TEST_TIME 111 8609
TEST_TIME 112 8586
TEST_TIME 113 8649
TEST_TIME 114 8669
TEST_TIME 115 8741
TEST_TIME 116 8726
TEST_TIME 117 8662
TEST_TIME 118 8619
TEST_TIME 119 8565
TEST_TIME 120 8549

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-01-2021 04:28:58 to 09-01-2021 04:29:02.

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 1411917 1411917 0 0.00 0 20987 7 0 0
doc_list_biud 1411917 1411917 0 0.00 0 29948 31 0 0
sp_add_invoice_to_stock 4575 4510 65 1.42 20 193683 19359 0 65
sp_cancel_adding_invoice 5366 5366 0 0.00 6 93837 909 0 0
sp_cancel_client_order 1631 1631 0 0.00 5 15615 359 0 0
sp_cancel_customer_reserve 530313 530313 0 0.00 1 32512 65 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 2238 82 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 4707 101 0 0
sp_cancel_supplier_invoice 5920 5917 3 0.05 2 27853 902 0 3
sp_cancel_supplier_order 403 403 0 0.00 9 1037756 106517 0 0
sp_cancel_write_off 9505 9505 0 0.00 1 21755 116 0 0
sp_client_order 18777 18777 0 0.00 4 22000 427 0 0
sp_customer_reserve 416376 416376 0 0.00 0 59537 190 0 0
sp_fill_shopping_cart 28648 28648 0 0.00 1 21112 51 0 0
sp_fill_shopping_cart_clo_res 416374 416374 0 0.00 0 31229 18 0 0
sp_get_clo_for_invoice 4507 4507 0 0.00 0 57016 465 0 0
sp_kill_cost_storno 15898 15898 0 0.00 0 15142 62 0 0
sp_kill_qstorno_ret_qs2qd 1076451 1076451 0 0.00 0 29946 35 0 0
sp_kill_qty_storno 566842 566842 0 0.00 0 29948 76 0 0
sp_lock_selected_doc 18987 18987 0 0.00 0 7512 6 0 0
sp_make_cost_storno 25425 25425 0 0.00 0 17017 58 0 0
sp_multiply_rows_for_pdistr 25425 25425 0 0.00 0 8422 32 0 0
sp_multiply_rows_for_qdistr 18777 18777 0 0.00 0 21985 243 0 0
sp_pay_from_customer 6428 6428 0 0.00 4 16997 122 0 0
sp_pay_to_supplier 5290 5290 0 0.00 5 13683 146 0 0
sp_payment_common 11718 11718 0 0.00 1 10281 22 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 0 15603 312 0 0
sp_qd_handle_on_invoice_upd_sts 9872 9872 0 0.00 0 29851 368 0 0
sp_qd_handle_on_reserve_upd_sts 9201 9201 0 0.00 0 12397 58 0 0
sp_reserve_write_off 9201 9201 0 0.00 6 14324 192 0 0
sp_supplier_invoice 4961 4961 0 0.00 6 103144 4013 0 0
sp_supplier_order 4929 4929 0 0.00 5 30408 1464 0 0
srv_aggregate_perf_data 99 99 0 0.00 2747 277181 17528 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1680 139303 48898 0 0
srv_find_qd_qs_mism 983015 983015 0 0.00 0 20985 6 0 0
srv_make_invnt_saldo 423 423 0 0.00 67 90978 3314 0 0
srv_make_money_saldo 648 648 0 0.00 1 10891 588 0 0
srv_recalc_idx_stat 5 5 0 0.00 134399 582009 359111 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4510 4510 0 0.00 0 10157 180 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 3173 124 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 3400 18 0 0
t$perf-norm:sp_cancel_customer_reserve 592 592 0 0.00 0 3110 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 3667 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 3011 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 695 19 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 19877 1646 0 0
t$perf-norm:sp_cancel_write_off 879 879 0 0.00 0 3509 10 0 0
t$perf-norm:sp_client_order 18777 18777 0 0.00 0 8771 18 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 4042 15 0 0
t$perf-norm:sp_pay_from_customer 6428 6428 0 0.00 0 6345 9 0 0
t$perf-norm:sp_pay_to_supplier 5290 5290 0 0.00 0 4177 9 0 0
t$perf-norm:sp_reserve_write_off 9201 9201 0 0.00 0 10804 10 0 0
t$perf-norm:sp_supplier_invoice 4961 4961 0 0.00 0 9746 22 0 0
t$perf-norm:sp_supplier_order 4929 4929 0 0.00 0 17948 22 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 727 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1503 29 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 5120 28 0 0
t$perf-norm:srv_make_money_saldo 648 648 0 0.00 0 278 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9142 9012 130 1.42 0 5825 19 0 130
v_all_customers 34132 34132 0 0.00 0 17 1 0 0
v_all_suppliers 26978 26978 0 0.00 0 55 1 0 0
v_all_wares 37554 37554 0 0.00 0 208 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 2576 12 0 0
v_cancel_client_order 3262 3262 0 0.00 0 9323 15 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 1772 9 0 0
v_cancel_customer_reserve 1184 1184 0 0.00 0 852 4 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 2891 12 0 0
v_cancel_supplier_invoice 712 706 6 0.84 0 3296 21 0 6
v_cancel_supplier_order 806 806 0 0.00 0 718 4 0 0
v_cancel_write_off 1758 1758 0 0.00 0 2598 16 0 0
v_max_id_clo_ord 4929 4929 0 0.00 0 10151 3 0 0
v_max_id_clo_res 946 946 0 0.00 0 2859 14 0 0
v_max_id_ord_sup 4939 4939 0 0.00 0 5516 7 0 0
v_max_non_paid_invoice 1669 1669 0 0.00 0 4712 6 0 0
v_max_non_paid_realizn 3512 3512 0 0.00 0 2436 3 0 0
v_min_id_clo_ord 4929 4929 0 0.00 0 3740 8 0 0
v_min_id_clo_res 946 946 0 0.00 0 10920 17 0 0
v_min_id_ord_sup 4939 4939 0 0.00 0 5502 11 0 0
v_min_non_paid_invoice 5290 5290 0 0.00 0 8364 14 0 0
v_min_non_paid_realizn 6428 6428 0 0.00 0 6655 15 0 0
v_our_firm 9254 9254 0 0.00 0 32 1 0 0
v_reserve_write_off 18402 18402 0 0.00 0 9060 8 0 0
x_lock_depdocs_on_canc_invoice 10930 10930 0 0.00 0 12833 111 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 10877 377 0 0
x_make_qstorno_1000_1200 4929 4929 0 0.00 2 30381 1377 0 0
x_make_qstorno_1200_2000 4961 4961 0 0.00 3 100800 3804 0 0
x_make_qstorno_2100_3300 416376 416376 0 0.00 0 59534 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2021 04:29:03 to 09-01-2021 04:29: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
01:56:55 990 8192000000 8958176768 9020698624 20673312 0.251 5 4 6 0 21468272 169984 624976
01:57:57 18052 8192000000 9732007408 10030788608 803234208 8.929 100 84 169 0 830434480 7705584 19494688
01:59:19 20534 8192000000 9774938176 10084532224 840142256 9.301 100 91 182 0 874448000 13030080 21275664
02:00:48 18415 8192000000 9807832176 10125582336 866413632 9.564 100 86 172 2 905268928 19314432 19540864
02:02:22 11524 8192000000 9862712624 10192826368 901877312 9.917 100 80 160 1 965468480 46195680 17395488
02:03:40 8220 8192000000 9924829536 10269384704 943736256 10.330 100 72 144 0 1026840144 68657664 14446224
02:04:49 5379 8192000000 9977820608 10332221440 986471856 10.747 100 73 146 1 1079527856 78125712 14930288
02:05:57 3111 8192000000 9977701744 10340769792 1012737680 11.002 100 73 144 1 1077670512 50390352 14542480
02:07:03 2177 8192000000 10019306704 10386718720 1030487760 11.173 100 84 168 1 1120005920 73012624 16505536
02:08:07 2962 8192000000 10026278960 10393239552 1040122992 11.266 100 83 166 0 1122475328 64571072 17781264
02:09:11 8696 8192000000 9983262336 10370252800 1054700480 11.406 100 33 65 1 1096969648 35249968 7019200
02:10:22 4106 8192000000 10002227936 10395054080 1061507296 11.471 100 47 94 3 1123573920 52776624 9290000
02:11:27 5940 8192000000 9988638272 10387689472 1071629184 11.568 100 38 76 0 1108321552 29692480 6999888
02:12:34 52011 8192000000 10009359792 10396368896 1068243648 11.535 100 54 109 3 1098576784 18510400 11822736
02:14:26 5113 8192000000 10020152592 10411089920 1079371824 11.641 100 60 121 0 1112700128 19552064 13776240
02:15:33 4912 8192000000 10007655728 10398904320 1080387760 11.651 100 48 96 0 1118547040 27269408 10889872
02:16:38 6163 8192000000 9988303088 10383048704 1050745376 11.368 97 37 74 1 1066635824 7983968 7906480
02:17:49 28386 8192000000 9994349472 10381262848 1050515792 11.366 99 44 88 0 1080343584 20466160 9361632
02:19:17 8525 8192000000 9952797792 10350297088 1018622368 11.059 98 38 72 6 1043280304 16644208 8013728
02:20:28 4582 8192000000 9947165280 10323865600 1020479904 11.077 100 41 83 2 1047856656 18763552 8613200
02:21:33 45058 8192000000 9932714160 10316787712 999953264 10.878 97 39 80 2 1026795504 17754592 9087648
02:23:30 4331 8192000000 9967194528 10354089984 1045140368 11.314 100 50 101 0 1083179136 27018224 11020544
02:24:36 9512 8192000000 9971174592 10366578688 1060767296 11.464 100 37 70 7 1093478000 25237936 7472768
02:25:46 6389 8192000000 9989649184 10383597568 1072950976 11.580 100 41 80 0 1108888544 26962400 8975168
02:26:54 43083 8192000000 10015578128 10412785664 1095987936 11.800 100 50 100 2 1116732544 9956304 10788304
02:28:46 31706 8192000000 10072218560 10473123840 1114956752 11.979 100 51 101 0 1174801696 49731360 10113584
02:30:25 13923 8192000000 10074455904 10485714944 1130710656 12.128 100 41 82 1 1197021568 56836672 9474240
02:31:43 10725 8192000000 10120502624 10528632832 1142150720 12.236 100 45 89 2 1234452304 82329696 9971888
02:33:06 4130 8192000000 10159567200 10570833920 1159415280 12.398 100 55 110 2 1266579888 95731024 11433584
02:34:16 4202 8192000000 10196999584 10613649408 1157588752 12.381 100 53 104 3 1305393200 136861840 10942608
02:35:28 9037 8192000000 10183351360 10603884544 1166208848 12.461 100 46 91 2 1296701888 120266016 10227024
02:36:46 5245 8192000000 10200510368 10605723648 1166864976 12.468 100 63 127 1 1306850176 127007136 12978064
02:37:57 13997 8192000000 10224161136 10632413184 1134293024 12.162 98 59 118 2 1319640992 172600208 12747760
02:39:21 10133 8192000000 10232018864 10650718208 1149287744 12.303 99 60 119 1 1338528992 176666704 12574544
02:40:36 2088 8192000000 10250477792 10654593024 1146073792 12.273 99 66 131 1 1348791984 187710176 15008016
02:41:41 3216 8192000000 10293032816 10697015296 1163750528 12.438 100 74 147 0 1400251072 218385904 18114640
02:42:51 3356 8192000000 10331452432 10738962432 1170888848 12.505 100 73 147 0 1427388784 239917488 16582448
02:44:01 2950 8192000000 10311827312 10717732864 1150082000 12.310 100 86 171 0 1419149536 249024320 20043216
02:45:10 3010 8192000000 10324025520 10714775552 1142891504 12.243 99 88 174 2 1418959056 256864384 19203168
02:46:18 1679 8192000000 10356112272 10750394368 1167157008 12.470 100 89 176 0 1450607392 263089648 20360736
02:47:22 5499 8192000000 10346042816 10758172672 1176650976 12.559 100 58 111 2 1449791648 258752416 14388256
02:48:30 8112 8192000000 10366697952 10784350208 1167557040 12.474 100 43 84 1 1463386368 286077216 9752112
02:49:40 11179 8192000000 10404013600 10830102528 1163490368 12.436 100 30 60 0 1502781120 331605600 7685152
02:50:57 17458 8192000000 10439842000 10897485824 1155551296 12.362 100 29 58 0 1535897296 372420560 7925440
02:52:18 33383 8192000000 10336281184 10806050816 1129311808 12.115 99 19 38 1 1443561088 308998656 5250624
02:53:53 21972 8192000000 10192307808 10648690688 1136682992 12.184 100 16 32 3 1334336416 193281920 4371504
02:55:32 38736 8192000000 10142762528 10576224256 1130614528 12.127 99 37 74 3 1224558528 86155280 7788720
02:57:50 61195 8192000000 10169608416 10688253952 1153020080 12.338 100 24 48 1 1312585632 153774128 5791424
02:59:52 108379 8192000000 10128948976 10582507520 1148790160 12.298 100 10 20 0 1220432496 69715024 1927312
03:02:53 129660 8192000000 10042216144 10487267328 1070013056 11.552 93 19 38 1 1102961712 28970832 3977824
03:06:03 5719 8192000000 10074119728 10490122240 1119174304 12.019 98 69 132 3 1157888992 24816656 13898032
03:07:11 24924 8192000000 10105338000 10543198208 1124922352 12.073 99 39 78 1 1194208032 61268480 8017200
03:08:43 70381 8192000000 10110473920 10584465408 1119245024 12.020 98 13 26 1 1196497712 74564272 2688416
03:10:54 64735 8192000000 10074514688 10615336960 1122710112 12.053 99 10 20 0 1214443552 89511536 2221904
03:13:04 89107 8192000000 10110931552 10569478144 1117946128 12.008 98 25 49 0 1188041152 64943712 5151312
03:15:50 63261 8192000000 10094567712 10529992704 1126328256 12.087 99 17 33 1 1151310656 21316928 3665472
03:18:03 46208 8192000000 10122039264 10584326144 1132880768 12.149 100 30 60 0 1227640656 88546720 6213168
03:19:59 139297 8192000000 10133364896 10672410624 1121848400 12.044 98 17 34 1 1280794064 154647440 4298224
03:23:19 124250 8192000000 10057629744 10489790464 1109332064 11.926 97 21 42 2 1142754576 29088048 4334464
03:26:31 62833 8192000000 10061884688 10548953088 1123310544 12.058 99 8 16 0 1159942272 35034592 1597136
03:28:34 53669 8192000000 10063475648 10540306432 1126512736 12.088 99 6 12 0 1165347488 37773680 1061072
03:30:28 94101 8192000000 10059272224 10545774592 1124604960 12.070 99 14 29 0 1186213472 58221872 3386640
03:33:15 37950 8192000000 10075593584 10523017216 1109367680 11.926 98 18 36 1 1161771200 48531744 3871776
03:35:12 80571 8192000000 10110157184 10550407168 1094628048 11.787 96 25 50 1 1189512912 89609152 5275712
03:37:35 83848 8192000000 10068646032 10558763008 1108211232 11.915 98 14 28 0 1197956800 86954048 2791520
03:41:00 48596 8192000000 10075574304 10517475328 1081759424 11.664 97 19 38 1 1131102224 45051536 4291264
03:42:54 69651 8192000000 10093742544 10530025472 1091940320 11.761 97 26 50 1 1141845248 44790672 5114256
03:45:08 27109 8192000000 10081650336 10503528448 1101248320 11.849 99 33 67 0 1140637744 32524288 6865136
03:46:35 94154 8192000000 10074201728 10553114624 1123149248 12.057 100 18 36 0 1193723920 66783472 3791200
03:49:23 86946 8192000000 10094083088 10589876224 1133109696 12.151 100 10 20 0 1198572144 62925776 2536672
03:52:02 93651 8192000000 10115597280 10556104704 1103443504 11.870 97 16 32 0 1186594480 79724464 3426512
03:54:36 91371 8192000000 10059140256 10515886080 1078873824 11.637 95 21 43 1 1129753120 46496288 4383008
03:57:14 86914 8192000000 10116875040 10620112896 1130199216 12.123 100 23 46 0 1234787488 99916160 4672112
03:59:44 54091 8192000000 10101533728 10591473664 1128181280 12.104 99 13 26 1 1196913760 65688784 3043696
04:01:40 51803 8192000000 10099371424 10556411904 1118982368 12.017 98 14 28 2 1163035792 41393904 2659520
04:03:32 103058 8192000000 10072309264 10540851200 1128640496 12.109 99 18 35 1 1191199696 58488304 4070896
04:06:19 51359 8192000000 10090627696 10550525952 1127844368 12.101 99 7 14 1 1184425968 55127088 1454512
04:08:12 103791 8192000000 10110217856 10567069696 1107863152 11.912 98 12 24 0 1147764544 37867728 2033664
04:10:56 54777 8192000000 10093514960 10542850048 1103844576 11.874 97 20 40 0 1162349936 54429296 4076064
04:13:17 43514 8192000000 10132860352 10578067456 1130381536 12.125 99 38 75 2 1215150096 76770528 7998032
04:15:03 70051 8192000000 10094747024 10540806144 1120784000 12.034 98 19 38 0 1177308672 52783280 3741392
04:17:17 42479 8192000000 10101893792 10529447936 1124055456 12.065 98 35 71 0 1163931504 32890816 6985232
04:19:08 19951 8192000000 10140893232 10590343168 1119310016 12.020 98 55 110 0 1237771584 107525984 10935584
04:20:30 40263 8192000000 10144271664 10605379584 1134248160 12.161 100 21 42 0 1210670256 71939184 4482912
04:22:12 132298 8192000000 10085336896 10560008192 1136313184 12.181 100 11 22 1 1206498128 67491664 2693280
04:25:33 26808 8192000000 10073541968 10549927936 1114994976 11.980 99 4 8 0 1161910208 46266512 648720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-01-2021 04:29:09 to 09-01-2021 04:29:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 65 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2021 04:29:24 to 09-01-2021 04:29:26.

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          224290
MON$OLDEST_ACTIVE               225292
MON$OLDEST_SNAPSHOT             225292
MON$NEXT_TRANSACTION            225292
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                       2089824
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             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2319 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.2319 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Sat Jan  9 04:26:56 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sat Jan  9 04:27:12 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

09.01.21 04:29:27. 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-01-09 04:29:30 No severe PSQL-related problems occured
09.01.21 04:29:31. End of report.