Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.02.2021 10: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 16853976 kB
MemAvailable 31277576 kB
Buffers 0 kB
Cached 14862148 kB
SwapCached 4876 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-02-01 10:26:54
DTS_END                         2021-02-01 10:26:54.1960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44196
TRN_ID                          229178
ATT_ID                          615
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: 8793.08 25260 1055169
customer order: creation 166.33 430 19959
customer order: refuse 13.93 326 1672
order to supplier: creation 43.51 1462 5221
order to supplier: removal 3.38 108094 406
invoice (draft): creation 43.99 3581 5279
invoice (draft): removal 49.47 1003 5936
invoice accept: apply 39.86 18271 4783
invoice accept: cancel 44.53 951 5344
customer reserve: creation 3721.33 177 446559
customer reserve: removal 4387.52 66 526502
realization accept: apply 79.23 184 9508
realization accept: cancel 77.43 113 9291
payment from customer: creation 56.58 122 6790
payment from customer: removal 5.58 89 669
payment to supplier: creation 46.48 144 5577
payment to supplier: removal 3.30 72 396
service: total inventory turnovers 3.81 3378 457
service: total monetary turnovers 5.91 511 709
service: aggregate perf. data 0.90 16260 108
service: refresh index statistics 0.03 349957 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-02-2021 10:30:10 to 01-02-2021 10:30:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5340
TEST_TIME 2 4973
TEST_TIME 3 5925
TEST_TIME 4 5686
TEST_TIME 5 4943
TEST_TIME 6 4863
TEST_TIME 7 5112
TEST_TIME 8 5363
TEST_TIME 9 5316
TEST_TIME 10 5693
TEST_TIME 11 6114
TEST_TIME 12 6236
TEST_TIME 13 6654
TEST_TIME 14 7006
TEST_TIME 15 7086
TEST_TIME 16 7259
TEST_TIME 17 7550
TEST_TIME 18 8356
TEST_TIME 19 8733
TEST_TIME 20 8647
TEST_TIME 21 8718
TEST_TIME 22 8997
TEST_TIME 23 9227
TEST_TIME 24 10190
TEST_TIME 25 10236
TEST_TIME 26 10334
TEST_TIME 27 10864
TEST_TIME 28 10612
TEST_TIME 29 10284
TEST_TIME 30 10077
TEST_TIME 31 9915
TEST_TIME 32 9984
TEST_TIME 33 9770
TEST_TIME 34 9734
TEST_TIME 35 9696
TEST_TIME 36 9576
TEST_TIME 37 9426
TEST_TIME 38 9341
TEST_TIME 39 9228
TEST_TIME 40 9092
TEST_TIME 41 9113
TEST_TIME 42 9148
TEST_TIME 43 9103
TEST_TIME 44 9119
TEST_TIME 45 9012
TEST_TIME 46 8908
TEST_TIME 47 8758
TEST_TIME 48 8720
TEST_TIME 49 8780
TEST_TIME 50 8682
TEST_TIME 51 8724
TEST_TIME 52 8704
TEST_TIME 53 8677
TEST_TIME 54 8657
TEST_TIME 55 8740
TEST_TIME 56 8717
TEST_TIME 57 8816
TEST_TIME 58 8941
TEST_TIME 59 9054
TEST_TIME 60 9103
TEST_TIME 61 9158
TEST_TIME 62 9201
TEST_TIME 63 9126
TEST_TIME 64 9029
TEST_TIME 65 9038
TEST_TIME 66 9008
TEST_TIME 67 9025
TEST_TIME 68 8950
TEST_TIME 69 8950
TEST_TIME 70 8950
TEST_TIME 71 8963
TEST_TIME 72 9017
TEST_TIME 73 8972
TEST_TIME 74 8945
TEST_TIME 75 9071
TEST_TIME 76 9065
TEST_TIME 77 9062
TEST_TIME 78 9140
TEST_TIME 79 9150
TEST_TIME 80 9144
TEST_TIME 81 9093
TEST_TIME 82 9002
TEST_TIME 83 8991
TEST_TIME 84 8953
TEST_TIME 85 8904
TEST_TIME 86 8849
TEST_TIME 87 8789
TEST_TIME 88 8733
TEST_TIME 89 8711
TEST_TIME 90 8755
TEST_TIME 91 8788
TEST_TIME 92 8839
TEST_TIME 93 8866
TEST_TIME 94 8897
TEST_TIME 95 8918
TEST_TIME 96 8982
TEST_TIME 97 8943
TEST_TIME 98 8943
TEST_TIME 99 8963
TEST_TIME 100 8955
TEST_TIME 101 8972
TEST_TIME 102 8946
TEST_TIME 103 8888
TEST_TIME 104 8882
TEST_TIME 105 8830
TEST_TIME 106 8783
TEST_TIME 107 8791
TEST_TIME 108 8760
TEST_TIME 109 8736
TEST_TIME 110 8768
TEST_TIME 111 8789
TEST_TIME 112 8756
TEST_TIME 113 8783
TEST_TIME 114 8763
TEST_TIME 115 8807
TEST_TIME 116 8797
TEST_TIME 117 8799
TEST_TIME 118 8793
TEST_TIME 119 8811
TEST_TIME 120 8802

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-02-2021 10:30:14 to 01-02-2021 10:30:17.

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 1468465 1468465 0 0.00 0 19017 7 0 0
doc_list_biud 1468462 1468462 0 0.00 0 60177 31 0 0
sp_add_invoice_to_stock 4852 4783 69 1.42 41 235151 18272 0 69
sp_cancel_adding_invoice 5344 5344 0 0.00 5 60052 952 0 0
sp_cancel_client_order 1672 1672 0 0.00 4 10864 327 0 0
sp_cancel_customer_reserve 526502 526502 0 0.00 1 55231 67 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 6205 90 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 6648 72 0 0
sp_cancel_supplier_invoice 5941 5936 5 0.08 1 60264 1003 0 5
sp_cancel_supplier_order 406 406 0 0.00 9 1090170 108094 0 0
sp_cancel_write_off 9291 9291 0 0.00 1 9037 114 0 0
sp_client_order 19959 19959 0 0.00 4 38253 430 0 0
sp_customer_reserve 446559 446559 0 0.00 0 51717 177 0 0
sp_fill_shopping_cart 30440 30440 0 0.00 1 15771 46 0 0
sp_fill_shopping_cart_clo_res 446551 446551 0 0.00 0 16080 18 0 0
sp_get_clo_for_invoice 4772 4772 0 0.00 0 31421 430 0 0
sp_kill_cost_storno 15700 15700 0 0.00 0 10216 59 0 0
sp_kill_qstorno_ret_qs2qd 1068637 1068637 0 0.00 0 60174 36 0 0
sp_kill_qty_storno 563428 563428 0 0.00 0 60177 79 0 0
sp_lock_selected_doc 19918 19918 0 0.00 0 7083 4 0 0
sp_make_cost_storno 26646 26646 0 0.00 0 18548 61 0 0
sp_multiply_rows_for_pdistr 26644 26644 0 0.00 0 10086 35 0 0
sp_multiply_rows_for_qdistr 19959 19959 0 0.00 0 17467 226 0 0
sp_pay_from_customer 6790 6790 0 0.00 4 11683 123 0 0
sp_pay_to_supplier 5577 5577 0 0.00 5 19023 145 0 0
sp_payment_common 12367 12367 0 0.00 1 18965 24 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 10855 299 0 0
sp_qd_handle_on_invoice_upd_sts 10115 10115 0 0.00 0 50530 402 0 0
sp_qd_handle_on_reserve_upd_sts 9506 9506 0 0.00 0 14578 58 0 0
sp_reserve_write_off 9508 9508 0 0.00 7 18779 185 0 0
sp_supplier_invoice 5279 5279 0 0.00 7 114171 3582 0 0
sp_supplier_order 5221 5221 0 0.00 5 43633 1463 0 0
srv_aggregate_perf_data 108 108 0 0.00 3033 112605 16260 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2129 114608 46870 0 0
srv_find_qd_qs_mism 1009239 1009239 0 0.00 0 8780 6 0 0
srv_make_invnt_saldo 457 457 0 0.00 65 85711 3379 0 0
srv_make_money_saldo 709 709 0 0.00 1 11487 511 0 0
srv_recalc_idx_stat 3 3 0 0.00 332171 368816 349957 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4784 4784 0 0.00 0 8329 138 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 2741 89 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 4005 11 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 4768 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 2170 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 356 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 442 7 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 17181 1371 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 988 7 0 0
t$perf-norm:sp_client_order 19959 19959 0 0.00 0 5061 16 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1113 5 0 0
t$perf-norm:sp_pay_from_customer 6790 6790 0 0.00 0 6057 7 0 0
t$perf-norm:sp_pay_to_supplier 5577 5577 0 0.00 0 3956 6 0 0
t$perf-norm:sp_reserve_write_off 9508 9508 0 0.00 0 4700 8 0 0
t$perf-norm:sp_supplier_invoice 5279 5279 0 0.00 0 4842 12 0 0
t$perf-norm:sp_supplier_order 5221 5221 0 0.00 0 6415 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 563 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 280 3 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 214 3 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 80 28 0 0
v_add_invoice_to_stock 9674 9536 138 1.42 0 2566 15 0 138
v_all_customers 36264 36264 0 0.00 0 18 1 0 0
v_all_suppliers 29200 29200 0 0.00 0 19 1 0 0
v_all_wares 39910 39910 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1168 7 0 0
v_cancel_client_order 3344 3344 0 0.00 0 3231 5 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 3920 11 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 2558 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 1712 7 0 0
v_cancel_supplier_invoice 830 820 10 1.20 0 1981 19 0 10
v_cancel_supplier_order 812 812 0 0.00 0 9331 15 0 0
v_cancel_write_off 2034 2034 0 0.00 0 2010 14 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 1005 2 0 0
v_max_id_clo_res 951 951 0 0.00 0 2391 12 0 0
v_max_id_ord_sup 5269 5269 0 0.00 0 5411 5 0 0
v_max_non_paid_invoice 1462 1462 0 0.00 0 1670 4 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 5384 8 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 10172 10 0 0
v_min_id_clo_res 951 951 0 0.00 0 2705 7 0 0
v_min_id_ord_sup 5269 5269 0 0.00 0 6321 10 0 0
v_min_non_paid_invoice 5577 5577 0 0.00 0 18962 16 0 0
v_min_non_paid_realizn 6790 6790 0 0.00 0 6882 14 0 0
v_our_firm 9898 9898 0 0.00 0 18 1 0 0
v_reserve_write_off 19012 19012 0 0.00 0 3118 5 0 0
x_lock_depdocs_on_canc_invoice 10870 10870 0 0.00 0 14027 104 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 10300 312 0 0
x_make_qstorno_1000_1200 5221 5221 0 0.00 2 43627 1376 0 0
x_make_qstorno_1200_2000 5279 5279 0 0.00 3 114155 3414 0 0
x_make_qstorno_2100_3300 446559 446559 0 0.00 0 51716 157 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-02-2021 10:30:18 to 01-02-2021 10:30:23.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:57 4 8192000000 8798027104 8809140224 4138896 0.050 2 1 2 0 4238288 63616 35776
07:57:58 23991 8192000000 9720566320 10014593024 788484832 8.779 100 85 170 0 817184512 7505600 21194080
07:59:27 11798 8192000000 9750918848 10053816320 826686000 9.166 100 87 174 0 859156336 11581584 20888752
08:00:43 20150 8192000000 9801171648 10113687552 863276784 9.533 100 85 170 0 904432720 20861312 20294624
08:02:13 15453 8192000000 9851171872 10180567040 902482752 9.923 100 75 150 0 956655824 36842464 17330608
08:03:36 12034 8192000000 9911612672 10254921728 960708208 10.496 100 77 153 0 1021322688 44824208 15790272
08:04:51 5421 8192000000 9949824416 10303397888 996968624 10.849 100 77 155 1 1064872576 51817744 16086208
08:05:59 3063 8192000000 9992723344 10352943104 1018533040 11.058 100 87 173 1 1106315424 70425024 17357360
08:07:03 4970 8192000000 10009927360 10377957376 1037794704 11.243 100 79 157 1 1130298368 76717920 15785744
08:08:10 2122 8192000000 10032868832 10404270080 1060741152 11.464 100 85 169 1 1141581648 63534672 17305824
08:09:14 32516 8192000000 10009956864 10389794816 1068175904 11.535 100 59 117 2 1114484768 34214512 12094352
08:10:47 7134 8192000000 10009863392 10398404608 1076051600 11.610 100 36 70 2 1117159872 34112112 6996160
08:11:54 7422 8192000000 10006840576 10406629376 1082464576 11.671 100 42 84 0 1117717392 26052608 9200208
08:13:02 8207 8192000000 10009664272 10407526400 1088771648 11.731 100 21 42 0 1134186240 41145632 4268960
08:14:11 57300 8192000000 10025009888 10411307008 1098850016 11.827 100 50 98 2 1127343936 17103008 11390912
08:16:08 7899 8192000000 10006236336 10418769920 1093180128 11.773 100 36 72 0 1133008160 32092288 7735744
08:17:19 9465 8192000000 9982931808 10389655552 1045258448 11.315 96 33 66 1 1074260304 21683408 7318448
08:18:31 7625 8192000000 9974431072 10377625600 1041048016 11.275 98 27 52 3 1076729232 29776512 5904704
08:19:40 62251 8192000000 9924177552 10314608640 971319856 10.600 93 29 58 1 996110352 18331520 6458976
08:21:46 9093 8192000000 9961358288 10339987456 1004780112 10.925 98 40 80 1 1032000128 18749216 8470800
08:22:55 4966 8192000000 9951171344 10323898368 1028258960 11.152 99 62 125 2 1049904992 7404464 14241568
08:24:02 6923 8192000000 9965123040 10358190080 1053717232 11.396 100 38 77 2 1076174864 14271552 8186080
08:25:10 26613 8192000000 9968833776 10376257536 1059507408 11.452 100 39 79 0 1088283072 20314288 8461376
08:26:39 7598 8192000000 9994615136 10388672512 1086324416 11.708 100 37 74 2 1107008128 12641920 8041792
08:27:53 5053 8192000000 10037591568 10431004672 1102873184 11.865 100 67 135 0 1149296240 31031040 15392016
08:29:04 6815 8192000000 10082106816 10485686272 1117329568 12.002 100 66 133 2 1189489808 58061792 14098448
08:30:15 56416 8192000000 10098492912 10510155776 1135112624 12.170 100 32 64 1 1226228480 83829760 7286096
08:32:24 18731 8192000000 10164196544 10579566592 1149388976 12.304 100 41 81 3 1270635424 112437920 8808528
08:33:47 5123 8192000000 10189407824 10596253696 1163312656 12.434 100 65 129 0 1301505776 123904752 14288368
08:34:55 4185 8192000000 10228537440 10635800576 1165945232 12.459 100 67 132 1 1334185904 153503840 14736832
08:36:05 9500 8192000000 10279865344 10694299648 1176794944 12.560 100 59 116 0 1387112432 196733904 13583584
08:37:18 2713 8192000000 10309600592 10725232640 1187920560 12.664 100 73 146 0 1412455904 207989328 16546016
08:38:25 7507 8192000000 10321062544 10735468544 1175714784 12.550 99 58 114 0 1437250192 247924000 13611408
08:39:42 2383 8192000000 10326546640 10747543552 1181267312 12.602 100 90 178 1 1433213984 231410336 20536336
08:40:51 2129 8192000000 10363448048 10762067968 1184327072 12.631 100 88 176 0 1460963328 256278112 20358144
08:41:58 4284 8192000000 10364891840 10779688960 1175648192 12.550 100 79 156 0 1470983712 277564704 17770816
08:43:06 8687 8192000000 10408200784 10831433728 1163727552 12.438 99 49 97 2 1515966736 340293024 11946160
08:44:19 4461 8192000000 10354053152 10774290432 1134384752 12.163 100 73 143 1 1446113856 295374160 16354944
08:45:28 16481 8192000000 10278029728 10694168576 1126900752 12.092 98 44 88 1 1377788880 239939872 10948256
08:46:46 9731 8192000000 10324983632 10753122304 1141544288 12.230 100 33 65 1 1430624816 280758896 8321632
08:47:58 8339 8192000000 10391644448 10808332288 1148262352 12.293 100 46 90 1 1481576800 321684256 11630192
08:49:16 15449 8192000000 10350568160 10778009600 1125681952 12.081 99 31 62 0 1455570080 321542768 8345360
08:50:34 24905 8192000000 10211953136 10665013248 1118452768 12.012 99 20 40 0 1329117296 205603808 5060720
08:52:14 18095 8192000000 10168587232 10623016960 1109454544 11.927 100 20 40 1 1274969504 160959904 4555056
08:53:46 35576 8192000000 10081416848 10519367680 1110533824 11.937 99 42 84 2 1155415184 34644896 10236464
08:55:26 70138 8192000000 10082260384 10539438080 1095999184 11.800 98 18 36 0 1178509280 78412000 4098096
08:57:49 17672 8192000000 10110140288 10538741760 1122875328 12.054 100 50 100 0 1197889152 63868048 11145776
08:59:11 61064 8192000000 10065331888 10527985664 1115556112 11.985 98 22 44 2 1179227312 59089952 4581248
09:01:14 70694 8192000000 10058512640 10496659456 1129712816 12.119 99 14 28 0 1153027616 20666816 2647984
09:03:25 53160 8192000000 10100124000 10539491328 1112205088 11.953 97 20 40 0 1171697904 54820928 4671888
09:05:28 107142 8192000000 10133707216 10609860608 1128911200 12.111 99 30 57 3 1218112224 83088240 6112784
09:08:28 36375 8192000000 10071878096 10502107136 1123935024 12.064 99 20 40 0 1171601824 43604464 4062336
09:10:41 60272 8192000000 10044788576 10489860096 1141598096 12.231 100 15 30 1 1177428832 32651392 3179344
09:12:45 61737 8192000000 10081970416 10528862208 1138311232 12.200 99 18 36 2 1183775152 41556976 3906944
09:15:03 64338 8192000000 10102733344 10619228160 1152778896 12.336 100 6 12 0 1208211216 54438688 993632
09:17:08 31106 8192000000 10088602016 10552037376 1140808944 12.223 99 14 28 0 1193751808 49956656 2986208
09:18:45 38855 8192000000 10160614144 10598813696 1130852992 12.129 97 30 60 1 1223206944 85701120 6652832
09:20:26 31146 8192000000 10199111104 10641899520 1164205072 12.443 100 25 49 2 1280939184 111176032 5558080
09:21:58 58873 8192000000 10216440320 10661838848 1162349168 12.425 100 30 60 1 1297491088 128538848 6603072
09:24:17 54478 8192000000 10164885632 10634113024 1137471488 12.192 98 19 38 0 1243736368 102376304 3888576
09:26:22 61780 8192000000 10129748896 10582831104 1157691072 12.382 100 39 75 0 1194567248 29716544 7159632
09:28:25 85338 8192000000 10101876336 10614829056 1147978320 12.291 99 12 24 1 1193356144 42938288 2439536
09:30:58 46685 8192000000 10123673760 10582994944 1139213024 12.208 98 9 18 0 1188354160 47459776 1681360
09:32:45 99065 8192000000 10135492256 10627915776 1145013264 12.263 99 12 24 0 1187068384 39827328 2227792
09:35:40 114588 8192000000 10071788464 10514542592 1015736016 11.031 88 13 26 1 1075655024 57207904 2711104
09:38:42 47619 8192000000 10048785568 10460749824 1059256912 11.449 95 19 38 1 1081585760 18767792 3561056
09:40:31 66501 8192000000 10074990800 10583724032 1084796752 11.693 97 18 36 0 1173300256 84909376 3594128
09:42:57 45265 8192000000 10101053376 10519834624 1085300400 11.698 96 34 69 2 1135701536 43556880 6844256
09:44:48 94927 8192000000 10089967056 10584145920 1045294288 11.316 93 7 14 0 1132950416 86192000 1464128
09:47:23 102962 8192000000 10016821232 10443534336 1050804608 11.368 95 20 40 0 1074482000 19575392 4102000
09:50:49 60420 8192000000 10034260880 10484555776 1097704016 11.816 99 9 17 0 1129516288 30543840 1268432
09:52:56 54699 8192000000 10081124848 10521612288 1118796688 12.016 100 15 30 1 1186138448 63961056 3380704
09:54:52 90004 8192000000 10113474656 10554683392 1089470432 11.738 97 19 35 0 1171271136 78063632 3737072
09:57:23 93551 8192000000 10111875136 10612883456 1124768560 12.072 99 17 34 1 1214783456 86355856 3659040
10:00:02 67346 8192000000 10079106496 10525196288 1113323600 11.964 98 18 36 1 1133668128 16451312 3893216
10:02:09 63327 8192000000 10065530272 10519195648 1125549824 12.079 99 14 28 0 1195075344 66474208 3051312
10:04:14 22306 8192000000 10087288000 10514665472 1124573056 12.070 99 35 70 0 1163038880 31745552 6720272
10:05:39 37168 8192000000 10085771888 10534637568 1125175888 12.076 99 21 42 0 1173065840 43471648 4418304
10:07:17 39815 8192000000 10103774448 10566537216 1137535824 12.192 100 31 62 2 1205857056 62206752 6114480
10:09:04 103822 8192000000 10099041824 10590052352 1144261904 12.256 100 23 46 0 1209615488 60538752 4814832
10:12:06 84999 8192000000 10082787088 10601017344 1144921632 12.262 100 5 10 1 1203011712 56920080 1170000
10:14:36 58004 8192000000 10094367728 10538274816 1154545856 12.352 100 19 37 0 1202693072 44186896 3960320
10:16:40 101523 8192000000 10074049024 10527330304 1104402016 11.879 97 19 38 1 1150638624 42167632 4068976
10:19:35 57682 8192000000 10094607664 10528821248 1104076976 11.876 97 26 52 0 1149203776 40155792 4971008
10:21:43 49854 8192000000 10062547776 10509774848 1077779648 11.626 95 27 54 1 1113923760 30796256 5347856
10:23:41 28643 8192000000 10042764528 10464153600 1065492800 11.509 96 24 48 1 1113826496 43661472 4672224
10:25:14 91069 8192000000 10045033712 10524782592 1094486448 11.785 98 19 38 2 1143284544 44916256 3881840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-02-2021 10:30:24 to 01-02-2021 10:30:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-02-2021 10:30:40 to 01-02-2021 10:30:42.

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          228485
MON$OLDEST_ACTIVE               229287
MON$OLDEST_SNAPSHOT             229287
MON$NEXT_TRANSACTION            229287
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                       2072992
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2353 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.2353 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 ---
:115: 
:116: mscs-test-161.budzdorov.ru    Mon Feb  1 10:27:46 2021
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
--- end of diff output ---

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

01.02.21 10:30:43. 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-02-01 10:30:46 No severe PSQL-related problems occured
01.02.21 10:30:47. End of report.