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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-12-05 04:26:55
DTS_END                         2020-12-05 04:26:55.7730
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/43948
TRN_ID                          226943
ATT_ID                          631
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: 8730.08 25604 1047609
customer order: creation 166.59 428 19991
customer order: refuse 14.35 354 1722
order to supplier: creation 43.23 1366 5187
order to supplier: removal 3.29 105644 395
invoice (draft): creation 44.22 3512 5306
invoice (draft): removal 48.30 838 5796
invoice accept: apply 39.63 17388 4756
invoice accept: cancel 43.52 913 5222
customer reserve: creation 3741.15 166 448938
customer reserve: removal 4312.23 65 517467
realization accept: apply 79.23 182 9508
realization accept: cancel 71.71 126 8605
payment from customer: creation 55.63 119 6676
payment from customer: removal 5.27 85 632
payment to supplier: creation 47.48 118 5698
payment to supplier: removal 3.80 98 456
service: total inventory turnovers 3.57 3251 428
service: total monetary turnovers 5.90 450 708
service: aggregate perf. data 0.96 17519 115
service: refresh index statistics 0.03 359466 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-12-2020 04:27:48 to 05-12-2020 04:27:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4713
TEST_TIME 2 5347
TEST_TIME 3 5085
TEST_TIME 4 4839
TEST_TIME 5 4509
TEST_TIME 6 4693
TEST_TIME 7 5275
TEST_TIME 8 5255
TEST_TIME 9 6228
TEST_TIME 10 6632
TEST_TIME 11 6805
TEST_TIME 12 7104
TEST_TIME 13 7118
TEST_TIME 14 7869
TEST_TIME 15 8334
TEST_TIME 16 8430
TEST_TIME 17 8434
TEST_TIME 18 9346
TEST_TIME 19 9225
TEST_TIME 20 9368
TEST_TIME 21 9288
TEST_TIME 22 9485
TEST_TIME 23 9793
TEST_TIME 24 9982
TEST_TIME 25 10648
TEST_TIME 26 10271
TEST_TIME 27 10921
TEST_TIME 28 10677
TEST_TIME 29 10370
TEST_TIME 30 10074
TEST_TIME 31 9851
TEST_TIME 32 9644
TEST_TIME 33 9583
TEST_TIME 34 9461
TEST_TIME 35 9477
TEST_TIME 36 9572
TEST_TIME 37 9488
TEST_TIME 38 9473
TEST_TIME 39 9312
TEST_TIME 40 9563
TEST_TIME 41 9461
TEST_TIME 42 9444
TEST_TIME 43 9394
TEST_TIME 44 9539
TEST_TIME 45 9412
TEST_TIME 46 9471
TEST_TIME 47 9453
TEST_TIME 48 9399
TEST_TIME 49 9638
TEST_TIME 50 9656
TEST_TIME 51 9582
TEST_TIME 52 9512
TEST_TIME 53 9418
TEST_TIME 54 9353
TEST_TIME 55 9313
TEST_TIME 56 9208
TEST_TIME 57 9119
TEST_TIME 58 9041
TEST_TIME 59 9063
TEST_TIME 60 9036
TEST_TIME 61 9068
TEST_TIME 62 9087
TEST_TIME 63 9056
TEST_TIME 64 9047
TEST_TIME 65 9064
TEST_TIME 66 9103
TEST_TIME 67 9029
TEST_TIME 68 9069
TEST_TIME 69 9082
TEST_TIME 70 9075
TEST_TIME 71 9084
TEST_TIME 72 9123
TEST_TIME 73 9132
TEST_TIME 74 9014
TEST_TIME 75 8943
TEST_TIME 76 8891
TEST_TIME 77 8836
TEST_TIME 78 8803
TEST_TIME 79 8751
TEST_TIME 80 8715
TEST_TIME 81 8729
TEST_TIME 82 8758
TEST_TIME 83 8850
TEST_TIME 84 8800
TEST_TIME 85 8827
TEST_TIME 86 8891
TEST_TIME 87 8869
TEST_TIME 88 8846
TEST_TIME 89 8972
TEST_TIME 90 9001
TEST_TIME 91 8914
TEST_TIME 92 8841
TEST_TIME 93 8789
TEST_TIME 94 8757
TEST_TIME 95 8725
TEST_TIME 96 8729
TEST_TIME 97 8740
TEST_TIME 98 8714
TEST_TIME 99 8733
TEST_TIME 100 8738
TEST_TIME 101 8700
TEST_TIME 102 8778
TEST_TIME 103 8855
TEST_TIME 104 8863
TEST_TIME 105 8876
TEST_TIME 106 8845
TEST_TIME 107 8869
TEST_TIME 108 8865
TEST_TIME 109 8793
TEST_TIME 110 8766
TEST_TIME 111 8709
TEST_TIME 112 8706
TEST_TIME 113 8702
TEST_TIME 114 8681
TEST_TIME 115 8665
TEST_TIME 116 8653
TEST_TIME 117 8703
TEST_TIME 118 8696
TEST_TIME 119 8674
TEST_TIME 120 8713
TEST_TIME 121 8664

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-12-2020 04:27:52 to 05-12-2020 04:27:55.

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 1461501 1461501 0 0.00 0 13061 6 0 0
doc_list_biud 1461501 1461501 0 0.00 0 37829 29 0 0
sp_add_invoice_to_stock 4851 4756 95 1.95 46 177091 17389 0 95
sp_cancel_adding_invoice 5222 5222 0 0.00 5 72384 913 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 17932 354 0 0
sp_cancel_customer_reserve 517467 517467 0 0.00 1 37831 66 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 4737 86 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 3 8768 98 0 0
sp_cancel_supplier_invoice 5806 5796 10 0.17 2 18814 839 0 10
sp_cancel_supplier_order 395 395 0 0.00 6 959977 105644 0 0
sp_cancel_write_off 8605 8605 0 0.00 1 15844 126 0 0
sp_client_order 19991 19991 0 0.00 4 27247 428 0 0
sp_customer_reserve 448938 448938 0 0.00 0 37585 166 0 0
sp_fill_shopping_cart 30460 30460 0 0.00 1 17726 51 0 0
sp_fill_shopping_cart_clo_res 448927 448927 0 0.00 0 22668 19 0 0
sp_get_clo_for_invoice 4745 4745 0 0.00 0 33995 484 0 0
sp_kill_cost_storno 14915 14915 0 0.00 0 13054 58 0 0
sp_kill_qstorno_ret_qs2qd 1049730 1049730 0 0.00 0 37827 35 0 0
sp_kill_qty_storno 553460 553460 0 0.00 0 37829 76 0 0
sp_lock_selected_doc 19872 19872 0 0.00 0 8760 5 0 0
sp_make_cost_storno 26627 26627 0 0.00 0 12575 57 0 0
sp_multiply_rows_for_pdistr 26627 26627 0 0.00 0 10515 29 0 0
sp_multiply_rows_for_qdistr 19991 19991 0 0.00 0 22055 252 0 0
sp_pay_from_customer 6676 6676 0 0.00 4 10528 120 0 0
sp_pay_to_supplier 5698 5698 0 0.00 4 14292 119 0 0
sp_payment_common 12374 12374 0 0.00 1 6967 20 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 17924 327 0 0
sp_qd_handle_on_invoice_upd_sts 9967 9967 0 0.00 0 31351 375 0 0
sp_qd_handle_on_reserve_upd_sts 9508 9508 0 0.00 0 21826 63 0 0
sp_reserve_write_off 9508 9508 0 0.00 6 21995 183 0 0
sp_supplier_invoice 5306 5306 0 0.00 7 69826 3513 0 0
sp_supplier_order 5187 5187 0 0.00 5 42536 1366 0 0
srv_aggregate_perf_data 115 115 0 0.00 2733 333555 17519 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1811 119698 49857 0 0
srv_find_qd_qs_mism 1000767 1000767 0 0.00 0 12372 5 0 0
srv_make_invnt_saldo 428 428 0 0.00 40 130412 3251 0 0
srv_make_money_saldo 708 708 0 0.00 1 13261 450 0 0
srv_recalc_idx_stat 3 3 0 0.00 223718 500609 359466 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4756 4756 0 0.00 0 8486 146 0 0
t$perf-norm:sp_cancel_adding_invoice 363 363 0 0.00 0 4707 88 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 1029 11 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1147 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 1374 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 3320 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 3434 18 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 18826 1348 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 1106 5 0 0
t$perf-norm:sp_client_order 19991 19991 0 0.00 0 12519 16 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 743 5 0 0
t$perf-norm:sp_pay_from_customer 6676 6676 0 0.00 0 4033 6 0 0
t$perf-norm:sp_pay_to_supplier 5698 5698 0 0.00 0 3761 7 0 0
t$perf-norm:sp_reserve_write_off 9508 9508 0 0.00 0 5424 9 0 0
t$perf-norm:sp_supplier_invoice 5306 5306 0 0.00 0 2581 12 0 0
t$perf-norm:sp_supplier_order 5187 5187 0 0.00 0 9378 14 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 1012 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 378 7 0 0
t$perf-norm:srv_make_invnt_saldo 428 428 0 0.00 0 3335 15 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 506 5 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9674 9484 190 1.96 0 4938 17 0 190
v_all_customers 35856 35856 0 0.00 0 56 1 0 0
v_all_suppliers 29104 29104 0 0.00 0 21 1 0 0
v_all_wares 39974 39974 0 0.00 0 126 1 0 0
v_cancel_adding_invoice 726 726 0 0.00 0 3638 13 0 0
v_cancel_client_order 3444 3444 0 0.00 0 2715 5 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 1996 9 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 2959 9 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 4579 10 0 0
v_cancel_supplier_invoice 792 772 20 2.52 0 2999 18 0 20
v_cancel_supplier_order 790 790 0 0.00 0 1213 5 0 0
v_cancel_write_off 2036 2036 0 0.00 0 5582 21 0 0
v_max_id_clo_ord 5181 5181 0 0.00 0 8085 3 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 640 5 0 0
v_max_id_ord_sup 5292 5292 0 0.00 0 7883 8 0 0
v_max_non_paid_invoice 1619 1619 0 0.00 0 662 3 0 0
v_max_non_paid_realizn 3769 3769 0 0.00 0 1281 2 0 0
v_min_id_clo_ord 5181 5181 0 0.00 0 8767 11 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 15092 39 0 0
v_min_id_ord_sup 5292 5292 0 0.00 0 9165 12 0 0
v_min_non_paid_invoice 5698 5698 0 0.00 0 4367 11 0 0
v_min_non_paid_realizn 6676 6676 0 0.00 0 5604 14 0 0
v_our_firm 9932 9932 0 0.00 0 12 1 0 0
v_reserve_write_off 19010 19010 0 0.00 0 4771 6 0 0
x_lock_depdocs_on_canc_invoice 10632 10632 0 0.00 0 8131 102 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 13231 330 0 0
x_make_qstorno_1000_1200 5187 5187 0 0.00 2 40053 1282 0 0
x_make_qstorno_1200_2000 5306 5306 0 0.00 4 69819 3307 0 0
x_make_qstorno_2100_3300 448938 448938 0 0.00 0 37584 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2020 04:27:56 to 05-12-2020 04:28:00.

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
01:56:59 7 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
01:58:00 32618 8192000000 9732358880 10030854144 807133888 8.969 100 85 169 1 835484544 7840048 20510608
01:59:37 8066 8192000000 9767103344 10072920064 842993760 9.330 100 93 186 1 877542512 12680080 21868672
02:00:49 19300 8192000000 9808598096 10126696448 870208448 9.602 100 89 178 1 909959008 19157792 20592768
02:02:17 14660 8192000000 9875428464 10209730560 921086080 10.107 100 81 162 2 979476992 40739168 17651744
02:03:38 6465 8192000000 9938727792 10285252608 957405600 10.464 100 74 148 0 1046966544 74060864 15500080
02:04:46 6878 8192000000 9958334864 10312974336 993776160 10.818 100 75 149 1 1076715312 67040752 15898400
02:05:58 2933 8192000000 9983148528 10343428096 1022943328 11.100 100 75 147 0 1098078000 59292112 15842560
02:07:03 2922 8192000000 10033016368 10395623424 1040067696 11.265 100 97 195 3 1146732752 86927264 19737792
02:08:07 2482 8192000000 10030666320 10400198656 1055236784 11.411 100 87 173 0 1150936080 77636336 18062960
02:09:12 3217 8192000000 10017943632 10386358272 1059302016 11.450 100 87 169 3 1119940560 43084848 17553696
02:10:17 24203 8192000000 9981533152 10387107840 1064627520 11.501 100 47 96 2 1111857904 36434944 10795440
02:11:42 4681 8192000000 9983371024 10373046272 1073951840 11.590 100 53 105 3 1107200224 22239424 11008960
02:12:49 5213 8192000000 9998231760 10392784896 1083616096 11.682 100 36 71 3 1107689392 16141360 7931936
02:14:00 46461 8192000000 10003582432 10396487680 1078910864 11.637 99 40 79 1 1100923600 13011552 9001184
02:15:47 5663 8192000000 10021094976 10416140288 1093778400 11.779 100 52 104 1 1128406560 23988464 10639696
02:16:58 7906 8192000000 9999218000 10412740608 1091935968 11.761 100 32 65 1 1107484096 8455200 7092928
02:18:06 6677 8192000000 9978842304 10363129856 1049341472 11.354 97 42 82 2 1077058400 18506480 9210448
02:19:15 49808 8192000000 9937516816 10330726400 946723776 10.359 89 14 28 0 963606256 13845280 3037200
02:21:08 4701 8192000000 9920911120 10296172544 984322000 10.726 97 46 93 1 1018606528 24008384 10276144
02:22:15 4097 8192000000 9938663120 10325385216 1031128784 11.179 100 49 96 4 1049006480 7342480 10535216
02:23:19 9981 8192000000 9970593712 10376306688 1046008592 11.322 100 48 96 5 1080745456 24449056 10287808
02:24:33 33717 8192000000 9958555888 10359001088 1049382912 11.355 100 41 82 0 1076002256 18341424 8277920
02:26:08 6883 8192000000 9978891664 10388226048 1068485280 11.538 100 32 61 3 1089811616 14070672 7255664
02:27:17 12246 8192000000 10029659632 10422595584 1091302080 11.755 100 54 108 2 1132202960 29857936 11042944
02:28:35 7622 8192000000 10054679712 10451021824 1107860656 11.912 100 41 82 1 1170063376 53915488 8287232
02:29:45 103158 8192000000 10104306960 10516889600 1122257856 12.048 100 36 72 0 1200923216 71096576 7568784
02:32:32 21304 8192000000 10151108528 10566524928 1140241792 12.218 100 37 74 3 1258594496 110170912 8181792
02:34:02 16550 8192000000 10178192576 10593255424 1151026384 12.319 100 45 90 1 1296309232 135639744 9643104
02:35:20 7505 8192000000 10212232976 10628804608 1156967408 12.375 100 62 122 4 1323334432 153341584 13025440
02:36:33 6749 8192000000 10222953536 10640719872 1167594016 12.474 100 54 105 2 1327932192 148642336 11695840
02:37:43 5081 8192000000 10224552768 10635939840 1152639952 12.334 99 56 111 1 1334226720 168954832 12631936
02:38:53 3412 8192000000 10297606592 10698461184 1160912384 12.412 100 85 170 0 1400032384 221063120 18056880
02:40:01 1809 8192000000 10342744400 10752716800 1170833696 12.505 100 78 156 1 1446395808 257933536 17628576
02:41:07 2931 8192000000 10321865344 10731536384 1177901504 12.571 100 77 153 3 1419677504 224472448 17303552
02:42:15 1880 8192000000 10319426112 10726641664 1165219184 12.452 100 84 168 1 1416176176 231760192 19196800
02:43:21 2738 8192000000 10317037408 10724253696 1146748560 12.279 100 74 143 1 1403128176 239912544 16467072
02:44:27 6583 8192000000 10259183712 10681483264 1132846320 12.148 100 45 90 2 1369627408 225341008 11440080
02:45:39 3848 8192000000 10242393712 10654826496 1128806144 12.110 100 67 133 0 1343066048 198837792 15422112
02:46:48 22279 8192000000 10264186112 10684866560 1118699872 12.015 100 29 58 0 1356781424 230760208 7321344
02:48:14 3240 8192000000 10295934704 10706948096 1122339888 12.049 100 81 154 1 1382576016 242713312 17522816
02:49:19 12038 8192000000 10274114640 10711695360 1105118560 11.886 99 31 63 0 1349816352 236855776 7842016
02:50:33 15833 8192000000 10249453760 10686844928 1117296352 12.001 100 18 36 0 1344729104 222356368 5076384
02:51:50 71544 8192000000 10165988048 10589069312 1110140720 11.934 100 40 79 2 1255561056 136055120 9365216
02:54:03 65899 8192000000 10040833168 10480820224 1094123120 11.782 98 28 55 0 1124295392 24317360 5854912
02:56:11 67518 8192000000 10062879872 10489683968 1101241104 11.849 98 30 60 1 1144640320 36749392 6649824
02:58:41 26793 8192000000 10121708912 10557063168 1127418704 12.097 99 23 46 1 1207482608 75387872 4676032
03:00:21 66836 8192000000 10189608480 10648121344 1122504624 12.051 98 27 52 1 1263527792 134708192 6314976
03:02:47 74996 8192000000 10140995840 10618552320 1123569520 12.061 99 11 22 0 1280345792 154272992 2503280
03:05:02 78576 8192000000 10070055808 10588803072 1132061280 12.141 100 17 34 1 1231976672 96271056 3644336
03:07:21 79274 8192000000 10088104720 10538401792 1132338592 12.143 100 16 32 0 1183585728 47844976 3402160
03:09:51 62624 8192000000 10097954352 10575765504 1110304720 11.935 98 21 42 2 1165298640 50814496 4179424
03:11:59 79000 8192000000 10128588656 10593943552 1117775200 12.006 99 19 38 1 1159581056 38151024 3654832
03:14:34 76845 8192000000 10065295792 10533154816 1121297600 12.039 99 7 14 0 1178812912 56039008 1476304
03:16:57 82837 8192000000 10089030832 10515591168 1116350880 11.993 99 22 44 1 1165596464 44864064 4381520
03:19:20 85299 8192000000 10094890768 10563792896 1130206768 12.123 100 26 52 1 1216882944 81261808 5414368
03:22:11 50834 8192000000 10086394160 10552397824 1118273744 12.011 98 10 19 1 1169474576 49204256 1996576
03:24:02 68718 8192000000 10135318992 10582581248 1134843776 12.167 99 21 42 1 1220067600 80835120 4388704
03:26:11 59764 8192000000 10092997344 10539831296 1129836448 12.120 99 9 17 1 1183857184 52193616 1827120
03:28:16 96407 8192000000 10096091984 10541264896 1115369952 11.983 97 15 30 1 1171850144 53968112 2512080
03:30:55 46877 8192000000 10115128832 10596073472 1140605360 12.221 100 42 84 2 1207221520 57982896 8633264
03:32:48 39709 8192000000 10077102864 10515202048 1088886352 11.732 96 16 32 1 1126469552 34485520 3097680
03:34:39 58885 8192000000 10060348832 10512420864 1096269808 11.802 97 14 27 1 1120211056 20986656 2954592
03:36:38 34698 8192000000 10064383216 10510639104 1118219184 12.010 100 31 62 0 1172061760 47303632 6538944
03:38:17 47352 8192000000 10072957152 10547761152 1028997104 11.159 92 30 60 1 1071222384 36145888 6079392
03:40:06 51594 8192000000 10044693744 10519666688 1111439968 11.946 100 12 24 0 1148102192 34312608 2349616
03:42:23 58696 8192000000 9992087408 10434637824 1037647824 11.242 94 6 12 1 1055635728 16876768 1111136
03:44:22 84375 8192000000 10060645088 10492399616 1087820064 11.722 98 10 20 0 1125811552 35811664 2179824
03:46:59 47689 8192000000 10040248224 10464301056 1030531248 11.174 94 16 32 0 1093361696 59305504 3524944
03:48:49 71016 8192000000 10033372496 10515283968 1081882080 11.665 99 11 22 1 1171456832 87227088 2347664
03:51:09 33142 8192000000 10077016048 10513252352 1095410368 11.794 100 40 80 1 1162966624 59728160 7828096
03:52:50 99323 8192000000 10044717840 10464755712 1042855616 11.292 94 18 36 0 1097672336 51571392 3245328
03:55:30 68915 8192000000 10067163376 10544754688 1098277808 11.821 99 13 26 2 1180460912 79592768 2590336
03:57:43 83584 8192000000 10033344576 10483019776 1101497072 11.852 99 17 34 0 1131005728 25922944 3585712
04:00:08 78998 8192000000 10092419296 10548346880 1129332464 12.115 100 11 23 2 1168730240 37097664 2300112
04:02:29 62055 8192000000 10072304320 10541023232 1132178960 12.142 100 22 43 1 1197307360 60680320 4448080
04:04:37 71117 8192000000 10141406848 10603683840 1133338112 12.153 99 21 41 1 1202085280 64028800 4718368
04:06:58 119698 8192000000 10141743040 10634485760 1108295984 11.916 96 17 34 0 1210698192 98232848 4169360
04:09:58 26579 8192000000 10092224512 10524635136 1133231616 12.152 99 25 50 0 1172286080 33506016 5548448
04:11:30 37970 8192000000 10077535952 10509484032 1140348160 12.219 100 25 50 0 1169443664 22728528 6366976
04:13:15 108760 8192000000 10098792240 10603966464 1154328800 12.350 100 12 24 0 1254770656 97926384 2515472
04:17:10 70509 8192000000 10111662256 10578292736 1162245872 12.424 100 9 18 0 1226500544 62347824 1906848
04:19:42 48952 8192000000 10072082560 10513006592 1118553328 12.013 97 18 36 0 1152673232 29772256 4347648
04:21:35 47692 8192000000 10132728960 10588180480 1147284128 12.284 100 26 52 2 1217705776 64928960 5492688
04:23:25 64258 8192000000 10094393408 10578989056 1129645840 12.118 98 18 36 1 1189538368 55809200 4083328
04:25:48 78088 8192000000 9544987312 9940922368 624356864 7.081 54 9 18 0 703316512 77007360 1952288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-12-2020 04:28:02 to 05-12-2020 04:28:17.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-12-2020 04:28:18 to 05-12-2020 04:28:20.

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          229110
MON$OLDEST_ACTIVE               229111
MON$OLDEST_SNAPSHOT             229111
MON$NEXT_TRANSACTION            229111
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                       2071264
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             781
MON$NEXT_STATEMENT              53


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

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