Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.11.2020 13:29
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 16976448 kB
MemAvailable 31499796 kB
Buffers 0 kB
Cached 14727060 kB
SwapCached 2868 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-04 13:26:52
DTS_END                         2020-11-04 13:26:52.7270
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/36228
TRN_ID                          226193
ATT_ID                          647
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: 8649.52 20423 1037942
customer order: creation 158.68 397 19041
customer order: refuse 13.41 406 1609
order to supplier: creation 42.95 1296 5154
order to supplier: removal 3.48 112062 417
invoice (draft): creation 42.38 3505 5086
invoice (draft): removal 51.46 867 6175
invoice accept: apply 38.19 16395 4583
invoice accept: cancel 46.08 885 5530
customer reserve: creation 3476.05 163 417126
customer reserve: removal 4501.75 71 540210
realization accept: apply 76.93 198 9232
realization accept: cancel 78.88 126 9466
payment from customer: creation 55.07 104 6608
payment from customer: removal 5.63 119 675
payment to supplier: creation 45.59 116 5471
payment to supplier: removal 3.27 71 392
service: total inventory turnovers 3.38 3691 406
service: total monetary turnovers 5.58 466 670
service: aggregate perf. data 0.72 18549 86
service: refresh index statistics 0.04 248963 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-11-2020 13:29:28 to 04-11-2020 13:29:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3385
TEST_TIME 2 4931
TEST_TIME 3 5264
TEST_TIME 4 4885
TEST_TIME 5 4937
TEST_TIME 6 5044
TEST_TIME 7 5238
TEST_TIME 8 5373
TEST_TIME 9 5279
TEST_TIME 10 5218
TEST_TIME 11 5312
TEST_TIME 12 5584
TEST_TIME 13 5578
TEST_TIME 14 6456
TEST_TIME 15 6949
TEST_TIME 16 7234
TEST_TIME 17 7883
TEST_TIME 18 8235
TEST_TIME 19 8453
TEST_TIME 20 8772
TEST_TIME 21 8720
TEST_TIME 22 8976
TEST_TIME 23 9540
TEST_TIME 24 10083
TEST_TIME 25 10298
TEST_TIME 26 10802
TEST_TIME 27 10409
TEST_TIME 28 10305
TEST_TIME 29 10169
TEST_TIME 30 10158
TEST_TIME 31 9970
TEST_TIME 32 9837
TEST_TIME 33 9779
TEST_TIME 34 9735
TEST_TIME 35 9581
TEST_TIME 36 9563
TEST_TIME 37 9512
TEST_TIME 38 9364
TEST_TIME 39 9219
TEST_TIME 40 9138
TEST_TIME 41 9107
TEST_TIME 42 9099
TEST_TIME 43 9145
TEST_TIME 44 9028
TEST_TIME 45 9201
TEST_TIME 46 9293
TEST_TIME 47 9204
TEST_TIME 48 9207
TEST_TIME 49 9309
TEST_TIME 50 9391
TEST_TIME 51 9319
TEST_TIME 52 9357
TEST_TIME 53 9442
TEST_TIME 54 9362
TEST_TIME 55 9385
TEST_TIME 56 9436
TEST_TIME 57 9450
TEST_TIME 58 9349
TEST_TIME 59 9206
TEST_TIME 60 9170
TEST_TIME 61 9121
TEST_TIME 62 9042
TEST_TIME 63 8952
TEST_TIME 64 8958
TEST_TIME 65 8970
TEST_TIME 66 8973
TEST_TIME 67 9002
TEST_TIME 68 8967
TEST_TIME 69 8970
TEST_TIME 70 9000
TEST_TIME 71 9095
TEST_TIME 72 9052
TEST_TIME 73 9150
TEST_TIME 74 9109
TEST_TIME 75 9075
TEST_TIME 76 9104
TEST_TIME 77 9139
TEST_TIME 78 9093
TEST_TIME 79 8989
TEST_TIME 80 8942
TEST_TIME 81 8856
TEST_TIME 82 8855
TEST_TIME 83 8813
TEST_TIME 84 8791
TEST_TIME 85 8769
TEST_TIME 86 8737
TEST_TIME 87 8702
TEST_TIME 88 8687
TEST_TIME 89 8723
TEST_TIME 90 8695
TEST_TIME 91 8805
TEST_TIME 92 9000
TEST_TIME 93 9011
TEST_TIME 94 9026
TEST_TIME 95 9074
TEST_TIME 96 9066
TEST_TIME 97 8997
TEST_TIME 98 8912
TEST_TIME 99 8894
TEST_TIME 100 8845
TEST_TIME 101 8810
TEST_TIME 102 8747
TEST_TIME 103 8721
TEST_TIME 104 8699
TEST_TIME 105 8712
TEST_TIME 106 8749
TEST_TIME 107 8711
TEST_TIME 108 8713
TEST_TIME 109 8728
TEST_TIME 110 8705
TEST_TIME 111 8696
TEST_TIME 112 8678
TEST_TIME 113 8713
TEST_TIME 114 8765
TEST_TIME 115 8763
TEST_TIME 116 8781
TEST_TIME 117 8796
TEST_TIME 118 8742
TEST_TIME 119 8702
TEST_TIME 120 8654

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-11-2020 13:29:31 to 04-11-2020 13:29:35.

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 1424646 1424646 0 0.00 0 16451 6 0 0
doc_list_biud 1424640 1424640 0 0.00 0 58371 33 0 0
sp_add_invoice_to_stock 4703 4583 120 2.55 42 149466 16396 0 120
sp_cancel_adding_invoice 5530 5530 0 0.00 4 89050 886 0 0
sp_cancel_client_order 1609 1609 0 0.00 5 32407 407 0 0
sp_cancel_customer_reserve 540210 540210 0 0.00 1 42613 71 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 19587 120 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 3326 71 0 0
sp_cancel_supplier_invoice 6188 6175 13 0.21 2 58450 867 0 13
sp_cancel_supplier_order 417 417 0 0.00 7 1127328 112063 0 0
sp_cancel_write_off 9466 9466 0 0.00 1 8015 126 0 0
sp_client_order 19041 19041 0 0.00 4 42864 397 0 0
sp_customer_reserve 417126 417126 0 0.00 0 86171 163 0 0
sp_fill_shopping_cart 29281 29281 0 0.00 1 17522 44 0 0
sp_fill_shopping_cart_clo_res 417124 417124 0 0.00 0 23723 17 0 0
sp_get_clo_for_invoice 4576 4576 0 0.00 0 49104 445 0 0
sp_kill_cost_storno 16063 16063 0 0.00 0 10038 53 0 0
sp_kill_qstorno_ret_qs2qd 1096478 1096478 0 0.00 0 58368 38 0 0
sp_kill_qty_storno 577210 577210 0 0.00 0 58370 81 0 0
sp_lock_selected_doc 19205 19205 0 0.00 0 7604 7 0 0
sp_make_cost_storno 25886 25886 0 0.00 0 16440 47 0 0
sp_multiply_rows_for_pdistr 25883 25883 0 0.00 0 9074 29 0 0
sp_multiply_rows_for_qdistr 19041 19041 0 0.00 0 15188 210 0 0
sp_pay_from_customer 6608 6608 0 0.00 4 10555 105 0 0
sp_pay_to_supplier 5471 5471 0 0.00 5 32104 116 0 0
sp_payment_common 12077 12077 0 0.00 1 8317 22 0 0
sp_qd_handle_on_cancel_clo 1609 1609 0 0.00 0 15282 328 0 0
sp_qd_handle_on_invoice_upd_sts 10102 10102 0 0.00 0 35509 356 0 0
sp_qd_handle_on_reserve_upd_sts 9231 9231 0 0.00 0 10540 58 0 0
sp_reserve_write_off 9232 9232 0 0.00 6 42898 199 0 0
sp_supplier_invoice 5086 5086 0 0.00 7 94289 3505 0 0
sp_supplier_order 5154 5154 0 0.00 5 44312 1297 0 0
srv_aggregate_perf_data 86 86 0 0.00 2563 205740 18550 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2287 200819 52152 0 0
srv_find_qd_qs_mism 994354 994354 0 0.00 0 10881 5 0 0
srv_make_invnt_saldo 406 406 0 0.00 53 130226 3691 0 0
srv_make_money_saldo 670 670 0 0.00 1 16516 466 0 0
srv_recalc_idx_stat 5 5 0 0.00 54799 460708 248963 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4583 4583 0 0.00 0 9099 147 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 3529 106 0 0
t$perf-norm:sp_cancel_client_order 1609 1609 0 0.00 0 2149 9 0 0
t$perf-norm:sp_cancel_customer_reserve 576 576 0 0.00 0 7628 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2547 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 5724 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 5922 38 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 48658 1507 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 3472 11 0 0
t$perf-norm:sp_client_order 19041 19041 0 0.00 0 6288 15 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 1770 6 0 0
t$perf-norm:sp_pay_from_customer 6608 6608 0 0.00 0 6643 10 0 0
t$perf-norm:sp_pay_to_supplier 5471 5471 0 0.00 0 2936 8 0 0
t$perf-norm:sp_reserve_write_off 9232 9232 0 0.00 0 6823 12 0 0
t$perf-norm:sp_supplier_invoice 5086 5086 0 0.00 0 4565 12 0 0
t$perf-norm:sp_supplier_order 5154 5154 0 0.00 0 4809 13 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 83 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 220 5 0 0
t$perf-norm:srv_make_invnt_saldo 406 406 0 0.00 0 3709 21 0 0
t$perf-norm:srv_make_money_saldo 670 670 0 0.00 0 726 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9384 9144 240 2.55 0 42501 28 0 240
v_all_customers 34666 34666 0 0.00 0 15 1 0 0
v_all_suppliers 28460 28460 0 0.00 0 26 1 0 0
v_all_wares 38082 38082 0 0.00 0 110 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 791 8 0 0
v_cancel_client_order 3218 3218 0 0.00 0 32062 29 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 5451 17 0 0
v_cancel_customer_reserve 1152 1152 0 0.00 0 42504 40 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 960 6 0 0
v_cancel_supplier_invoice 772 746 26 3.36 0 2838 21 0 26
v_cancel_supplier_order 834 834 0 0.00 0 246 2 0 0
v_cancel_write_off 1954 1954 0 0.00 0 3880 17 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 1694 2 0 0
v_max_id_clo_res 942 942 0 0.00 0 2897 12 0 0
v_max_id_ord_sup 5086 5086 0 0.00 0 7307 6 0 0
v_max_non_paid_invoice 1480 1480 0 0.00 0 68 1 0 0
v_max_non_paid_realizn 3587 3587 0 0.00 0 5475 4 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 3251 4 0 0
v_min_id_clo_res 942 942 0 0.00 0 1238 8 0 0
v_min_id_ord_sup 5086 5086 0 0.00 0 9024 9 0 0
v_min_non_paid_invoice 5470 5470 0 0.00 0 8313 12 0 0
v_min_non_paid_realizn 6607 6607 0 0.00 0 8257 19 0 0
v_our_firm 9456 9456 0 0.00 0 18 1 0 0
v_reserve_write_off 18462 18462 0 0.00 0 42803 15 0 0
x_lock_depdocs_on_canc_invoice 11332 11332 0 0.00 0 11652 90 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 4275 272 0 0
x_make_qstorno_1000_1200 5154 5154 0 0.00 2 44298 1229 0 0
x_make_qstorno_1200_2000 5086 5086 0 0.00 3 94282 3328 0 0
x_make_qstorno_2100_3300 417126 417126 0 0.00 0 86169 144 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-11-2020 13:29:35 to 04-11-2020 13:29:40.

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
10:56:56 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
10:57:57 28058 8192000000 9718370560 10013827072 798197584 8.878 100 91 183 1 828878544 8309072 22371888
10:59:35 12579 8192000000 9759433456 10065989632 834813872 9.248 100 88 176 2 869134816 13292672 21028272
11:00:55 17209 8192000000 9804304896 10123993088 870906016 9.609 100 80 160 1 914308064 24931856 18470192
11:02:21 14031 8192000000 9867418224 10201538560 919068944 10.087 100 79 158 1 976786288 40198368 17518976
11:03:37 6837 8192000000 9922187696 10265858048 956749952 10.457 100 72 144 1 1027483968 55772080 14961936
11:04:45 5554 8192000000 9953752288 10312347648 996428400 10.844 100 74 148 1 1072075776 60694768 14952608
11:05:54 2666 8192000000 10016234128 10379059200 1026116768 11.131 100 89 178 1 1125505584 82072672 17316144
11:06:58 2048 8192000000 10023985984 10390958080 1051026432 11.371 100 93 186 1 1136708960 66760864 18921664
11:08:01 4964 8192000000 10008664640 10395045888 1059971264 11.456 100 54 106 1 1134950256 64564800 10414192
11:09:07 23473 8192000000 9996376480 10376327168 1065665376 11.511 100 67 135 1 1111049120 30623440 14760304
11:10:31 11996 8192000000 9976878736 10374701056 1068536768 11.538 100 22 44 3 1105393104 31521280 5335056
11:11:43 8845 8192000000 9993505104 10394542080 1072104624 11.572 100 32 64 0 1104172480 25052608 7015248
11:12:55 7888 8192000000 9994695840 10395504640 1076579232 11.615 100 37 74 0 1094764368 10655280 7529856
11:14:04 8589 8192000000 10000512816 10405462016 1081551440 11.662 100 29 58 1 1105745056 18532384 5661232
11:15:16 39813 8192000000 10003760224 10412277760 1078052048 11.629 99 29 57 0 1114028368 30760160 5216160
11:17:00 5320 8192000000 10006999744 10401316864 1079016704 11.638 100 51 102 0 1118055312 27371168 11667440
11:18:07 8840 8192000000 9966741280 10353225728 1038399424 11.249 98 36 70 2 1070387872 23996400 7992048
11:19:18 6258 8192000000 9949020896 10320580608 1026682336 11.136 98 39 80 2 1052817696 16961728 9173632
11:20:25 24002 8192000000 9912583664 10294697984 919255776 10.089 90 23 46 1 955503472 31849472 4398224
11:21:49 5779 8192000000 9945006400 10330595328 1010434768 10.980 99 34 68 1 1030809216 12785936 7588512
11:22:56 7970 8192000000 9968029840 10349539328 1041298800 11.277 100 37 75 2 1069058480 18260816 9498864
11:24:11 67563 8192000000 9966908608 10363777024 1051822048 11.378 100 39 78 1 1077081376 15487984 9771344
11:26:21 5738 8192000000 9989142528 10389929984 1081204288 11.659 100 45 91 1 1110145040 18903248 10037504
11:27:32 30276 8192000000 10039369616 10429001728 1095686640 11.797 100 53 106 2 1139919024 32910080 11322304
11:29:10 40565 8192000000 10067942336 10474586112 1116324768 11.992 100 37 73 1 1186309712 62437248 7547696
11:30:55 34293 8192000000 10124120736 10528686080 1132169872 12.142 100 45 90 2 1239563280 96912880 10480528
11:32:32 8143 8192000000 10191392480 10592821248 1142780592 12.242 100 60 119 0 1295841040 140083856 12976592
11:33:55 6490 8192000000 10205595600 10610814976 1154366000 12.350 100 58 116 2 1310158736 143259072 12533664
11:35:08 18450 8192000000 10254763424 10667425792 1159664208 12.400 100 52 103 2 1353401104 182394368 11342528
11:36:30 7675 8192000000 10271716176 10680451072 1171412848 12.510 100 52 102 1 1371624720 187534000 12677872
11:37:42 8453 8192000000 10328348640 10737967104 1180833472 12.598 100 62 123 1 1438902192 243438368 14630352
11:38:56 2286 8192000000 10376857344 10782093312 1184944336 12.636 100 65 130 1 1478351952 278179264 15228352
11:40:05 2481 8192000000 10426442592 10860027904 1186607440 12.652 100 72 143 2 1528537168 324734384 17195344
11:41:11 5253 8192000000 10393551520 10834915328 1188870208 12.673 100 40 78 0 1497320944 298639296 9811440
11:42:21 13330 8192000000 10341991984 10791301120 1155826896 12.364 100 35 70 0 1461224992 296629440 8768656
11:43:46 18523 8192000000 10329204704 10773078016 1131487792 12.135 98 34 67 1 1429818224 289945008 8385424
11:45:06 17350 8192000000 10274476528 10688421888 1115091136 11.981 98 37 72 2 1359862768 236153984 8617648
11:46:40 28159 8192000000 10247554576 10669940736 1109759664 11.930 99 28 56 0 1339495088 222794928 6940496
11:48:10 15151 8192000000 10311981344 10733043712 1100440304 11.842 98 43 86 0 1379510928 268134288 10936336
11:49:27 14395 8192000000 10250968768 10726322176 1127915952 12.102 100 24 48 1 1402582624 268007856 6658816
11:50:54 33895 8192000000 10148532240 10610102272 1081261680 11.659 97 13 26 2 1255279872 170566992 3451200
11:53:10 101049 8192000000 10064752832 10507071488 1105841840 11.893 100 24 49 0 1149972208 39400656 4729712
11:56:04 40176 8192000000 10060705952 10543591424 1107119552 11.905 99 12 24 0 1176587264 66239408 3228304
11:57:47 72148 8192000000 10076792800 10512441344 1106410400 11.898 99 20 39 1 1161319376 50992368 3916608
12:00:00 97278 8192000000 10071838000 10523688960 1104328336 11.879 98 34 67 2 1172261136 60257024 7675776
12:02:52 129374 8192000000 10037680448 10487418880 1068380256 11.537 95 21 42 2 1095723840 22956032 4387552
12:06:05 75204 8192000000 10132311360 10585128960 1096995728 11.809 97 26 52 2 1219100400 116349360 5755312
12:08:21 55095 8192000000 10109003744 10652680192 1114006528 11.970 99 12 24 1 1214390928 97761216 2623184
12:10:27 56343 8192000000 10100014144 10594803712 1120000768 12.027 100 15 30 0 1210564912 87044352 3519792
12:13:13 47020 8192000000 10107698752 10543665152 1118853488 12.016 99 20 40 0 1166259232 43553728 3852016
12:15:26 55377 8192000000 10109496480 10595360768 1126133728 12.085 99 20 40 0 1209807504 79680272 3993504
12:17:21 18433 8192000000 10088943392 10505854976 1133264576 12.152 100 54 108 1 1174744864 29197648 12282640
12:18:45 64344 8192000000 10101464848 10550726656 1125850800 12.082 99 25 49 0 1208265680 77117600 5297280
12:21:07 73397 8192000000 10111592624 10576625664 1129710496 12.119 99 43 86 1 1174365328 36491040 8163792
12:23:36 157436 8192000000 10070269200 10522632192 1102952528 11.866 96 19 38 1 1162730720 55105328 4672864
12:27:22 41000 8192000000 10068683488 10535075840 1125400016 12.078 99 11 22 0 1160917664 33661664 1855984
12:29:07 50136 8192000000 10083283088 10534043648 1113487680 11.965 98 11 23 1 1163964400 48145680 2331040
12:30:57 44680 8192000000 10078645680 10552647680 1108187824 11.915 97 16 32 1 1172033088 61059584 2785680
12:32:42 16903 8192000000 10078655456 10511986688 1135032496 12.169 100 35 69 0 1185708032 43083552 7591984
12:34:07 54966 8192000000 10092513072 10538704896 1086970272 11.714 95 25 51 2 1140954640 48692352 5292016
12:36:02 67671 8192000000 10080971968 10539294720 1108861152 11.922 98 24 48 0 1174272160 60280176 5130832
12:38:11 48813 8192000000 10094328080 10507296768 1098228096 11.821 98 34 67 1 1166456896 60736384 7492416
12:40:00 86857 8192000000 10058911696 10486525952 1027106448 11.141 93 28 56 2 1078201584 45649904 5445232
12:42:27 66163 8192000000 10066503376 10533732352 1082841296 11.675 98 25 50 1 1177777936 89717584 5219056
12:44:36 127674 8192000000 10008839152 10442326016 1071765040 11.569 97 19 38 1 1097897376 22619984 3512352
12:48:36 89059 8192000000 10049428160 10542145536 1103767616 11.873 99 7 14 0 1162751008 57326768 1656624
12:51:05 28684 8192000000 10031635776 10449506304 1058918256 11.446 96 21 41 4 1102233568 38659776 4655536
12:52:45 23168 8192000000 10096001424 10516992000 1111819648 11.950 100 24 48 0 1186221152 68290896 6110608
12:54:17 22859 8192000000 10154869072 10580324352 1106313808 11.898 97 45 90 4 1253936480 136551632 11071040
12:55:43 72123 8192000000 10266496496 10717925376 1133156752 12.151 99 31 62 0 1313967792 173491328 7319712
13:00:02 57380 8192000000 10108518256 10555764736 1091483584 11.757 96 25 50 2 1132625824 36351392 4790848
13:02:02 200818 8192000000 10085844080 10539454464 1093396352 11.775 96 17 34 1 1142868576 45664896 3807328
13:06:38 77297 8192000000 10106808960 10585010176 1141335040 12.228 100 13 27 1 1218277664 74115664 2826960
13:08:55 82243 8192000000 10132273440 10582011904 1134236208 12.161 99 14 28 1 1206641024 69231136 3173680
13:11:18 78586 8192000000 10085566496 10529804288 1124011440 12.065 98 15 30 0 1183980016 56290592 3677984
13:13:54 43672 8192000000 10103397808 10552926208 1130867568 12.130 99 27 54 0 1190912448 54074720 5970160
13:15:38 51084 8192000000 10070608848 10503847936 1142216704 12.236 100 24 46 2 1174023152 26847136 4959312
13:17:36 68098 8192000000 10142944608 10592587776 1134372192 12.163 98 28 56 0 1197326608 57215712 5738704
13:19:45 59624 8192000000 10076286192 10574704640 1124303312 12.068 99 20 40 0 1211200176 82962016 3934848
13:21:53 54246 8192000000 10110700816 10572967936 1136002576 12.178 100 25 50 1 1179648336 38776528 4869232
13:23:58 88185 8192000000 10093180368 10601086976 1143893840 12.252 100 10 20 0 1190510448 44526352 2090256
13:26:33 27055 8192000000 8985462416 9273618432 130521168 1.568 12 5 10 0 137367408 5996032 850208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-11-2020 13:29:41 to 04-11-2020 13:29:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-11-2020 13:29:57 to 04-11-2020 13:29:59.

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          226183
MON$OLDEST_ACTIVE               226625
MON$OLDEST_SNAPSHOT             226625
MON$NEXT_TRANSACTION            226625
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                       2089312
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.2244 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2244 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2244 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.2244 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'

04.11.20 13:30:00. 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-04 13:30:02 No severe PSQL-related problems occured
04.11.20 13:30:04. End of report.