Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.01.2021 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 16806032 kB
MemAvailable 31322744 kB
Buffers 0 kB
Cached 14904680 kB
SwapCached 4868 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-24 13:26:55
DTS_END                         2021-01-24 13:26:55.0550
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/38956
TRN_ID                          230908
ATT_ID                          629
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: 8596.35 24626 1031562
customer order: creation 166.37 400 19964
customer order: refuse 13.54 318 1625
order to supplier: creation 44.18 1497 5301
order to supplier: removal 3.00 125260 360
invoice (draft): creation 43.96 3776 5275
invoice (draft): removal 47.65 985 5718
invoice accept: apply 40.58 17182 4870
invoice accept: cancel 43.06 937 5167
customer reserve: creation 3720.73 166 446488
customer reserve: removal 4192.62 70 503114
realization accept: apply 80.07 195 9608
realization accept: cancel 77.72 138 9326
payment from customer: creation 57.13 152 6856
payment from customer: removal 5.21 118 625
payment to supplier: creation 46.24 135 5549
payment to supplier: removal 3.28 109 394
service: total inventory turnovers 3.86 3487 463
service: total monetary turnovers 6.15 569 738
service: aggregate perf. data 0.97 13268 116
service: refresh index statistics 0.04 323761 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-01-2021 13:29:25 to 24-01-2021 13:29:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1660
TEST_TIME 2 2686
TEST_TIME 3 2964
TEST_TIME 4 3257
TEST_TIME 5 4033
TEST_TIME 6 4007
TEST_TIME 7 4117
TEST_TIME 8 4540
TEST_TIME 9 5221
TEST_TIME 10 5451
TEST_TIME 11 5777
TEST_TIME 12 6048
TEST_TIME 13 6301
TEST_TIME 14 6302
TEST_TIME 15 6552
TEST_TIME 16 6925
TEST_TIME 17 7079
TEST_TIME 18 7404
TEST_TIME 19 7397
TEST_TIME 20 7521
TEST_TIME 21 7559
TEST_TIME 22 8356
TEST_TIME 23 8533
TEST_TIME 24 8884
TEST_TIME 25 8982
TEST_TIME 26 9471
TEST_TIME 27 10279
TEST_TIME 28 11092
TEST_TIME 29 10821
TEST_TIME 30 10578
TEST_TIME 31 10357
TEST_TIME 32 10450
TEST_TIME 33 10222
TEST_TIME 34 10007
TEST_TIME 35 9997
TEST_TIME 36 9840
TEST_TIME 37 9682
TEST_TIME 38 9644
TEST_TIME 39 9531
TEST_TIME 40 9435
TEST_TIME 41 9461
TEST_TIME 42 9349
TEST_TIME 43 9197
TEST_TIME 44 9234
TEST_TIME 45 9329
TEST_TIME 46 9197
TEST_TIME 47 9066
TEST_TIME 48 8986
TEST_TIME 49 8969
TEST_TIME 50 9012
TEST_TIME 51 9020
TEST_TIME 52 8987
TEST_TIME 53 9035
TEST_TIME 54 9099
TEST_TIME 55 9022
TEST_TIME 56 9031
TEST_TIME 57 8994
TEST_TIME 58 8964
TEST_TIME 59 8987
TEST_TIME 60 9130
TEST_TIME 61 9187
TEST_TIME 62 9086
TEST_TIME 63 8951
TEST_TIME 64 8911
TEST_TIME 65 8869
TEST_TIME 66 8840
TEST_TIME 67 8820
TEST_TIME 68 8786
TEST_TIME 69 8808
TEST_TIME 70 8794
TEST_TIME 71 8837
TEST_TIME 72 8856
TEST_TIME 73 8842
TEST_TIME 74 8896
TEST_TIME 75 8867
TEST_TIME 76 8872
TEST_TIME 77 8915
TEST_TIME 78 8870
TEST_TIME 79 8930
TEST_TIME 80 9005
TEST_TIME 81 9047
TEST_TIME 82 8957
TEST_TIME 83 8878
TEST_TIME 84 8863
TEST_TIME 85 8805
TEST_TIME 86 8744
TEST_TIME 87 8695
TEST_TIME 88 8663
TEST_TIME 89 8721
TEST_TIME 90 8686
TEST_TIME 91 8664
TEST_TIME 92 8675
TEST_TIME 93 8700
TEST_TIME 94 8680
TEST_TIME 95 8705
TEST_TIME 96 8808
TEST_TIME 97 8805
TEST_TIME 98 8831
TEST_TIME 99 8839
TEST_TIME 100 8888
TEST_TIME 101 8823
TEST_TIME 102 8746
TEST_TIME 103 8749
TEST_TIME 104 8714
TEST_TIME 105 8701
TEST_TIME 106 8668
TEST_TIME 107 8638
TEST_TIME 108 8642
TEST_TIME 109 8637
TEST_TIME 110 8675
TEST_TIME 111 8676
TEST_TIME 112 8681
TEST_TIME 113 8710
TEST_TIME 114 8736
TEST_TIME 115 8775
TEST_TIME 116 8753
TEST_TIME 117 8768
TEST_TIME 118 8702
TEST_TIME 119 8647
TEST_TIME 120 8599

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-01-2021 13:29:28 to 24-01-2021 13:29:32.

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 1444303 1444303 0 0.00 0 15611 7 0 0
doc_list_biud 1444303 1444303 0 0.00 0 34388 31 0 0
sp_add_invoice_to_stock 4988 4870 118 2.36 40 208602 17182 0 118
sp_cancel_adding_invoice 5167 5167 0 0.00 5 56871 938 0 0
sp_cancel_client_order 1625 1625 0 0.00 4 8021 319 0 0
sp_cancel_customer_reserve 503114 503114 0 0.00 1 34948 70 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 11450 119 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 5023 110 0 0
sp_cancel_supplier_invoice 5730 5718 12 0.20 3 34394 985 0 12
sp_cancel_supplier_order 360 360 0 0.00 8 1257518 125260 0 0
sp_cancel_write_off 9326 9326 0 0.00 1 11195 138 0 0
sp_client_order 19964 19964 0 0.00 4 21554 401 0 0
sp_customer_reserve 446488 446488 0 0.00 0 37279 166 0 0
sp_fill_shopping_cart 30536 30536 0 0.00 1 26226 51 0 0
sp_fill_shopping_cart_clo_res 446488 446488 0 0.00 0 21748 18 0 0
sp_get_clo_for_invoice 4870 4870 0 0.00 0 33813 490 0 0
sp_kill_cost_storno 15512 15512 0 0.00 0 7033 69 0 0
sp_kill_qstorno_ret_qs2qd 1021632 1021632 0 0.00 0 34387 38 0 0
sp_kill_qty_storno 539788 539788 0 0.00 0 34388 82 0 0
sp_lock_selected_doc 19950 19950 0 0.00 0 8201 5 0 0
sp_make_cost_storno 26883 26883 0 0.00 0 14973 62 0 0
sp_multiply_rows_for_pdistr 26883 26883 0 0.00 0 7919 34 0 0
sp_multiply_rows_for_qdistr 19964 19964 0 0.00 0 21517 218 0 0
sp_pay_from_customer 6856 6856 0 0.00 4 15165 153 0 0
sp_pay_to_supplier 5549 5549 0 0.00 5 13282 135 0 0
sp_payment_common 12405 12405 0 0.00 1 11234 40 0 0
sp_qd_handle_on_cancel_clo 1625 1625 0 0.00 0 8014 281 0 0
sp_qd_handle_on_invoice_upd_sts 10037 10037 0 0.00 0 27927 405 0 0
sp_qd_handle_on_reserve_upd_sts 9608 9608 0 0.00 0 12494 51 0 0
sp_reserve_write_off 9608 9608 0 0.00 7 15623 195 0 0
sp_supplier_invoice 5275 5275 0 0.00 7 98118 3777 0 0
sp_supplier_order 5301 5301 0 0.00 5 54528 1498 0 0
srv_aggregate_perf_data 116 116 0 0.00 2866 114205 13268 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1567 164026 53228 0 0
srv_find_qd_qs_mism 985335 985335 0 0.00 0 15124 6 0 0
srv_make_invnt_saldo 463 463 0 0.00 42 110802 3487 0 0
srv_make_money_saldo 738 738 0 0.00 1 14274 569 0 0
srv_recalc_idx_stat 5 5 0 0.00 152642 562757 323762 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4872 4872 0 0.00 0 20367 185 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 3456 85 0 0
t$perf-norm:sp_cancel_client_order 1625 1625 0 0.00 0 5562 16 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 1627 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 4053 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1596 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 6440 30 0 0
t$perf-norm:sp_cancel_supplier_order 360 360 0 0.00 0 20484 1476 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 1560 5 0 0
t$perf-norm:sp_client_order 19964 19964 0 0.00 0 9151 18 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 2414 6 0 0
t$perf-norm:sp_pay_from_customer 6856 6856 0 0.00 0 3342 8 0 0
t$perf-norm:sp_pay_to_supplier 5549 5549 0 0.00 0 5251 12 0 0
t$perf-norm:sp_reserve_write_off 9608 9608 0 0.00 0 18198 12 0 0
t$perf-norm:sp_supplier_invoice 5275 5275 0 0.00 0 5857 18 0 0
t$perf-norm:sp_supplier_order 5302 5302 0 0.00 0 7528 19 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 166 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 324 8 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 1782 12 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 364 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 9976 9740 236 2.36 0 6626 21 0 236
v_all_customers 36278 36278 0 0.00 0 34 1 0 0
v_all_suppliers 28922 28922 0 0.00 0 18 1 0 0
v_all_wares 39928 39928 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 2040 12 0 0
v_cancel_client_order 3250 3250 0 0.00 0 4123 8 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 5165 15 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 1539 6 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 4723 12 0 0
v_cancel_supplier_invoice 780 756 24 3.07 0 3423 21 0 24
v_cancel_supplier_order 720 720 0 0.00 0 6141 15 0 0
v_cancel_write_off 1984 1984 0 0.00 0 6796 28 0 0
v_max_id_clo_ord 5300 5300 0 0.00 0 578 1 0 0
v_max_id_clo_res 977 977 0 0.00 0 6816 20 0 0
v_max_id_ord_sup 5272 5272 0 0.00 0 2681 3 0 0
v_max_non_paid_invoice 1660 1660 0 0.00 0 3300 4 0 0
v_max_non_paid_realizn 3605 3605 0 0.00 0 4343 8 0 0
v_min_id_clo_ord 5300 5300 0 0.00 0 5405 10 0 0
v_min_id_clo_res 977 977 0 0.00 0 4462 16 0 0
v_min_id_ord_sup 5272 5272 0 0.00 0 12635 10 0 0
v_min_non_paid_invoice 5549 5549 0 0.00 0 6814 21 0 0
v_min_non_paid_realizn 6856 6856 0 0.00 0 7347 37 0 0
v_our_firm 10152 10152 0 0.00 0 12 1 0 0
v_reserve_write_off 19216 19216 0 0.00 0 5859 8 0 0
x_lock_depdocs_on_canc_invoice 10507 10507 0 0.00 0 20844 132 0 0
x_lock_depdocs_on_canc_sup_ord 360 360 0 0.00 0 6993 403 0 0
x_make_qstorno_1000_1200 5301 5301 0 0.00 2 54399 1415 0 0
x_make_qstorno_1200_2000 5275 5275 0 0.00 4 95885 3570 0 0
x_make_qstorno_2100_3300 446488 446488 0 0.00 0 37277 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-01-2021 13:29:33 to 24-01-2021 13:29:37.

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:58 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
10:57:59 41305 8192000000 9734498064 10032111616 801825008 8.915 100 86 172 0 830533744 7980336 20728400
10:59:43 22369 8192000000 9775389328 10081206272 842862144 9.328 100 92 184 1 878621664 13838944 21920576
11:01:12 20778 8192000000 9823963840 10142658560 874599264 9.646 100 83 166 1 924574544 30771088 19204192
11:02:38 6412 8192000000 9863762816 10199203840 915873488 10.055 100 76 151 1 980697312 47647664 17176160
11:03:50 5422 8192000000 9907359312 10253869056 965885056 10.547 100 75 150 0 1024685616 43423632 15376928
11:05:00 7057 8192000000 9971314896 10330689536 1001718432 10.895 100 76 152 3 1093482256 76439968 15323856
11:06:10 2300 8192000000 10002708672 10360954880 1024388880 11.114 100 84 168 0 1122548752 80931552 17228320
11:07:15 1739 8192000000 10037603328 10405371904 1048471296 11.346 100 93 185 0 1146431872 79256416 18704160
11:08:18 30051 8192000000 10033346352 10414579712 1067252816 11.526 100 67 132 2 1150895104 69950112 13692176
11:09:49 5130 8192000000 10002788224 10383093760 1072937744 11.580 100 51 102 0 1109802992 25941520 10923728
11:10:56 13644 8192000000 9989072480 10391236608 1076635456 11.615 100 19 38 0 1103082000 22787744 3658800
11:12:12 67354 8192000000 10016600336 10427199488 1085279328 11.698 100 30 60 1 1111719296 19855568 6584400
11:14:21 4284 8192000000 10035926112 10442776576 1090388768 11.746 100 36 71 0 1157101888 58663712 8049408
11:15:27 7815 8192000000 10005072400 10414043136 1098075600 11.819 100 25 50 2 1132857808 28616480 6165728
11:16:36 7877 8192000000 10009963136 10411667456 1052915504 11.389 97 32 65 1 1087882784 28093312 6873968
11:17:44 31826 8192000000 9987107872 10383708160 1072217040 11.573 99 40 79 1 1096249632 15215552 8817040
11:19:17 7582 8192000000 9952470896 10345140224 1002449264 10.902 94 29 56 2 1027562800 19188144 5925392
11:20:25 8123 8192000000 9929095744 10318901248 979264496 10.677 95 38 79 1 1000318704 12408032 8646176
11:21:34 6281 8192000000 9942350384 10325770240 1010640160 10.982 98 29 55 1 1049138560 32585696 5912704
11:22:42 41828 8192000000 9939666768 10340503552 1028385504 11.153 99 24 48 1 1059965152 26457408 5122240
11:24:27 7471 8192000000 9958751200 10352939008 1043928400 11.302 99 24 48 1 1066516416 17511872 5076144
11:25:38 8607 8192000000 9991621232 10391314432 1068070720 11.534 100 63 121 1 1092371872 10245360 14055792
11:26:47 11249 8192000000 10003234320 10405920768 1090018352 11.743 100 30 58 1 1120866912 24190016 6658544
11:28:05 18681 8192000000 10036036144 10425425920 1106620240 11.900 100 44 87 2 1135896704 19906048 9370416
11:29:29 56936 8192000000 10070968160 10485096448 1116526384 11.994 100 27 52 1 1181872832 59658608 5687840
11:31:32 38607 8192000000 10128447216 10542157824 1140602064 12.221 100 42 85 2 1230535632 80619808 9313760
11:33:21 33552 8192000000 10179963248 10598199296 1154015648 12.347 100 42 84 1 1289644800 125878112 9751040
11:35:05 1959 8192000000 10241034080 10640523264 1169009120 12.488 100 85 169 2 1347655328 160595328 18050880
11:36:09 10504 8192000000 10249424800 10658627584 1162891200 12.430 100 73 145 1 1353642752 174625152 16126400
11:37:26 8491 8192000000 10248388768 10657832960 1177458464 12.566 100 64 128 2 1352855344 161541888 13854992
11:38:41 3846 8192000000 10294993600 10698354688 1179390000 12.585 100 72 145 1 1399984080 204288192 16305888
11:39:47 4419 8192000000 10318784928 10723020800 1166945584 12.468 100 68 135 0 1426034640 244084336 15004720
11:40:54 6084 8192000000 10321369680 10739380224 1168088912 12.479 100 61 121 0 1434238400 252175808 13973680
11:42:05 3181 8192000000 10369802016 10769342464 1151843216 12.327 98 82 164 0 1456983760 286246880 18893664
11:43:13 1834 8192000000 10391238560 10783789056 1131951424 12.140 98 87 172 1 1468739984 317334208 19454352
11:44:21 3017 8192000000 10448066896 10844721152 1171189408 12.508 100 88 175 0 1541951872 350704640 20057824
11:45:36 7011 8192000000 10453775104 10864709632 1153753648 12.345 99 78 156 0 1544300480 371864672 18682160
11:46:47 1894 8192000000 10477650048 10892410880 1148870192 12.299 99 84 165 1 1566983328 398069104 20044032
11:47:55 1565 8192000000 10507371664 10917785600 1148056768 12.291 99 89 180 1 1616097872 446630960 21410144
11:48:59 19206 8192000000 10439393296 10877620224 1145837168 12.270 100 45 90 1 1532877888 375098544 11942176
11:50:25 37259 8192000000 10398787312 10850222080 1124393552 12.068 99 30 60 0 1533204688 400513248 8297888
11:52:15 7796 8192000000 10381161376 10808012800 1112518064 11.956 100 35 70 3 1467561376 346090224 8953088
11:53:36 32285 8192000000 10132864768 10687811584 1088151888 11.725 98 18 36 0 1345573120 252787504 4633728
11:55:12 49857 8192000000 10070006368 10492096512 1107260800 11.906 99 20 40 1 1170548944 59598576 3689568
11:57:08 73996 8192000000 10115805424 10559541248 1107361184 11.907 98 19 38 1 1191379680 80238736 3779760
12:00:03 87858 8192000000 10083884944 10522484736 1117560992 12.004 98 27 53 1 1174016960 50772944 5683024
12:02:32 38246 8192000000 10088177024 10536366080 1128121888 12.104 99 26 52 0 1174576656 40892656 5562112
12:04:10 66644 8192000000 10103961760 10580819968 1121140672 12.038 98 15 30 0 1190755984 66509408 3105904
12:06:17 22742 8192000000 10131252544 10579259392 1136112176 12.179 100 20 39 0 1229603312 88831040 4660096
12:08:21 164026 8192000000 10131248512 10588860416 1129011392 12.112 98 20 40 0 1245577808 111588496 4977920
12:13:41 88388 8192000000 10084935168 10538520576 1134518048 12.164 99 9 18 0 1169044848 32357280 2169520
12:16:10 136481 8192000000 10112212256 10586578944 1101824256 11.855 96 10 20 0 1163923136 60182064 1916816
12:19:29 99002 8192000000 10076993040 10570313728 1125791696 12.082 98 10 20 0 1216553760 88460656 2301408
12:22:16 61017 8192000000 10083155360 10514268160 1109151840 11.924 97 19 38 1 1146539280 32994224 4393216
12:24:21 64780 8192000000 10139428192 10582736896 1123038704 12.056 98 24 48 1 1203096256 75354096 4703456
12:26:26 145538 8192000000 10045434304 10501935104 1084768800 11.693 95 15 30 3 1126580352 38227584 3583968
12:30:10 65655 8192000000 10083513184 10546966528 1127182688 12.095 99 16 32 1 1174441664 44146928 3112048
12:32:16 62472 8192000000 10034410112 10503016448 1090378704 11.746 96 11 21 2 1129013552 36678912 1955936
12:34:18 28642 8192000000 10043746256 10478587904 1092520384 11.767 98 21 42 0 1123630320 27076240 4033696
12:35:47 29227 8192000000 10042802384 10471313408 1084710768 11.692 97 15 30 1 1105901648 18448192 2742688
12:37:26 71505 8192000000 10065851904 10490990592 1061901536 11.475 95 23 47 0 1132520128 66023456 4595136
12:39:37 96333 8192000000 10053960624 10483843072 1079236384 11.640 97 17 34 0 1105110400 22412848 3461168
12:42:16 58605 8192000000 10065102464 10510446592 1096422992 11.804 99 28 56 0 1154204464 51489520 6291952
12:44:21 71768 8192000000 10078481408 10535378944 1092473712 11.766 99 34 68 2 1143786224 44002224 7310288
12:46:35 103211 8192000000 10042956016 10508226560 1066905344 11.523 95 15 30 0 1102795616 33314992 2575280
12:49:20 61757 8192000000 10029853520 10489012224 1080888832 11.656 97 25 50 1 1109456064 22658560 5908672
12:51:22 51512 8192000000 10027180704 10474688512 1100215056 11.840 99 10 21 1 1127308128 24975936 2117136
12:53:15 65263 8192000000 10054659936 10512285696 1110071888 11.933 99 10 20 1 1156408544 44269360 2067296
12:55:31 67877 8192000000 10028857136 10481119232 1060157616 11.458 96 14 28 0 1123400208 60178656 3063936
12:57:59 42758 8192000000 10103166912 10562920448 1127482880 12.098 100 28 56 0 1220674112 87032352 6158880
12:59:53 71804 8192000000 10166273168 10620051456 1135075536 12.169 100 21 42 0 1234019920 94419008 4525376
13:02:36 65146 8192000000 10101461504 10539347968 1124341888 12.068 99 30 60 2 1172122736 41968336 5812512
13:04:42 54380 8192000000 10108665456 10568212480 1128377776 12.106 99 29 58 1 1198822176 65232288 5212112
13:06:42 38839 8192000000 10088161472 10511142912 1129495984 12.117 99 29 56 1 1183633808 45602256 8535568
13:08:21 94596 8192000000 10063958480 10538782720 1121748848 12.044 98 14 28 1 1164595376 39918496 2928032
13:11:16 47247 8192000000 10078742864 10540998656 1125578304 12.080 98 9 17 1 1163907120 36222624 2106192
13:13:03 83377 8192000000 10093021424 10548400128 1100385968 11.841 96 17 34 0 1144787552 40114704 4286880
13:15:27 87618 8192000000 10110187248 10606891008 1099524080 11.833 97 16 32 0 1210481456 107338800 3618576
13:17:57 69289 8192000000 10093519472 10555609088 1118511216 12.013 99 23 46 0 1201813696 77969424 5333056
13:20:12 127827 8192000000 10087918608 10592751616 1081348208 11.660 95 14 28 2 1139863616 55960560 2554848
13:23:20 111781 8192000000 10096274592 10573058048 1135226128 12.171 100 19 38 1 1200262928 61175216 3861584
13:26:12 57088 8192000000 9501983232 9887854592 624968688 7.088 55 5 10 0 666856448 40821936 1065824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-01-2021 13:29:39 to 24-01-2021 13:29:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-01-2021 13:29:54 to 24-01-2021 13:29:56.

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          231974
MON$OLDEST_ACTIVE               231975
MON$OLDEST_SNAPSHOT             231975
MON$NEXT_TRANSACTION            231975
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                       2087712
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2341 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2341 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2341 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.2341 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'

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