Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.03.2021 07:41
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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16832752 kB
MemAvailable 31576900 kB
Buffers 0 kB
Cached 14855468 kB
SwapCached 0 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-03-19 07:38:00
DTS_END                         2021-03-19 07:38:00.4070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59956
TRN_ID                          231637
ATT_ID                          647
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8813.31 23909 1057597
customer order: creation 164.28 407 19714
customer order: refuse 13.99 383 1679
order to supplier: creation 43.28 1523 5193
order to supplier: removal 3.27 130765 392
invoice (draft): creation 43.37 3602 5204
invoice (draft): removal 48.63 1113 5835
invoice accept: apply 41.18 18661 4942
invoice accept: cancel 43.81 978 5257
customer reserve: creation 3853.74 179 462449
customer reserve: removal 4272.53 78 512704
realization accept: apply 80.41 195 9649
realization accept: cancel 81.39 129 9767
payment from customer: creation 57.78 122 6933
payment from customer: removal 5.38 88 645
payment to supplier: creation 46.87 145 5624
payment to supplier: removal 3.09 79 371
service: total inventory turnovers 3.65 4033 438
service: total monetary turnovers 5.98 680 717
service: aggregate perf. data 0.67 27156 80
service: refresh index statistics 0.03 287866 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-03-2021 07:41:37 to 19-03-2021 07:41:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3733
TEST_TIME 2 6026
TEST_TIME 3 6186
TEST_TIME 4 5318
TEST_TIME 5 5291
TEST_TIME 6 5649
TEST_TIME 7 5799
TEST_TIME 8 5757
TEST_TIME 9 5800
TEST_TIME 10 6003
TEST_TIME 11 5844
TEST_TIME 12 5911
TEST_TIME 13 5892
TEST_TIME 14 6076
TEST_TIME 15 6308
TEST_TIME 16 6485
TEST_TIME 17 6899
TEST_TIME 18 6961
TEST_TIME 19 6979
TEST_TIME 20 7234
TEST_TIME 21 7883
TEST_TIME 22 7956
TEST_TIME 23 8380
TEST_TIME 24 8631
TEST_TIME 25 8803
TEST_TIME 26 8996
TEST_TIME 27 8802
TEST_TIME 28 8950
TEST_TIME 29 8909
TEST_TIME 30 9196
TEST_TIME 31 9828
TEST_TIME 32 10682
TEST_TIME 33 10896
TEST_TIME 34 10723
TEST_TIME 35 10438
TEST_TIME 36 10411
TEST_TIME 37 10251
TEST_TIME 38 10182
TEST_TIME 39 10046
TEST_TIME 40 9921
TEST_TIME 41 9811
TEST_TIME 42 9702
TEST_TIME 43 9577
TEST_TIME 44 9617
TEST_TIME 45 9480
TEST_TIME 46 9546
TEST_TIME 47 9632
TEST_TIME 48 9706
TEST_TIME 49 9653
TEST_TIME 50 9642
TEST_TIME 51 9632
TEST_TIME 52 9617
TEST_TIME 53 9575
TEST_TIME 54 9421
TEST_TIME 55 9271
TEST_TIME 56 9231
TEST_TIME 57 9173
TEST_TIME 58 9173
TEST_TIME 59 9135
TEST_TIME 60 9090
TEST_TIME 61 9034
TEST_TIME 62 8959
TEST_TIME 63 8937
TEST_TIME 64 8920
TEST_TIME 65 8933
TEST_TIME 66 8924
TEST_TIME 67 8894
TEST_TIME 68 8917
TEST_TIME 69 9019
TEST_TIME 70 9109
TEST_TIME 71 9092
TEST_TIME 72 9086
TEST_TIME 73 9109
TEST_TIME 74 9130
TEST_TIME 75 9136
TEST_TIME 76 9162
TEST_TIME 77 9152
TEST_TIME 78 9173
TEST_TIME 79 9128
TEST_TIME 80 9092
TEST_TIME 81 9043
TEST_TIME 82 9000
TEST_TIME 83 8947
TEST_TIME 84 8888
TEST_TIME 85 8855
TEST_TIME 86 8799
TEST_TIME 87 8741
TEST_TIME 88 8738
TEST_TIME 89 8750
TEST_TIME 90 8734
TEST_TIME 91 8864
TEST_TIME 92 8858
TEST_TIME 93 8940
TEST_TIME 94 8958
TEST_TIME 95 8963
TEST_TIME 96 8983
TEST_TIME 97 8964
TEST_TIME 98 8965
TEST_TIME 99 9006
TEST_TIME 100 9012
TEST_TIME 101 9020
TEST_TIME 102 8956
TEST_TIME 103 8935
TEST_TIME 104 8882
TEST_TIME 105 8849
TEST_TIME 106 8819
TEST_TIME 107 8803
TEST_TIME 108 8787
TEST_TIME 109 8761
TEST_TIME 110 8751
TEST_TIME 111 8756
TEST_TIME 112 8775
TEST_TIME 113 8751
TEST_TIME 114 8764
TEST_TIME 115 8756
TEST_TIME 116 8800
TEST_TIME 117 8807
TEST_TIME 118 8826
TEST_TIME 119 8851
TEST_TIME 120 8815
TEST_TIME 121 8743

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-03-2021 07:41:40 to 19-03-2021 07:41:44.

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 1483432 1483432 0 0.00 0 27158 7 0 0
doc_list_biud 1483423 1483423 0 0.00 0 67035 34 0 0
sp_add_invoice_to_stock 5057 4942 115 2.27 43 216643 18661 0 115
sp_cancel_adding_invoice 5257 5257 0 0.00 4 57644 979 0 0
sp_cancel_client_order 1679 1679 0 0.00 5 15432 383 0 0
sp_cancel_customer_reserve 512704 512704 0 0.00 1 16499 78 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 7398 89 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 2390 80 0 0
sp_cancel_supplier_invoice 5846 5835 11 0.18 1 67046 1113 0 11
sp_cancel_supplier_order 392 392 0 0.00 10 1106887 130765 0 0
sp_cancel_write_off 9767 9767 0 0.00 1 27235 130 0 0
sp_client_order 19714 19714 0 0.00 4 22113 407 0 0
sp_customer_reserve 462449 462449 0 0.00 0 138596 180 0 0
sp_fill_shopping_cart 30100 30100 0 0.00 1 28631 45 0 0
sp_fill_shopping_cart_clo_res 462443 462443 0 0.00 0 27182 18 0 0
sp_get_clo_for_invoice 4936 4936 0 0.00 0 40684 421 0 0
sp_kill_cost_storno 16040 16040 0 0.00 0 27156 70 0 0
sp_kill_qstorno_ret_qs2qd 1041402 1041402 0 0.00 0 67033 43 0 0
sp_kill_qty_storno 550218 550218 0 0.00 0 67035 91 0 0
sp_lock_selected_doc 20005 20005 0 0.00 0 8354 3 0 0
sp_make_cost_storno 27142 27142 0 0.00 0 16285 60 0 0
sp_multiply_rows_for_pdistr 27142 27142 0 0.00 0 9080 34 0 0
sp_multiply_rows_for_qdistr 19714 19714 0 0.00 0 16183 210 0 0
sp_pay_from_customer 6933 6933 0 0.00 4 16291 122 0 0
sp_pay_to_supplier 5624 5624 0 0.00 5 16735 146 0 0
sp_payment_common 12556 12556 0 0.00 1 16725 26 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 0 15421 349 0 0
sp_qd_handle_on_invoice_upd_sts 10192 10192 0 0.00 0 41577 426 0 0
sp_qd_handle_on_reserve_upd_sts 9649 9649 0 0.00 0 11548 60 0 0
sp_reserve_write_off 9649 9649 0 0.00 7 18112 195 0 0
sp_supplier_invoice 5204 5204 0 0.00 6 75309 3603 0 0
sp_supplier_order 5193 5193 0 0.00 6 26564 1523 0 0
srv_aggregate_perf_data 80 80 0 0.00 2772 464039 27157 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1165 180273 47614 0 0
srv_find_qd_qs_mism 1010044 1010044 0 0.00 0 14037 6 0 0
srv_make_invnt_saldo 438 438 0 0.00 48 137365 4034 0 0
srv_make_money_saldo 717 717 0 0.00 1 16390 680 0 0
srv_recalc_idx_stat 4 4 0 0.00 88405 546491 287867 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4942 4942 0 0.00 0 10059 187 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 2253 83 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 7316 17 0 0
t$perf-norm:sp_cancel_customer_reserve 622 622 0 0.00 0 970 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 1544 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 1050 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 1588 21 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 22167 1639 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 2181 7 0 0
t$perf-norm:sp_client_order 19714 19714 0 0.00 0 11560 22 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 2302 10 0 0
t$perf-norm:sp_pay_from_customer 6933 6933 0 0.00 0 9027 9 0 0
t$perf-norm:sp_pay_to_supplier 5624 5624 0 0.00 0 4774 9 0 0
t$perf-norm:sp_reserve_write_off 9649 9649 0 0.00 0 5412 12 0 0
t$perf-norm:sp_supplier_invoice 5204 5204 0 0.00 0 1748 16 0 0
t$perf-norm:sp_supplier_order 5194 5194 0 0.00 0 1928 16 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 248 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 2591 19 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 602 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 29 9 0 0
v_add_invoice_to_stock 10098 9868 230 2.27 0 5064 21 0 230
v_all_customers 35942 35942 0 0.00 0 32 1 0 0
v_all_suppliers 28962 28962 0 0.00 0 19 1 0 0
v_all_wares 39426 39426 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 2051 9 0 0
v_cancel_client_order 3358 3358 0 0.00 0 4321 8 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 507 4 0 0
v_cancel_customer_reserve 1244 1244 0 0.00 0 2271 7 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 2382 10 0 0
v_cancel_supplier_invoice 756 734 22 2.91 0 10792 41 0 22
v_cancel_supplier_order 784 784 0 0.00 0 4950 11 0 0
v_cancel_write_off 1904 1904 0 0.00 0 3975 19 0 0
v_max_id_clo_ord 5192 5192 0 0.00 0 568 1 0 0
v_max_id_clo_res 966 966 0 0.00 0 2742 17 0 0
v_max_id_ord_sup 5195 5195 0 0.00 0 11624 7 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 1664 4 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 8170 5 0 0
v_min_id_clo_ord 5192 5192 0 0.00 0 4232 6 0 0
v_min_id_clo_res 966 966 0 0.00 0 7842 19 0 0
v_min_id_ord_sup 5195 5195 0 0.00 0 4434 6 0 0
v_min_non_paid_invoice 5623 5623 0 0.00 0 9181 18 0 0
v_min_non_paid_realizn 6933 6933 0 0.00 0 10423 16 0 0
v_our_firm 9986 9986 0 0.00 0 16 1 0 0
v_reserve_write_off 19298 19298 0 0.00 0 5333 7 0 0
x_lock_depdocs_on_canc_invoice 10725 10725 0 0.00 0 23827 126 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 20487 420 0 0
x_make_qstorno_1000_1200 5193 5193 0 0.00 2 25924 1438 0 0
x_make_qstorno_1200_2000 5204 5204 0 0.00 2 55872 3432 0 0
x_make_qstorno_2100_3300 462449 462449 0 0.00 0 138595 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-03-2021 07:41:45 to 19-03-2021 07:41:49.

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
05:08:03 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:09:04 21115 8192000000 9707960000 9999130624 783173824 8.726 100 86 173 0 811770928 7173456 21423648
05:10:32 18746 8192000000 9750470864 10053419008 828515264 9.184 100 85 170 0 860416128 11065072 20835792
05:12:01 16055 8192000000 9791607984 10108674048 864282032 9.543 100 83 166 0 903164432 19178448 19703952
05:13:28 18204 8192000000 9874993792 10206580736 924701312 10.142 100 83 165 1 973846016 31434720 17709984
05:14:50 9922 8192000000 9923049408 10267586560 963710240 10.525 100 80 160 1 1041029984 60703344 16616400
05:16:03 5374 8192000000 9982424464 10333478912 995839088 10.838 100 84 167 0 1082763920 70613632 16311200
05:17:10 3543 8192000000 9993759840 10361442304 1027643376 11.146 100 82 164 0 1105434128 60982240 16808512
05:18:15 2176 8192000000 10018774304 10388537344 1056044784 11.419 100 93 187 0 1134172016 58245056 19882176
05:19:20 1279 8192000000 10045373872 10420228096 1069762560 11.550 100 92 184 1 1162661216 73594320 19304336
05:20:27 48156 8192000000 10004416016 10390319104 1078286560 11.631 100 60 121 4 1118546528 25440896 14819072
05:22:15 4174 8192000000 10000420560 10388508672 1077780688 11.626 100 38 74 0 1115148256 29017168 8350400
05:23:20 9148 8192000000 10014310464 10416041984 1084552096 11.691 100 48 96 3 1124381024 29775824 10053104
05:24:30 8935 8192000000 10004977936 10399322112 1088139776 11.725 100 32 65 1 1128530336 33579616 6810944
05:25:41 79507 8192000000 9982074368 10397429760 1072998032 11.581 98 31 61 1 1099838992 20315728 6525232
05:28:01 3843 8192000000 10013574752 10407817216 1088144352 11.725 99 59 115 3 1120654784 20194208 12316224
05:29:09 8986 8192000000 9963685216 10357997568 1026991072 11.139 95 21 42 1 1059864336 27673920 5199344
05:30:20 6726 8192000000 9924966800 10305171456 952154144 10.412 91 41 83 1 979838992 18055680 9629168
05:31:30 32388 8192000000 9933504144 10319532032 972483824 10.611 95 22 44 0 999382448 22117328 4781296
05:33:03 8980 8192000000 9940852032 10327994368 1026184400 11.132 100 46 90 1 1054768816 17436192 11148224
05:34:14 5423 8192000000 9969743088 10351071232 1050775504 11.368 100 41 82 2 1070567536 10768960 9023072
05:35:21 60186 8192000000 9968624672 10367787008 1062667936 11.482 100 23 46 1 1077634096 8572736 6393424
05:37:27 8668 8192000000 9982129008 10388078592 1069262960 11.545 100 48 96 2 1110297152 30351712 10682480
05:38:42 1937 8192000000 10038403296 10419990528 1095632096 11.796 100 78 157 1 1148008048 36197488 16178464
05:39:47 50448 8192000000 10052634656 10464993280 1119442896 12.022 100 39 78 1 1182041008 54933632 7664480
05:41:43 13993 8192000000 10108266160 10511814656 1127033920 12.093 100 56 112 1 1210094048 71375184 11684944
05:43:03 4953 8192000000 10122235680 10530848768 1134487504 12.164 100 49 96 1 1229941680 84498944 10955232
05:44:13 7499 8192000000 10126548720 10537938944 1143801328 12.251 100 58 113 1 1237085648 81147952 12136368
05:45:25 12734 8192000000 10176943024 10583121920 1155864688 12.365 100 61 120 0 1284011280 115031296 13115296
05:46:48 11054 8192000000 10202060320 10616135680 1164194800 12.443 100 55 110 0 1316097632 139619744 12283088
05:48:10 3891 8192000000 10217727760 10633768960 1170037936 12.497 99 43 82 3 1328561232 148810032 9713264
05:49:17 3669 8192000000 10278885088 10689556480 1184544560 12.633 100 70 139 0 1388009184 188260640 15203984
05:50:31 9168 8192000000 10335319328 10747756544 1174677280 12.541 100 69 138 0 1434509088 244240288 15591520
05:51:47 3587 8192000000 10343692848 10768478208 1177281792 12.565 100 79 154 0 1452678144 257955520 17440832
05:52:54 3721 8192000000 10373316112 10776649728 1176876368 12.561 100 77 153 0 1471905504 277183696 17845440
05:54:04 6465 8192000000 10397351808 10822049792 1170925376 12.505 100 75 149 0 1517420064 329078688 17416000
05:55:15 1161 8192000000 10396706080 10793676800 1155566320 12.362 100 93 186 0 1494603280 318101200 20935760
05:56:23 2520 8192000000 10423514864 10824404992 1149407984 12.304 100 90 178 2 1525564000 354398000 21758016
05:57:32 7766 8192000000 10426960224 10854649856 1127075744 12.094 97 69 138 0 1519097680 375361440 16660496
05:58:45 2985 8192000000 10424154832 10848481280 1143905360 12.252 99 63 126 0 1511684144 352423664 15355120
05:59:53 11817 8192000000 10397468784 10840780800 1148560816 12.296 100 51 102 1 1507947232 346706816 12679600
06:01:07 16668 8192000000 10428042144 10856108032 1153613360 12.343 99 39 78 1 1514566496 350706992 10246144
06:02:35 2178 8192000000 10379982432 10804240384 1174298000 12.537 100 52 101 1 1484556576 297801264 12457312
06:03:41 11102 8192000000 10368857328 10838437888 1148564480 12.296 99 29 58 1 1456287008 299830528 7892000
06:05:09 9584 8192000000 10454387680 10885423104 1157575648 12.381 100 41 82 1 1540729712 372819008 10335056
06:06:37 19567 8192000000 10458439312 10920071168 1141252672 12.227 99 28 56 0 1539786128 390839280 7694176
06:07:58 30705 8192000000 10368944384 10878124032 1135103840 12.169 100 24 48 2 1467061680 326088048 5869792
06:09:32 51087 8192000000 10086423248 10573000704 1133412848 12.154 100 21 42 0 1248517680 110563728 4541104
06:11:34 128820 8192000000 10098912624 10569801728 1122875872 12.054 98 22 44 0 1163793024 36382064 4535088
06:14:46 64245 8192000000 10063093856 10529177600 1119677888 12.024 98 11 23 0 1161995136 40166992 2150256
06:16:50 96588 8192000000 10114972848 10572378112 1135254768 12.171 99 15 30 0 1189235744 50491248 3489728
06:19:27 88059 8192000000 10149899440 10603913216 1130976928 12.131 98 32 64 0 1206683840 68543984 7162928
06:22:01 92879 8192000000 10129163840 10586533888 1122753792 12.053 98 20 40 0 1181879568 54785296 4340480
06:24:54 98417 8192000000 10119839920 10624892928 1150249728 12.312 100 5 10 0 1227917904 76430160 1238016
06:27:51 92005 8192000000 10117020320 10580348928 1134445600 12.163 98 18 36 0 1186199904 47958544 3795760
06:30:24 167797 8192000000 10061458544 10508881920 1125373824 12.078 98 17 34 1 1154969376 25951408 3644144
06:34:14 41024 8192000000 10082340752 10526490624 1149609520 12.306 100 33 66 1 1186664128 29995280 7059328
06:35:58 90740 8192000000 10062014512 10503028736 1108137184 11.915 96 19 38 0 1135374240 23594096 3642960
06:38:29 180273 8192000000 10096074880 10551746560 1097322064 11.812 95 22 44 0 1157628960 55502224 4804672
06:43:13 52179 8192000000 10076547264 10522529792 1093212992 11.773 95 16 32 3 1130047024 33521408 3312624
06:45:24 73451 8192000000 10078840160 10577813504 1055942432 11.418 94 19 38 1 1138922064 78709184 4270448
06:47:47 45872 8192000000 10092667296 10521436160 1103350528 11.869 98 19 38 4 1165150352 57601248 4198576
06:49:37 52135 8192000000 10112943824 10547228672 1122365664 12.049 99 24 48 0 1188846768 61603792 4877312
06:51:29 58943 8192000000 10057544928 10526576640 1082856496 11.675 97 34 68 0 1127442432 37224592 7361344
06:53:29 54290 8192000000 10045008416 10487468032 1029809744 11.167 93 18 36 0 1058571904 25003600 3758560
06:55:24 139805 8192000000 10032435184 10527100928 1072328384 11.574 98 18 36 2 1143665296 67502208 3834704
06:58:59 62230 8192000000 10025184464 10490683392 1096132048 11.801 99 9 19 0 1131367872 33118496 2117328
07:01:02 38323 8192000000 10027296752 10474848256 1113648480 11.967 100 12 24 0 1148793552 32748192 2396880
07:02:46 87839 8192000000 10102925520 10570985472 1106070304 11.895 99 10 20 0 1189749632 81679056 2000272
07:05:14 28368 8192000000 10123662384 10559856640 1072611952 11.577 96 29 58 0 1197610256 117761664 7236640
07:06:43 81665 8192000000 10150287264 10616184832 1126441216 12.088 100 24 48 1 1252887248 120304912 6141120
07:09:24 91999 8192000000 10057932640 10540883968 1077527632 11.624 95 11 22 0 1146432544 66910224 1994688
07:11:56 59243 8192000000 10106022384 10560217088 1129730576 12.119 100 20 40 2 1184657248 50849984 4076688
07:14:03 13992 8192000000 10095962160 10523381760 1136877936 12.186 100 58 116 1 1181572096 33104048 11590112
07:15:41 14455 8192000000 10123595264 10560323584 1137733008 12.194 100 58 115 1 1206694784 57179472 11782304
07:16:56 87501 8192000000 10116955840 10605731840 1104753616 11.883 97 18 36 0 1157030240 48951312 3325312
07:21:50 49327 8192000000 10059096704 10530504704 1140563952 12.221 100 13 26 0 1176720400 33422480 2733968
07:23:40 50521 8192000000 10064895296 10526203904 1117218592 12.001 98 2 4 0 1148240160 30793328 228240
07:26:00 53957 8192000000 10105330832 10553245696 1138394208 12.200 99 33 66 0 1176124576 30962816 6767552
07:28:03 52447 8192000000 10102288048 10568663040 1134966960 12.168 99 30 59 0 1219276560 78050736 6258864
07:30:05 49279 8192000000 10122858288 10570788864 1135601488 12.174 99 20 40 0 1203408880 63804032 4003360
07:31:58 38787 8192000000 10127038640 10581872640 1124034384 12.065 98 20 40 1 1218613264 90227840 4351040
07:33:42 39550 8192000000 10108885200 10544336896 1133502128 12.154 99 37 74 2 1188477568 47488432 7487008
07:35:22 63692 8192000000 10086673216 10606399488 1089940832 11.742 95 17 34 0 1170625568 77234192 3450544
07:37:46 70990 8192000000 9514859712 9905704960 630341680 7.144 55 4 7 0 656910224 25719200 849344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-03-2021 07:41:51 to 19-03-2021 07:42:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-03-2021 07:42:06 to 19-03-2021 07:42:08.

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          230339
MON$OLDEST_ACTIVE               231846
MON$OLDEST_SNAPSHOT             231846
MON$NEXT_TRANSACTION            231846
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                       2111424
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Mar 19 07:39:00 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

19.03.21 07:42:09. 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-03-19 07:42:12 No severe PSQL-related problems occured
19.03.21 07:42:13. End of report.