Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2020 10: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 16594376 kB
MemAvailable 31451216 kB
Buffers 0 kB
Cached 15002272 kB
SwapCached 3712 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-12-16 10:26:53
DTS_END                         2020-12-16 10:26:53.6810
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/49978
TRN_ID                          234896
ATT_ID                          618
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: 9196.40 18877 1103568
customer order: creation 171.61 438 20593
customer order: refuse 15.17 331 1820
order to supplier: creation 45.50 1365 5460
order to supplier: removal 3.59 98579 431
invoice (draft): creation 44.97 3205 5396
invoice (draft): removal 52.29 815 6275
invoice accept: apply 41.75 14550 5010
invoice accept: cancel 47.07 875 5648
customer reserve: creation 3880.33 140 465639
customer reserve: removal 4602.16 62 552259
realization accept: apply 82.39 165 9887
realization accept: cancel 81.89 120 9827
payment from customer: creation 60.37 104 7244
payment from customer: removal 5.49 88 659
payment to supplier: creation 48.68 112 5841
payment to supplier: removal 3.19 59 383
service: total inventory turnovers 3.63 3428 435
service: total monetary turnovers 5.54 440 665
service: aggregate perf. data 0.77 19811 92
service: refresh index statistics 0.03 232847 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2020 10:28:24 to 16-12-2020 10:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3811
TEST_TIME 2 5902
TEST_TIME 3 5911
TEST_TIME 4 6030
TEST_TIME 5 5779
TEST_TIME 6 5459
TEST_TIME 7 5766
TEST_TIME 8 6034
TEST_TIME 9 6121
TEST_TIME 10 6352
TEST_TIME 11 6803
TEST_TIME 12 7228
TEST_TIME 13 7378
TEST_TIME 14 8102
TEST_TIME 15 8360
TEST_TIME 16 8466
TEST_TIME 17 8960
TEST_TIME 18 8997
TEST_TIME 19 9256
TEST_TIME 20 9417
TEST_TIME 21 9568
TEST_TIME 22 10064
TEST_TIME 23 10544
TEST_TIME 24 10773
TEST_TIME 25 10733
TEST_TIME 26 10678
TEST_TIME 27 10695
TEST_TIME 28 10705
TEST_TIME 29 10495
TEST_TIME 30 10506
TEST_TIME 31 10429
TEST_TIME 32 10314
TEST_TIME 33 10157
TEST_TIME 34 10079
TEST_TIME 35 10389
TEST_TIME 36 10402
TEST_TIME 37 10330
TEST_TIME 38 10286
TEST_TIME 39 10347
TEST_TIME 40 10338
TEST_TIME 41 10299
TEST_TIME 42 10072
TEST_TIME 43 9889
TEST_TIME 44 9868
TEST_TIME 45 9893
TEST_TIME 46 9745
TEST_TIME 47 9677
TEST_TIME 48 9593
TEST_TIME 49 9496
TEST_TIME 50 9451
TEST_TIME 51 9466
TEST_TIME 52 9378
TEST_TIME 53 9615
TEST_TIME 54 9641
TEST_TIME 55 9592
TEST_TIME 56 9604
TEST_TIME 57 9695
TEST_TIME 58 9684
TEST_TIME 59 9732
TEST_TIME 60 9770
TEST_TIME 61 9858
TEST_TIME 62 9927
TEST_TIME 63 9866
TEST_TIME 64 9872
TEST_TIME 65 9741
TEST_TIME 66 9685
TEST_TIME 67 9620
TEST_TIME 68 9545
TEST_TIME 69 9497
TEST_TIME 70 9426
TEST_TIME 71 9373
TEST_TIME 72 9451
TEST_TIME 73 9396
TEST_TIME 74 9360
TEST_TIME 75 9265
TEST_TIME 76 9258
TEST_TIME 77 9211
TEST_TIME 78 9228
TEST_TIME 79 9255
TEST_TIME 80 9238
TEST_TIME 81 9291
TEST_TIME 82 9287
TEST_TIME 83 9253
TEST_TIME 84 9238
TEST_TIME 85 9428
TEST_TIME 86 9515
TEST_TIME 87 9541
TEST_TIME 88 9498
TEST_TIME 89 9396
TEST_TIME 90 9372
TEST_TIME 91 9369
TEST_TIME 92 9343
TEST_TIME 93 9334
TEST_TIME 94 9333
TEST_TIME 95 9331
TEST_TIME 96 9347
TEST_TIME 97 9314
TEST_TIME 98 9324
TEST_TIME 99 9322
TEST_TIME 100 9417
TEST_TIME 101 9383
TEST_TIME 102 9373
TEST_TIME 103 9419
TEST_TIME 104 9456
TEST_TIME 105 9479
TEST_TIME 106 9474
TEST_TIME 107 9396
TEST_TIME 108 9334
TEST_TIME 109 9318
TEST_TIME 110 9254
TEST_TIME 111 9260
TEST_TIME 112 9243
TEST_TIME 113 9204
TEST_TIME 114 9213
TEST_TIME 115 9199
TEST_TIME 116 9170
TEST_TIME 117 9201
TEST_TIME 118 9209
TEST_TIME 119 9237
TEST_TIME 120 9199

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-12-2020 10:28:27 to 16-12-2020 10:28:31.

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 1533425 1533425 0 0.00 0 33136 5 0 0
doc_list_biud 1533424 1533424 0 0.00 0 90604 28 0 0
sp_add_invoice_to_stock 5106 5010 96 1.88 32 150480 14550 0 96
sp_cancel_adding_invoice 5648 5648 0 0.00 5 79091 876 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 8613 332 0 0
sp_cancel_customer_reserve 552259 552259 0 0.00 1 90605 63 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 13357 88 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 2537 60 0 0
sp_cancel_supplier_invoice 6283 6275 8 0.12 2 63572 815 0 8
sp_cancel_supplier_order 431 431 0 0.00 10 935223 98579 0 0
sp_cancel_write_off 9827 9827 0 0.00 1 29441 120 0 0
sp_client_order 20593 20593 0 0.00 4 28016 438 0 0
sp_customer_reserve 465639 465639 0 0.00 0 85983 141 0 0
sp_fill_shopping_cart 31443 31443 0 0.00 1 18175 44 0 0
sp_fill_shopping_cart_clo_res 465635 465635 0 0.00 0 27704 16 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 41096 438 0 0
sp_kill_cost_storno 16517 16517 0 0.00 0 6798 55 0 0
sp_kill_qstorno_ret_qs2qd 1121051 1121051 0 0.00 0 90595 34 0 0
sp_kill_qty_storno 591148 591148 0 0.00 0 90603 73 0 0
sp_lock_selected_doc 20637 20637 0 0.00 0 9412 6 0 0
sp_make_cost_storno 27974 27974 0 0.00 0 16672 46 0 0
sp_multiply_rows_for_pdistr 27973 27973 0 0.00 0 8049 28 0 0
sp_multiply_rows_for_qdistr 20593 20593 0 0.00 0 27995 265 0 0
sp_pay_from_customer 7244 7244 0 0.00 4 14812 105 0 0
sp_pay_to_supplier 5841 5841 0 0.00 5 16748 113 0 0
sp_payment_common 13085 13085 0 0.00 1 12268 16 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 8605 293 0 0
sp_qd_handle_on_invoice_upd_sts 10650 10650 0 0.00 0 31415 326 0 0
sp_qd_handle_on_reserve_upd_sts 9886 9886 0 0.00 0 7557 44 0 0
sp_reserve_write_off 9887 9887 0 0.00 7 17055 166 0 0
sp_supplier_invoice 5396 5396 0 0.00 8 111741 3206 0 0
sp_supplier_order 5460 5460 0 0.00 6 43361 1365 0 0
srv_aggregate_perf_data 92 92 0 0.00 2604 305620 19812 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 916 137422 48930 0 0
srv_find_qd_qs_mism 1055226 1055226 0 0.00 0 33135 4 0 0
srv_make_invnt_saldo 435 435 0 0.00 30 125684 3428 0 0
srv_make_money_saldo 665 665 0 0.00 1 19907 441 0 0
srv_recalc_idx_stat 4 4 0 0.00 147396 313380 232848 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5010 5010 0 0.00 0 16000 161 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 2982 88 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 2843 15 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 2835 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 379 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 921 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 645 10 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 13406 1264 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 1410 7 0 0
t$perf-norm:sp_client_order 20593 20593 0 0.00 0 19137 17 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 5898 15 0 0
t$perf-norm:sp_pay_from_customer 7244 7244 0 0.00 0 9066 12 0 0
t$perf-norm:sp_pay_to_supplier 5841 5841 0 0.00 0 3615 7 0 0
t$perf-norm:sp_reserve_write_off 9887 9887 0 0.00 0 4986 8 0 0
t$perf-norm:sp_supplier_invoice 5396 5396 0 0.00 0 9363 19 0 0
t$perf-norm:sp_supplier_order 5460 5460 0 0.00 0 7139 21 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 501 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 63 2 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 253 3 0 0
t$perf-norm:srv_make_money_saldo 665 665 0 0.00 0 171 2 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10194 10002 192 1.88 0 9119 19 0 192
v_all_customers 37760 37760 0 0.00 0 21 1 0 0
v_all_suppliers 30264 30264 0 0.00 0 14 1 0 0
v_all_wares 41186 41186 0 0.00 0 106 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1078 7 0 0
v_cancel_client_order 3640 3640 0 0.00 0 5524 11 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 399 4 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3135 10 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 904 6 0 0
v_cancel_supplier_invoice 800 784 16 2.00 0 5702 28 0 16
v_cancel_supplier_order 862 862 0 0.00 0 545 4 0 0
v_cancel_write_off 1956 1956 0 0.00 0 4416 17 0 0
v_max_id_clo_ord 5458 5458 0 0.00 0 635 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 3706 26 0 0
v_max_id_ord_sup 5391 5391 0 0.00 0 1734 2 0 0
v_max_non_paid_invoice 1558 1558 0 0.00 0 1198 3 0 0
v_max_non_paid_realizn 3849 3849 0 0.00 0 4982 6 0 0
v_min_id_clo_ord 5458 5458 0 0.00 0 3434 5 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 5787 13 0 0
v_min_id_ord_sup 5391 5391 0 0.00 0 4961 6 0 0
v_min_non_paid_invoice 5841 5841 0 0.00 0 12263 11 0 0
v_min_non_paid_realizn 7244 7244 0 0.00 0 5802 10 0 0
v_our_firm 10216 10216 0 0.00 0 12 1 0 0
v_reserve_write_off 19772 19772 0 0.00 0 5536 8 0 0
x_lock_depdocs_on_canc_invoice 11531 11531 0 0.00 0 19121 106 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 12779 272 0 0
x_make_qstorno_1000_1200 5460 5460 0 0.00 2 43326 1296 0 0
x_make_qstorno_1200_2000 5396 5396 0 0.00 5 110215 3026 0 0
x_make_qstorno_2100_3300 465639 465639 0 0.00 0 85982 123 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2020 10:28:32 to 16-12-2020 10:28:36.

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:57 5 8192000000 8796641776 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:58 20026 8192000000 9714828080 10007605248 792210496 8.817 100 83 166 0 820502624 7893872 20398256
07:59:27 12294 8192000000 9752502448 10056892416 827522624 9.174 100 88 176 1 860620704 12026256 21071824
08:00:43 22976 8192000000 9796945440 10113499136 865728400 9.557 100 81 162 3 904704144 20007904 18967840
08:02:14 15825 8192000000 9861645712 10194006016 907083664 9.968 100 77 152 2 969384080 45838320 16462096
08:03:33 10305 8192000000 9903166176 10248085504 953288656 10.423 100 76 151 1 1010693104 41167552 16236896
08:04:46 5529 8192000000 9961681472 10314747904 987070912 10.753 100 69 135 0 1072142688 71251792 13819984
08:05:53 2942 8192000000 10015036336 10376179712 1020025184 11.072 100 85 170 1 1126148176 88665840 17457152
08:06:59 1864 8192000000 10021237792 10386575360 1042445968 11.288 100 91 183 3 1125994992 64919680 18629344
08:08:02 20537 8192000000 10027895088 10406928384 1057528176 11.433 100 55 110 0 1131747472 62681696 11537600
08:09:23 7283 8192000000 9975629856 10362650624 1067158064 11.525 100 26 53 2 1094130624 21128128 5844432
08:10:31 6831 8192000000 9993152576 10392379392 1078094048 11.629 100 42 82 3 1112254912 25117712 9043152
08:11:39 40016 8192000000 10003029248 10414084096 1084369280 11.689 100 44 87 3 1113875104 19807088 9698736
08:13:22 3549 8192000000 10024056720 10417532928 1085780448 11.703 100 45 91 3 1129344080 33159264 10404368
08:14:27 4786 8192000000 10003066128 10405003264 1083576832 11.682 100 33 65 4 1113868128 23204704 7086592
08:15:36 5174 8192000000 10005565056 10402181120 1089870736 11.741 100 36 72 1 1118082240 20421808 7789696
08:16:45 27783 8192000000 10011432496 10412048384 1086611728 11.710 100 40 83 1 1110475376 15225488 8638160
08:18:19 3424 8192000000 9965769584 10355449856 1037989744 11.245 98 49 93 4 1067181824 18929840 10262240
08:19:26 6883 8192000000 9951324480 10339627008 1002311520 10.901 97 48 95 0 1031988656 17365440 12311696
08:20:34 9943 8192000000 9929540160 10316804096 988104752 10.763 96 36 72 1 1015935440 19858576 7972112
08:21:47 32632 8192000000 9929593488 10322608128 995546320 10.835 96 23 46 1 1023603536 23075680 4981536
08:23:22 8402 8192000000 9951816960 10343940096 1044816304 11.311 100 37 74 0 1075846320 22516144 8513872
08:24:32 4984 8192000000 9974006352 10370289664 1061697456 11.473 100 40 78 1 1085518912 15130432 8691024
08:25:39 69272 8192000000 9996411136 10401583104 1076550960 11.615 100 29 58 1 1109467872 26825408 6091504
08:27:53 6299 8192000000 10056556496 10455965696 1106238064 11.897 100 49 96 3 1158426800 42301728 9887008
08:29:03 3753 8192000000 10076658512 10475933696 1122733904 12.053 100 59 117 1 1181094688 45736976 12623808
08:30:11 11263 8192000000 10114368304 10521899008 1136884432 12.186 100 56 111 7 1228193968 79824080 11485456
08:31:26 15525 8192000000 10153998816 10564304896 1135367184 12.172 99 50 101 2 1235540176 88818064 11354928
08:32:45 15519 8192000000 10224855344 10634510336 1153792528 12.345 100 59 118 2 1310888240 143703152 13392560
08:34:09 21887 8192000000 10248034256 10672504832 1165266064 12.453 100 43 86 1 1341717040 166732016 9718960
08:35:33 10192 8192000000 10266258048 10686054400 1172750720 12.523 100 64 128 1 1379395472 191228080 15416672
08:36:50 3513 8192000000 10316605504 10724728832 1175923008 12.552 100 88 176 3 1418473776 221993136 20557632
08:37:57 7639 8192000000 10329525072 10737106944 1166685008 12.466 99 76 151 2 1422272896 238677728 16910160
08:39:14 3969 8192000000 10293024944 10701266944 1142938496 12.243 98 73 145 0 1383876336 224321040 16616800
08:40:23 910 8192000000 10316670592 10733416448 1157772608 12.382 100 83 164 3 1413624352 237714848 18136896
08:41:28 3331 8192000000 10291383232 10702811136 1140178096 12.217 99 59 117 1 1385336144 231640784 13517264
08:42:40 11905 8192000000 10305735952 10734456832 1136002080 12.178 99 37 74 0 1399982000 254578656 9401264
08:44:01 8785 8192000000 10293532576 10717990912 1129318240 12.115 99 36 72 1 1385965264 247978912 8668112
08:45:15 11182 8192000000 10264431168 10721067008 1115621280 11.986 98 43 85 2 1349138080 223212560 10304240
08:46:32 16526 8192000000 10259173856 10678394880 1099497520 11.833 98 35 70 0 1350884080 242664400 8722160
08:47:55 31823 8192000000 10203296432 10686656512 1099024320 11.828 98 21 41 1 1362950752 258842192 5084240
08:49:30 36198 8192000000 10128350656 10611478528 1092395696 11.765 98 23 46 2 1276466928 178337888 5733344
08:51:48 44889 8192000000 10088038048 10560974848 1082143408 11.668 98 10 20 3 1186642064 102217520 2281136
08:53:50 37387 8192000000 10074098256 10537975808 1098406480 11.823 99 14 27 2 1161371552 59355168 3609904
08:55:55 103303 8192000000 10036603696 10480406528 1059739968 11.454 95 19 39 2 1081257648 18298384 3219296
08:59:05 28890 8192000000 10095789680 10513137664 1129293072 12.115 100 43 86 0 1191621824 53601008 8727744
09:00:36 76952 8192000000 10047789200 10545643520 1072891600 11.580 96 6 12 0 1167415648 93089664 1434384
09:02:53 117372 8192000000 10063374992 10493173760 1096851920 11.808 97 12 24 1 1144608720 44643024 3113776
09:05:54 137421 8192000000 10075402688 10537287680 1089863392 11.741 96 10 18 2 1129969136 37626640 2479104
09:09:12 72344 8192000000 10078426256 10545197056 1133993632 12.159 100 20 38 1 1183988736 46255456 3739648
09:11:30 58245 8192000000 10069336224 10501083136 1121675280 12.043 99 11 23 0 1175500960 50899696 2925984
09:13:28 68786 8192000000 10120713984 10567262208 1130453888 12.126 98 18 36 3 1194836320 60366752 4015680
09:15:38 109597 8192000000 10150470064 10611273728 1136629920 12.184 99 14 28 0 1244175104 104159648 3385536
09:18:36 51753 8192000000 10214519712 10673688576 1158381952 12.388 100 23 46 1 1289646640 125380048 5884640
09:20:41 18190 8192000000 10084067488 10509144064 1124433776 12.069 98 39 78 1 1154844000 22584752 7825472
09:22:01 61584 8192000000 10153804656 10634739712 1126812720 12.091 98 13 26 0 1221497168 91880288 2804160
09:24:12 54693 8192000000 10135864784 10579992576 1136786864 12.185 99 26 52 0 1211578832 68773600 6018368
09:26:20 72421 8192000000 10118333920 10582118400 1137630640 12.193 99 15 30 0 1214791792 74039088 3122064
09:28:32 71663 8192000000 10094507808 10544562176 1062850368 11.484 93 7 14 1 1132271280 68146560 1274352
09:30:56 85533 8192000000 10050659680 10492616704 1101497920 11.852 98 20 40 1 1131563008 25602448 4462640
09:33:27 46287 8192000000 10043921520 10518626304 1118755696 12.015 100 9 18 0 1146520816 25746752 2018368
09:36:36 81830 8192000000 10140024144 10608996352 1130509488 12.126 100 17 34 1 1221620736 87607344 3503904
09:38:59 53889 8192000000 10044968864 10495512576 1091245104 11.754 96 23 46 1 1124664752 29316688 4102960
09:40:53 61686 8192000000 10027432384 10481516544 1077819632 11.627 99 13 25 1 1110388544 30270288 2298624
09:42:58 41943 8192000000 10037760192 10479865856 1070758336 11.559 97 21 42 1 1115632432 40539952 4334144
09:44:47 41865 8192000000 10060499424 10497703936 1097745040 11.816 99 20 40 1 1160473696 58306960 4421696
09:46:29 30011 8192000000 10083382112 10516815872 1115904416 11.988 100 35 71 2 1177559104 54192528 7462160
09:48:02 64811 8192000000 10131771360 10594537472 1120236736 12.029 99 34 68 0 1212847488 85296240 7314512
09:50:22 46777 8192000000 10171156880 10664042496 1108901376 11.922 99 28 56 0 1293202080 177966112 6334592
09:52:24 78693 8192000000 10041887632 10494533632 1027239744 11.142 93 15 29 3 1077098960 47028864 2830352
09:54:47 121635 8192000000 10061812080 10526736384 1098439376 11.823 99 21 42 1 1157293952 54764272 4090304
09:57:49 28056 8192000000 10050054704 10530701312 1113412192 11.965 100 7 14 1 1147238064 32674544 1151328
09:59:29 46151 8192000000 10035622944 10486460416 1116703552 11.996 100 15 30 0 1149277056 29540320 3033184
10:01:20 59247 8192000000 10105279536 10555510784 1132593312 12.146 100 16 31 2 1187182976 50932288 3657376
10:03:20 33534 8192000000 10115837904 10561118208 1136954176 12.187 100 23 46 0 1194891904 52801472 5136256
10:04:54 39597 8192000000 10131098384 10580140032 1148102688 12.292 100 27 54 1 1219989248 66175456 5711104
10:06:34 91431 8192000000 10111508144 10530713600 1125402192 12.078 98 22 44 0 1159070080 29173888 4494000
10:09:07 39007 8192000000 10137831376 10612101120 1138272800 12.199 99 22 44 1 1217370112 74566064 4531248
10:10:51 106755 8192000000 10088952192 10577780736 1112787568 11.959 97 11 22 0 1138823632 24162128 1873936
10:13:38 95345 8192000000 10103678816 10563850240 1158522624 12.389 100 26 51 1 1198356144 34694096 5139424
10:16:16 84514 8192000000 10105138528 10590478336 1123568928 12.061 97 14 28 1 1185967696 59463808 2934960
10:18:43 49369 8192000000 10083127488 10526703616 1105375376 11.889 97 21 42 0 1159430816 48782272 5273168
10:20:34 71618 8192000000 10098420208 10540630016 1080190992 11.649 95 21 42 3 1164988368 80124752 4672624
10:22:57 54947 8192000000 10166526384 10640400384 1136631216 12.184 99 25 50 1 1268728928 126165440 5932272
10:24:52 20740 8192000000 10162818208 10586804224 1113845808 11.969 97 53 105 2 1227515296 101919776 11749712
10:26:15 52606 8192000000 9070848272 9394343936 202306672 2.410 18 7 12 1 302358416 98184992 1866752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2020 10:28:38 to 16-12-2020 10:28:53.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2020 10:28:53 to 16-12-2020 10:28:55.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235568
MON$OLDEST_ACTIVE               235569
MON$OLDEST_SNAPSHOT             235569
MON$NEXT_TRANSACTION            235569
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                       2119616
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             799
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2296 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.2296 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 ---
--- end of diff output ---

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

16.12.20 10:28:56. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-12-16 10:28:59 No severe PSQL-related problems occured
16.12.20 10:29:00. End of report.