Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.11.2020 13: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 16792512 kB
MemAvailable 31466856 kB
Buffers 0 kB
Cached 14910756 kB
SwapCached 2724 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-13 13:26:53
DTS_END                         2020-11-13 13:26:53.6020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36294
TRN_ID                          238163
ATT_ID                          688
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: 8856.34 24281 1062761
customer order: creation 174.40 375 20928
customer order: refuse 14.53 296 1744
order to supplier: creation 47.70 1342 5724
order to supplier: removal 3.03 101117 364
invoice (draft): creation 46.50 3300 5580
invoice (draft): removal 45.71 963 5485
invoice accept: apply 44.28 18883 5314
invoice accept: cancel 41.10 893 4932
customer reserve: creation 4098.85 188 491862
customer reserve: removal 4053.23 59 486387
realization accept: apply 85.71 177 10285
realization accept: cancel 69.96 106 8395
payment from customer: creation 60.06 118 7207
payment from customer: removal 5.63 112 676
payment to supplier: creation 50.52 142 6062
payment to supplier: removal 3.82 69 458
service: total inventory turnovers 4.25 3106 510
service: total monetary turnovers 6.13 479 735
service: aggregate perf. data 0.91 15228 109
service: refresh index statistics 0.03 338669 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-11-2020 13:28:35 to 13-11-2020 13:28:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2602
TEST_TIME 2 3833
TEST_TIME 3 4758
TEST_TIME 4 4847
TEST_TIME 5 5165
TEST_TIME 6 5569
TEST_TIME 7 6227
TEST_TIME 8 6420
TEST_TIME 9 6464
TEST_TIME 10 6328
TEST_TIME 11 6627
TEST_TIME 12 6990
TEST_TIME 13 7049
TEST_TIME 14 7041
TEST_TIME 15 7279
TEST_TIME 16 8397
TEST_TIME 17 8104
TEST_TIME 18 8353
TEST_TIME 19 8623
TEST_TIME 20 8894
TEST_TIME 21 8890
TEST_TIME 22 8853
TEST_TIME 23 9361
TEST_TIME 24 9682
TEST_TIME 25 9993
TEST_TIME 26 10254
TEST_TIME 27 10610
TEST_TIME 28 10269
TEST_TIME 29 9982
TEST_TIME 30 10393
TEST_TIME 31 10214
TEST_TIME 32 10117
TEST_TIME 33 9969
TEST_TIME 34 9815
TEST_TIME 35 9680
TEST_TIME 36 9656
TEST_TIME 37 9612
TEST_TIME 38 9649
TEST_TIME 39 9522
TEST_TIME 40 9622
TEST_TIME 41 9772
TEST_TIME 42 9645
TEST_TIME 43 9583
TEST_TIME 44 9604
TEST_TIME 45 9545
TEST_TIME 46 9424
TEST_TIME 47 9451
TEST_TIME 48 9349
TEST_TIME 49 9295
TEST_TIME 50 9203
TEST_TIME 51 9214
TEST_TIME 52 9330
TEST_TIME 53 9382
TEST_TIME 54 9371
TEST_TIME 55 9387
TEST_TIME 56 9315
TEST_TIME 57 9259
TEST_TIME 58 9411
TEST_TIME 59 9331
TEST_TIME 60 9309
TEST_TIME 61 9195
TEST_TIME 62 9166
TEST_TIME 63 9088
TEST_TIME 64 9056
TEST_TIME 65 9038
TEST_TIME 66 9076
TEST_TIME 67 9026
TEST_TIME 68 8956
TEST_TIME 69 8929
TEST_TIME 70 8878
TEST_TIME 71 8868
TEST_TIME 72 8913
TEST_TIME 73 8918
TEST_TIME 74 8895
TEST_TIME 75 8914
TEST_TIME 76 8954
TEST_TIME 77 8998
TEST_TIME 78 8991
TEST_TIME 79 8977
TEST_TIME 80 8965
TEST_TIME 81 8979
TEST_TIME 82 9092
TEST_TIME 83 9089
TEST_TIME 84 9034
TEST_TIME 85 8972
TEST_TIME 86 8941
TEST_TIME 87 8995
TEST_TIME 88 8983
TEST_TIME 89 8930
TEST_TIME 90 8910
TEST_TIME 91 8859
TEST_TIME 92 8894
TEST_TIME 93 8873
TEST_TIME 94 8842
TEST_TIME 95 8867
TEST_TIME 96 8843
TEST_TIME 97 8886
TEST_TIME 98 8981
TEST_TIME 99 8949
TEST_TIME 100 8962
TEST_TIME 101 8914
TEST_TIME 102 8935
TEST_TIME 103 9079
TEST_TIME 104 9044
TEST_TIME 105 9064
TEST_TIME 106 9111
TEST_TIME 107 9064
TEST_TIME 108 9021
TEST_TIME 109 8983
TEST_TIME 110 8986
TEST_TIME 111 8940
TEST_TIME 112 8940
TEST_TIME 113 8927
TEST_TIME 114 8897
TEST_TIME 115 8865
TEST_TIME 116 8841
TEST_TIME 117 8856
TEST_TIME 118 8840
TEST_TIME 119 8821
TEST_TIME 120 8859

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-11-2020 13:28:38 to 13-11-2020 13:28:42.

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 1513339 1513339 0 0.00 0 13057 7 0 0
doc_list_biud 1513339 1513339 0 0.00 0 42902 25 0 0
sp_add_invoice_to_stock 5409 5314 95 1.75 33 187279 18883 0 95
sp_cancel_adding_invoice 4932 4932 0 0.00 6 60623 894 0 0
sp_cancel_client_order 1744 1744 0 0.00 5 11554 296 0 0
sp_cancel_customer_reserve 486387 486387 0 0.00 1 42905 59 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 8991 112 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 3 3597 70 0 0
sp_cancel_supplier_invoice 5491 5485 6 0.10 2 27294 964 0 6
sp_cancel_supplier_order 364 364 0 0.00 9 947684 101118 0 0
sp_cancel_write_off 8395 8395 0 0.00 1 18493 107 0 0
sp_client_order 20928 20928 0 0.00 4 33977 375 0 0
sp_customer_reserve 491862 491862 0 0.00 0 73657 188 0 0
sp_fill_shopping_cart 32217 32217 0 0.00 1 23992 50 0 0
sp_fill_shopping_cart_clo_res 491858 491858 0 0.00 0 12661 15 0 0
sp_get_clo_for_invoice 5310 5310 0 0.00 0 13893 350 0 0
sp_kill_cost_storno 14461 14461 0 0.00 0 6548 57 0 0
sp_kill_qstorno_ret_qs2qd 987018 987018 0 0.00 0 35902 33 0 0
sp_kill_qty_storno 522902 522902 0 0.00 0 42902 71 0 0
sp_lock_selected_doc 21338 21338 0 0.00 0 4532 2 0 0
sp_make_cost_storno 28864 28864 0 0.00 0 13053 58 0 0
sp_multiply_rows_for_pdistr 28864 28864 0 0.00 0 6387 29 0 0
sp_multiply_rows_for_qdistr 20928 20928 0 0.00 0 33970 171 0 0
sp_pay_from_customer 7207 7207 0 0.00 4 13036 119 0 0
sp_pay_to_supplier 6062 6062 0 0.00 5 13070 143 0 0
sp_payment_common 13269 13269 0 0.00 1 12965 25 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 11542 268 0 0
sp_qd_handle_on_invoice_upd_sts 10242 10242 0 0.00 0 36070 346 0 0
sp_qd_handle_on_reserve_upd_sts 10285 10285 0 0.00 0 18974 54 0 0
sp_reserve_write_off 10285 10285 0 0.00 7 19017 177 0 0
sp_supplier_invoice 5580 5580 0 0.00 7 97808 3300 0 0
sp_supplier_order 5724 5724 0 0.00 5 81926 1342 0 0
srv_aggregate_perf_data 109 109 0 0.00 2747 182093 15229 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1199 140879 51680 0 0
srv_find_qd_qs_mism 1010926 1010926 0 0.00 0 9512 7 0 0
srv_make_invnt_saldo 510 510 0 0.00 41 89958 3107 0 0
srv_make_money_saldo 735 735 0 0.00 1 17127 480 0 0
srv_recalc_idx_stat 4 4 0 0.00 87017 696356 338669 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5315 5315 0 0.00 0 14673 173 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 3067 75 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 7744 21 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 2896 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 1311 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 698 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 750 14 0 0
t$perf-norm:sp_cancel_supplier_order 364 364 0 0.00 0 18483 1368 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 1907 5 0 0
t$perf-norm:sp_client_order 20928 20928 0 0.00 0 18267 19 0 0
t$perf-norm:sp_customer_reserve 1081 1081 0 0.00 0 7259 14 0 0
t$perf-norm:sp_pay_from_customer 7207 7207 0 0.00 0 4166 10 0 0
t$perf-norm:sp_pay_to_supplier 6062 6062 0 0.00 0 6390 11 0 0
t$perf-norm:sp_reserve_write_off 10285 10285 0 0.00 0 6752 9 0 0
t$perf-norm:sp_supplier_invoice 5580 5580 0 0.00 0 6161 18 0 0
t$perf-norm:sp_supplier_order 5724 5724 0 0.00 0 7778 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 535 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 8652 146 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 1842 9 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 426 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 94 27 0 0
v_add_invoice_to_stock 10810 10620 190 1.75 0 10767 19 0 190
v_all_customers 37680 37680 0 0.00 0 231 1 0 0
v_all_suppliers 30738 30738 0 0.00 0 16 1 0 0
v_all_wares 41856 41856 0 0.00 0 233 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 815 8 0 0
v_cancel_client_order 3488 3488 0 0.00 0 7527 7 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 3247 14 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 714 4 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 3566 8 0 0
v_cancel_supplier_invoice 804 792 12 1.49 0 6006 26 0 12
v_cancel_supplier_order 728 728 0 0.00 0 1064 5 0 0
v_cancel_write_off 2058 2058 0 0.00 0 3452 16 0 0
v_max_id_clo_ord 5717 5717 0 0.00 0 5563 2 0 0
v_max_id_clo_res 1081 1081 0 0.00 0 4360 16 0 0
v_max_id_ord_sup 5572 5572 0 0.00 0 4300 4 0 0
v_max_non_paid_invoice 1982 1982 0 0.00 0 1203 3 0 0
v_max_non_paid_realizn 4007 4007 0 0.00 0 5614 4 0 0
v_min_id_clo_ord 5717 5717 0 0.00 0 5725 7 0 0
v_min_id_clo_res 1081 1081 0 0.00 0 9181 35 0 0
v_min_id_ord_sup 5572 5572 0 0.00 0 10378 6 0 0
v_min_non_paid_invoice 6062 6062 0 0.00 0 10644 18 0 0
v_min_non_paid_realizn 7207 7207 0 0.00 0 12959 18 0 0
v_our_firm 10576 10576 0 0.00 0 49 1 0 0
v_reserve_write_off 20570 20570 0 0.00 0 9236 6 0 0
x_lock_depdocs_on_canc_invoice 10021 10021 0 0.00 0 12020 120 0 0
x_lock_depdocs_on_canc_sup_ord 364 364 0 0.00 0 6540 387 0 0
x_make_qstorno_1000_1200 5724 5724 0 0.00 2 76395 1260 0 0
x_make_qstorno_1200_2000 5580 5580 0 0.00 4 88260 3113 0 0
x_make_qstorno_2100_3300 491862 491862 0 0.00 0 73656 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-11-2020 13:28:43 to 13-11-2020 13:28:47.

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:57 14 8192000000 8797107344 8807763968 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:58 37400 8192000000 9710702208 10007826432 790206368 8.797 100 89 178 0 819535216 7680320 21648528
10:59:41 14935 8192000000 9751792720 10054860800 827549968 9.175 100 90 180 0 862313024 12914336 21848720
11:01:02 20329 8192000000 9795005360 10111303680 861375200 9.514 100 83 166 0 899252800 18627856 19249744
11:02:28 15664 8192000000 9847566192 10178801664 898902288 9.887 100 77 154 0 962511488 46350800 17258400
11:03:48 12585 8192000000 9896740208 10240434176 938775776 10.281 100 82 164 1 1008236672 52569744 16891152
11:05:05 8207 8192000000 9944800144 10298556416 983988912 10.723 100 77 152 1 1065567216 65644464 15933840
11:06:14 2632 8192000000 9974900512 10334584832 1013275120 11.007 100 91 178 0 1090855744 59981472 17599152
11:07:19 2437 8192000000 10029994704 10394632192 1038717088 11.252 100 85 170 0 1149909696 93805152 17387456
11:08:24 1642 8192000000 10041905040 10411827200 1059966528 11.456 100 93 182 0 1153998928 75694160 18338240
11:09:26 4256 8192000000 10000632960 10398728192 1069597824 11.548 100 29 56 2 1110002720 33977456 6427440
11:10:31 5095 8192000000 10006756560 10413432832 1080071536 11.648 100 38 77 1 1124663088 36638592 7952960
11:11:40 5291 8192000000 10006963648 10397749248 1086605872 11.710 100 36 69 0 1118909024 25206528 7096624
11:12:46 50682 8192000000 10002813744 10425266176 1084100288 11.687 100 29 58 2 1109588496 19368208 6120000
11:14:37 7507 8192000000 10011516656 10421071872 1090437504 11.747 100 35 70 0 1121195888 23029376 7729008
11:15:45 4656 8192000000 10007968640 10414518272 1096343504 11.803 100 38 76 1 1127092256 22436448 8312304
11:16:51 9203 8192000000 9999090288 10404163584 1086339664 11.708 99 38 77 3 1108875088 14871456 7663968
11:18:04 63540 8192000000 9965082880 10372501504 1019278736 11.065 95 39 78 2 1037265488 9372624 8614128
11:20:11 3827 8192000000 9954832464 10336645120 1000883536 10.887 96 45 91 1 1050712880 39480096 10349248
11:21:16 6428 8192000000 9947756752 10339487744 1015191856 11.026 99 42 82 1 1045052240 20799120 9061264
11:22:25 12551 8192000000 9932772608 10323206144 1015240352 11.026 99 25 50 1 1039316368 18455008 5621008
11:23:38 8327 8192000000 9961506880 10350673920 1042941664 11.293 100 26 52 0 1076849664 27550496 6357504
11:24:47 21360 8192000000 9958709536 10359513088 1048568240 11.347 100 22 44 0 1074224960 21082832 4573888
11:26:09 6471 8192000000 9976815264 10374950912 1067062640 11.524 100 37 70 5 1089114832 14099472 7952720
11:27:22 25592 8192000000 10010562528 10407571456 1090157488 11.744 100 43 85 3 1118396960 19686592 8552880
11:28:53 43500 8192000000 10082999728 10489196544 1106722784 11.901 100 52 104 0 1183416496 65900368 10793344
11:30:39 6029 8192000000 10093614992 10495475712 1124688368 12.071 100 55 110 1 1196408624 59879616 11840640
11:31:48 5891 8192000000 10127223568 10540437504 1133867392 12.158 100 49 94 2 1230443536 87073744 9502400
11:32:58 9917 8192000000 10158033840 10581745664 1146840128 12.280 100 62 124 1 1262086912 102103152 13143632
11:34:11 8418 8192000000 10154417488 10567630848 1151306720 12.322 100 55 110 1 1263457360 100044144 12106496
11:35:24 5946 8192000000 10196028080 10616414208 1160319408 12.406 100 64 124 3 1302992864 128677504 13995952
11:36:44 8397 8192000000 10228775248 10642862080 1169055936 12.488 100 59 118 1 1335928080 152949680 13922464
11:37:55 1414 8192000000 10276735344 10682138624 1179972416 12.590 100 88 176 3 1377602608 177282448 20347744
11:38:59 1975 8192000000 10305150272 10711392256 1185600000 12.642 100 87 173 2 1400781312 196070224 19111088
11:40:06 5241 8192000000 10307670560 10715000832 1162463712 12.426 100 70 140 1 1403251168 224496256 16291200
11:41:19 1195 8192000000 10313052848 10733670400 1155576240 12.362 100 80 159 6 1413375872 238921744 18877888
11:42:23 2082 8192000000 10337652576 10773798912 1143771520 12.251 98 91 182 2 1415654192 252089008 19793664
11:43:30 8482 8192000000 10288607424 10707902464 1170907392 12.505 100 45 90 0 1390473216 209546464 10019360
11:45:03 15502 8192000000 10238461136 10681384960 1102795120 11.864 96 34 66 0 1333753824 222681808 8276896
11:46:23 18778 8192000000 10235421888 10655404032 1089359680 11.737 96 33 65 1 1307803632 210813232 7630720
11:47:46 28917 8192000000 10268907184 10691743744 1076676704 11.616 96 29 58 2 1328209728 244136656 7396368
11:49:22 14085 8192000000 10282436720 10732142592 1107938176 11.913 99 30 60 1 1372472976 256167616 8367184
11:51:10 25911 8192000000 10165133040 10643542016 1086157504 11.706 98 22 44 2 1270031504 178079392 5794608
11:52:38 12312 8192000000 10195237088 10641334272 1100385536 11.841 99 17 34 0 1291754288 186545536 4823216
11:53:51 85941 8192000000 10115386528 10536194048 1112049040 11.952 100 30 58 1 1232390432 113956368 6385024
11:57:01 33234 8192000000 10080872512 10520625152 1120955184 12.036 100 22 42 4 1185549504 60197424 4396896
11:58:58 61141 8192000000 10086305808 10512977920 1110579312 11.938 98 31 60 1 1184250480 67606096 6065072
12:01:20 63875 8192000000 10144323280 10576289792 1123331488 12.058 99 27 56 0 1256524944 127209312 5984144
12:04:19 91016 8192000000 10161957072 10610495488 1128085392 12.103 99 20 40 1 1239765696 107665936 4014368
12:06:51 33914 8192000000 10088620528 10574938112 1131886784 12.139 100 24 48 0 1233462352 95895312 5680256
12:08:27 56127 8192000000 10104499840 10543869952 1142010176 12.234 100 18 36 2 1177476720 31650272 3816272
12:10:32 75118 8192000000 10080211888 10555637760 1140746688 12.223 100 8 16 2 1183672208 41238768 1686752
12:12:48 110653 8192000000 10064214736 10511233024 1125028192 12.074 98 10 20 0 1173902304 46817376 2056736
12:15:39 35684 8192000000 10086361856 10523779072 1148566688 12.296 100 31 62 1 1197848416 43243232 6038496
12:17:20 67903 8192000000 10126538384 10601648128 1139470256 12.211 99 29 58 3 1251784768 106306704 6007808
12:20:08 140878 8192000000 10072956560 10524045312 1112482208 11.956 97 12 24 0 1170102208 55142096 2477904
12:23:30 35078 8192000000 10137334448 10593148928 1151568768 12.324 100 26 52 0 1216432896 59150272 5713856
12:25:09 24765 8192000000 10081597776 10509926400 1146466496 12.276 100 50 99 2 1172390864 15085936 10838432
12:26:34 76924 8192000000 10092559136 10534907904 1147883712 12.290 100 12 24 0 1221062720 70913312 2265696
12:29:03 13284 8192000000 10070777936 10511032320 1137609888 12.193 99 26 50 3 1165852208 23527936 4714384
12:30:20 114649 8192000000 10074787424 10571259904 1099465088 11.833 96 27 52 1 1169769232 65175504 5128640
12:33:28 49538 8192000000 10012473680 10452287488 1056702736 11.425 95 10 20 1 1082815360 24214304 1898320
12:35:32 62576 8192000000 10040151792 10479747072 1091102496 11.753 98 23 45 1 1130752912 34448096 5202320
12:37:35 98661 8192000000 10047038432 10488696832 1072297328 11.574 97 12 24 0 1136914736 62214432 2402976
12:40:15 102501 8192000000 10065037984 10514665472 1036644704 11.232 93 19 38 1 1130986272 90174080 4167488
12:42:57 43298 8192000000 10079444448 10563358720 1075864688 11.608 98 21 42 2 1158641952 78342432 4434832
12:44:48 28386 8192000000 10055306944 10478592000 1101074992 11.848 100 41 80 3 1148024736 38925680 8024064
12:46:18 31237 8192000000 10081860928 10506407936 1084202032 11.687 98 48 96 1 1150514192 57158608 9153552
12:47:52 78043 8192000000 10053776016 10533675008 1075729760 11.607 97 17 35 0 1142132560 63129952 3272848
12:50:19 39304 8192000000 10069101984 10483949568 1078061312 11.629 97 46 91 3 1126565184 39609664 8894208
12:51:58 84281 8192000000 10076962688 10525523968 1099387904 11.832 99 32 64 1 1192027776 86314720 6325152
12:54:27 135786 8192000000 10051376496 10545520640 1110666960 11.939 99 9 18 0 1168351856 55823888 1861008
12:57:43 136491 8192000000 10075606432 10537332736 1101602656 11.853 97 16 32 1 1140872016 36312896 2956464
13:01:03 57128 8192000000 10108322032 10551930880 1141326304 12.228 99 18 36 0 1206019632 60960608 3732720
13:03:02 137853 8192000000 10094640816 10577911808 1135492240 12.173 99 16 30 1 1218055472 79415248 3147984
13:06:20 60879 8192000000 10122518592 10559033344 1146175616 12.274 99 33 66 2 1196624720 43320624 7128480
13:08:22 20142 8192000000 10192030512 10644410368 1155755408 12.363 100 26 52 0 1294998912 133462464 5781040
13:09:42 75978 8192000000 10113142976 10547347456 1131991600 12.140 98 20 40 1 1177509376 41390752 4127024
13:11:58 64545 8192000000 10087487392 10572165120 1079162704 11.639 95 24 48 1 1126669232 43078016 4428512
13:14:09 65212 8192000000 10057596704 10503774208 1109832832 11.931 98 28 56 3 1147832640 32394032 5605776
13:16:23 103711 8192000000 10081889760 10551898112 1131138320 12.132 99 9 18 0 1182669168 49879360 1651488
13:19:08 83909 8192000000 10063614384 10532687872 1071075008 11.562 94 11 22 0 1110552608 37131984 2345616
13:21:32 56247 8192000000 10093825312 10543366144 1109969088 11.932 99 19 36 0 1164074224 49950160 4154976
13:23:58 115198 8192000000 10083898688 10532065280 1064434992 11.499 94 19 38 0 1158624976 89860320 4329664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-11-2020 13:28:49 to 13-11-2020 13:29:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          313
MON$OLDEST_ACTIVE               238723
MON$OLDEST_SNAPSHOT             238723
MON$NEXT_TRANSACTION            238723
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                       2072800
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             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2260 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2260 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2260 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.2260 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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Fri Nov 13 13:27:04 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
--- end of diff output ---

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

13.11.20 13:29:07. 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-13 13:29:10 No severe PSQL-related problems occured
13.11.20 13:29:11. End of report.