Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2020 13: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 16841668 kB
MemAvailable 31390316 kB
Buffers 0 kB
Cached 14712776 kB
SwapCached 3700 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-18 13:26:57
DTS_END                         2020-12-18 13:26:57.5830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55930
TRN_ID                          237153
ATT_ID                          665
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: 8835.17 23956 1060220
customer order: creation 166.09 453 19931
customer order: refuse 14.38 318 1725
order to supplier: creation 44.63 1290 5355
order to supplier: removal 3.56 116489 427
invoice (draft): creation 44.44 3367 5333
invoice (draft): removal 49.82 913 5978
invoice accept: apply 40.13 18275 4815
invoice accept: cancel 45.31 1076 5437
customer reserve: creation 3652.62 182 438314
customer reserve: removal 4490.68 76 538882
realization accept: apply 80.47 151 9656
realization accept: cancel 79.47 123 9537
payment from customer: creation 56.70 89 6804
payment from customer: removal 5.66 66 679
payment to supplier: creation 47.02 121 5642
payment to supplier: removal 3.32 63 398
service: total inventory turnovers 3.76 3031 451
service: total monetary turnovers 6.08 525 730
service: aggregate perf. data 1.01 9411 121
service: refresh index statistics 0.04 323093 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 990
TEST_TIME 2 1208
TEST_TIME 3 3013
TEST_TIME 4 3682
TEST_TIME 5 3969
TEST_TIME 6 4310
TEST_TIME 7 5247
TEST_TIME 8 5794
TEST_TIME 9 5777
TEST_TIME 10 5921
TEST_TIME 11 6112
TEST_TIME 12 6240
TEST_TIME 13 6377
TEST_TIME 14 6702
TEST_TIME 15 7255
TEST_TIME 16 7770
TEST_TIME 17 8024
TEST_TIME 18 8227
TEST_TIME 19 8564
TEST_TIME 20 8846
TEST_TIME 21 8927
TEST_TIME 22 9144
TEST_TIME 23 9592
TEST_TIME 24 9972
TEST_TIME 25 10112
TEST_TIME 26 11322
TEST_TIME 27 11122
TEST_TIME 28 10819
TEST_TIME 29 10619
TEST_TIME 30 10350
TEST_TIME 31 10389
TEST_TIME 32 10255
TEST_TIME 33 10100
TEST_TIME 34 9939
TEST_TIME 35 9766
TEST_TIME 36 9616
TEST_TIME 37 9509
TEST_TIME 38 9343
TEST_TIME 39 9313
TEST_TIME 40 9492
TEST_TIME 41 9435
TEST_TIME 42 9441
TEST_TIME 43 9412
TEST_TIME 44 9316
TEST_TIME 45 9322
TEST_TIME 46 9302
TEST_TIME 47 9563
TEST_TIME 48 9559
TEST_TIME 49 9547
TEST_TIME 50 9596
TEST_TIME 51 9560
TEST_TIME 52 9550
TEST_TIME 53 9421
TEST_TIME 54 9310
TEST_TIME 55 9271
TEST_TIME 56 9179
TEST_TIME 57 9115
TEST_TIME 58 9060
TEST_TIME 59 9032
TEST_TIME 60 9025
TEST_TIME 61 9024
TEST_TIME 62 9065
TEST_TIME 63 9079
TEST_TIME 64 9121
TEST_TIME 65 9057
TEST_TIME 66 9103
TEST_TIME 67 9110
TEST_TIME 68 9123
TEST_TIME 69 9114
TEST_TIME 70 8992
TEST_TIME 71 8936
TEST_TIME 72 8921
TEST_TIME 73 8905
TEST_TIME 74 8883
TEST_TIME 75 8854
TEST_TIME 76 8825
TEST_TIME 77 8796
TEST_TIME 78 8819
TEST_TIME 79 8956
TEST_TIME 80 8944
TEST_TIME 81 8950
TEST_TIME 82 9006
TEST_TIME 83 8997
TEST_TIME 84 8989
TEST_TIME 85 9142
TEST_TIME 86 9126
TEST_TIME 87 9144
TEST_TIME 88 9053
TEST_TIME 89 8988
TEST_TIME 90 8926
TEST_TIME 91 8906
TEST_TIME 92 8859
TEST_TIME 93 8817
TEST_TIME 94 8774
TEST_TIME 95 8769
TEST_TIME 96 8764
TEST_TIME 97 8745
TEST_TIME 98 8751
TEST_TIME 99 8733
TEST_TIME 100 8783
TEST_TIME 101 8817
TEST_TIME 102 8896
TEST_TIME 103 8913
TEST_TIME 104 8889
TEST_TIME 105 8904
TEST_TIME 106 8835
TEST_TIME 107 8801
TEST_TIME 108 8781
TEST_TIME 109 8741
TEST_TIME 110 8747
TEST_TIME 111 8748
TEST_TIME 112 8759
TEST_TIME 113 8733
TEST_TIME 114 8771
TEST_TIME 115 8745
TEST_TIME 116 8739
TEST_TIME 117 8718
TEST_TIME 118 8702
TEST_TIME 119 8738
TEST_TIME 120 8837

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2020 13:27:52 to 18-12-2020 13: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 1469083 1469083 0 0.00 0 22600 7 0 0
doc_list_biud 1469083 1469083 0 0.00 0 34970 34 0 0
sp_add_invoice_to_stock 4918 4815 103 2.09 26 242410 18275 0 103
sp_cancel_adding_invoice 5437 5437 0 0.00 5 104133 1077 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 13525 319 0 0
sp_cancel_customer_reserve 538882 538882 0 0.00 1 34975 76 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 4321 66 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2562 64 0 0
sp_cancel_supplier_invoice 5984 5978 6 0.10 2 34887 914 0 6
sp_cancel_supplier_order 427 427 0 0.00 7 998614 116489 0 0
sp_cancel_write_off 9537 9537 0 0.00 1 13159 124 0 0
sp_client_order 19931 19931 0 0.00 4 51962 454 0 0
sp_customer_reserve 438314 438314 0 0.00 0 50490 183 0 0
sp_fill_shopping_cart 30604 30604 0 0.00 1 27088 47 0 0
sp_fill_shopping_cart_clo_res 438313 438313 0 0.00 0 30501 21 0 0
sp_get_clo_for_invoice 4814 4814 0 0.00 0 51655 419 0 0
sp_kill_cost_storno 16051 16051 0 0.00 0 8685 58 0 0
sp_kill_qstorno_ret_qs2qd 1093706 1093706 0 0.00 0 34874 41 0 0
sp_kill_qty_storno 576453 576453 0 0.00 0 34969 87 0 0
sp_lock_selected_doc 20206 20206 0 0.00 0 10880 4 0 0
sp_make_cost_storno 26913 26913 0 0.00 0 14261 41 0 0
sp_multiply_rows_for_pdistr 26913 26913 0 0.00 0 22582 30 0 0
sp_multiply_rows_for_qdistr 19931 19931 0 0.00 0 12218 262 0 0
sp_pay_from_customer 6804 6804 0 0.00 4 12455 89 0 0
sp_pay_to_supplier 5642 5642 0 0.00 5 22607 122 0 0
sp_payment_common 12446 12446 0 0.00 1 6407 16 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 13494 286 0 0
sp_qd_handle_on_invoice_upd_sts 10248 10248 0 0.00 0 27584 392 0 0
sp_qd_handle_on_reserve_upd_sts 9656 9656 0 0.00 0 20014 48 0 0
sp_reserve_write_off 9656 9656 0 0.00 7 20033 151 0 0
sp_supplier_invoice 5333 5333 0 0.00 6 119617 3368 0 0
sp_supplier_order 5355 5355 0 0.00 5 26539 1290 0 0
srv_aggregate_perf_data 122 121 1 0.81 2690 45274 9411 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1568 141308 44515 0 0
srv_find_qd_qs_mism 1016013 1016013 0 0.00 0 21363 6 0 0
srv_make_invnt_saldo 451 451 0 0.00 35 88735 3031 0 0
srv_make_money_saldo 730 730 0 0.00 1 11632 525 0 0
srv_recalc_idx_stat 5 5 0 0.00 206278 428186 323094 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4815 4815 0 0.00 0 27999 162 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 2704 84 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 1380 9 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4395 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 598 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 2028 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 3789 30 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 17286 1327 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 1696 7 0 0
t$perf-norm:sp_client_order 19931 19931 0 0.00 0 7714 15 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 605 3 0 0
t$perf-norm:sp_pay_from_customer 6804 6804 0 0.00 0 6775 11 0 0
t$perf-norm:sp_pay_to_supplier 5642 5642 0 0.00 0 5117 6 0 0
t$perf-norm:sp_reserve_write_off 9656 9656 0 0.00 0 10330 13 0 0
t$perf-norm:sp_supplier_invoice 5333 5333 0 0.00 0 11948 16 0 0
t$perf-norm:sp_supplier_order 5355 5355 0 0.00 0 8725 13 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 161 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 223 5 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 3190 18 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 358 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 9828 9622 206 2.09 0 9819 17 0 206
v_all_customers 36270 36270 0 0.00 0 85 1 0 0
v_all_suppliers 29388 29388 0 0.00 0 27 1 0 0
v_all_wares 39860 39860 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 387 6 0 0
v_cancel_client_order 3450 3450 0 0.00 0 5696 6 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 1475 5 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 2386 7 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 2551 11 0 0
v_cancel_supplier_invoice 836 824 12 1.43 0 2532 14 0 12
v_cancel_supplier_order 854 854 0 0.00 0 448 3 0 0
v_cancel_write_off 1984 1984 0 0.00 0 7825 16 0 0
v_max_id_clo_ord 5355 5355 0 0.00 0 6150 4 0 0
v_max_id_clo_res 989 989 0 0.00 0 2811 13 0 0
v_max_id_ord_sup 5318 5318 0 0.00 0 13543 9 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 453 2 0 0
v_max_non_paid_realizn 3649 3649 0 0.00 0 253 2 0 0
v_min_id_clo_ord 5355 5355 0 0.00 0 7285 9 0 0
v_min_id_clo_res 989 989 0 0.00 0 6764 25 0 0
v_min_id_ord_sup 5318 5318 0 0.00 0 5086 9 0 0
v_min_non_paid_invoice 5642 5642 0 0.00 0 5562 10 0 0
v_min_non_paid_realizn 6804 6804 0 0.00 0 6404 8 0 0
v_our_firm 9900 9900 0 0.00 0 23 1 0 0
v_reserve_write_off 19312 19312 0 0.00 0 3836 6 0 0
x_lock_depdocs_on_canc_invoice 11003 11003 0 0.00 0 23575 120 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 4433 289 0 0
x_make_qstorno_1000_1200 5355 5355 0 0.00 2 26529 1210 0 0
x_make_qstorno_1200_2000 5333 5333 0 0.00 3 119526 3176 0 0
x_make_qstorno_2100_3300 438314 438314 0 0.00 0 50489 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2020 13:27:56 to 18-12-2020 13: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
10:57:01 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:58:02 26717 8192000000 9730264448 10029834240 807303632 8.970 100 87 175 0 836628784 7875840 21449312
10:59:34 22958 8192000000 9771942720 10081292288 843272128 9.333 100 88 176 1 878074368 14001824 20800416
11:01:00 18279 8192000000 9817941648 10135683072 874017008 9.640 100 90 179 1 925838096 30814608 21006480
11:02:22 16341 8192000000 9868000016 10198573056 915447584 10.051 100 85 170 0 986446064 52042288 18956192
11:03:42 8277 8192000000 9909792992 10255630336 952058480 10.411 100 75 150 3 1019061968 51181408 15822080
11:04:55 4213 8192000000 9971362160 10323730432 997165072 10.851 100 86 172 1 1083978912 69496128 17317712
11:06:01 3678 8192000000 10006498688 10368966656 1031674064 11.185 100 87 172 2 1114780624 66106256 17000304
11:07:06 2213 8192000000 10004983856 10375409664 1054427936 11.403 100 85 167 3 1119853072 48485008 16940128
11:08:10 35774 8192000000 10024633984 10404945920 1070166304 11.554 100 68 135 2 1134958496 51468976 13323216
11:09:46 4295 8192000000 10004409296 10400378880 1075814752 11.608 100 33 66 2 1122882320 40491312 6576256
11:10:52 3803 8192000000 9998945232 10394349568 1082456992 11.671 100 43 88 0 1113004960 20527808 10020160
11:11:59 60568 8192000000 9999911280 10409852928 1086652912 11.711 100 39 79 2 1105546304 10458064 8435328
11:14:05 6232 8192000000 10005820736 10409889792 1093400992 11.775 100 39 76 3 1132090912 31541440 7148480
11:15:15 3715 8192000000 10038206608 10430345216 1101442384 11.851 100 59 121 2 1144232256 28983664 13806208
11:16:20 5008 8192000000 10001961584 10394394624 1090077280 11.743 100 47 95 0 1112901488 11801424 11022784
11:17:27 45199 8192000000 9972811296 10383462400 1008137440 10.957 93 29 57 2 1027528992 13250368 6141184
11:19:13 4206 8192000000 9939687792 10318618624 1018615168 11.059 98 49 96 3 1040216048 10583632 11017248
11:20:20 8349 8192000000 9911618032 10286219264 975678832 10.642 96 31 62 1 999507376 16866784 6961760
11:21:29 68344 8192000000 9933120912 10317533184 996110688 10.841 96 22 44 2 1021808848 20791648 4906512
11:23:39 9177 8192000000 9973425072 10367279104 1044338720 11.306 100 50 100 1 1065995184 9667776 11988688
11:24:50 10897 8192000000 9971015184 10370621440 1055209776 11.411 100 39 78 4 1089440624 25406976 8823872
11:26:02 8823 8192000000 9971129200 10363015168 1058099552 11.438 100 24 48 4 1090740528 27291792 5349184
11:27:12 89552 8192000000 9979572832 10380255232 1071912832 11.570 100 24 47 1 1089837488 13070992 4853664
11:29:47 5031 8192000000 10068355952 10459492352 1090680016 11.749 100 58 116 2 1173928640 71340064 11908560
11:30:54 16260 8192000000 10099955552 10498453504 1103365904 11.870 100 54 108 1 1205043184 90493136 11184144
11:32:15 10923 8192000000 10131569872 10527838208 1121210144 12.038 100 62 122 3 1229178384 95114768 12853472
11:33:29 7723 8192000000 10181988432 10583773184 1125819072 12.082 100 55 110 1 1280973968 143167312 11987584
11:34:42 7574 8192000000 10194508896 10606211072 1135642912 12.175 100 51 98 2 1306661680 160102944 10915824
11:35:55 5238 8192000000 10228815648 10633854976 1139841536 12.214 100 57 114 0 1342865856 189975840 13048480
11:37:04 5485 8192000000 10266057488 10674704384 1153926000 12.346 100 63 126 0 1372793152 204187856 14679296
11:38:17 2114 8192000000 10319195280 10712268800 1157658848 12.381 100 87 174 0 1420119968 243564752 18896368
11:39:22 2233 8192000000 10292357888 10683072512 1103181728 11.868 96 81 160 0 1380483152 259273056 18028368
11:40:29 2180 8192000000 10385184224 10793811968 1156030576 12.366 100 75 150 0 1493213008 319307968 17874464
11:41:35 1561 8192000000 10372268368 10772914176 1120957504 12.036 99 83 166 1 1462267744 322327024 18983216
11:42:39 3103 8192000000 10329602848 10746515456 1120522384 12.032 99 55 110 2 1421630688 287561952 13546352
11:43:49 2088 8192000000 10361989632 10763788288 1142589184 12.240 100 79 158 2 1459223136 298682240 17951712
11:44:55 10972 8192000000 10363809664 10801905664 1144276864 12.256 100 46 92 0 1470281936 314159648 11845424
11:46:10 16063 8192000000 10383852320 10810097664 1164972704 12.450 100 47 94 0 1481269232 304190976 12105552
11:47:30 12987 8192000000 10413208320 10856009728 1160272144 12.406 100 40 80 2 1525044592 354262496 10509952
11:48:45 13061 8192000000 10410386736 10843422720 1156328720 12.369 100 40 80 2 1509539456 342987920 10222816
11:50:07 21137 8192000000 10379934288 10867916800 1144486544 12.258 100 26 50 1 1493622944 342160784 6975616
11:51:32 46517 8192000000 10282030864 10725793792 1125406800 12.078 100 15 30 0 1409561984 279512912 4642272
11:53:22 28879 8192000000 10074966384 10490146816 1121388304 12.040 100 44 86 1 1156512848 25725680 9398864
11:56:01 61102 8192000000 10090490096 10529325056 1118032176 12.008 100 36 72 0 1175901616 50221696 7647744
11:58:09 14748 8192000000 10066368544 10492018688 1116612192 11.995 100 52 105 1 1163225760 34644432 11969136
11:59:25 29818 8192000000 10045882896 10493890560 1111686640 11.948 99 12 23 0 1138050800 23816560 2547600
12:01:03 80303 8192000000 10113598512 10548895744 1119823008 12.025 99 26 53 1 1189728352 64136096 5769248
12:03:24 84323 8192000000 10140156192 10598035456 1130610720 12.127 100 13 26 0 1234336624 100753824 2972080
12:06:51 68152 8192000000 10076022336 10549022720 1132718880 12.147 100 8 15 1 1159868528 25636784 1512864
12:09:01 23405 8192000000 10059779104 10490507264 1133744320 12.157 100 50 98 1 1166003712 22100352 10159040
12:10:25 39384 8192000000 10126834144 10549014528 1117762480 12.006 98 29 58 1 1186282720 61635184 6885056
12:12:21 98403 8192000000 10075332128 10531753984 1131093664 12.132 99 18 36 0 1256955504 121487424 4374416
12:14:59 63581 8192000000 10087959120 10567266304 1122964768 12.055 98 15 30 0 1197594912 71428624 3201520
12:17:04 30326 8192000000 10072285584 10511220736 1128436320 12.107 99 27 53 0 1156826912 22819792 5570800
12:18:35 74795 8192000000 10087082688 10532888576 1124413520 12.069 98 30 58 1 1182226576 52157344 5655712
12:21:45 49173 8192000000 10056073520 10532089856 1127017600 12.093 99 6 13 0 1153381680 25527712 836368
12:23:35 79811 8192000000 10050229872 10487341056 1066158224 11.515 94 11 21 3 1095122320 26601440 2362656
12:26:00 49170 8192000000 10086120448 10543910912 1128841296 12.110 99 20 40 0 1192050464 58705440 4503728
12:27:55 62882 8192000000 10076678384 10511441920 1108055072 11.914 97 22 44 2 1171730400 58870896 4804432
12:30:01 86875 8192000000 10067720240 10495356928 1095928384 11.799 97 12 24 0 1125685136 27354448 2402304
12:32:43 59410 8192000000 10076876320 10528808960 1097580368 11.815 98 19 38 2 1153534912 51695184 4259360
12:35:13 34083 8192000000 10065945488 10513620992 1081928096 11.666 97 35 69 2 1155210640 66312576 6969968
12:37:52 48628 8192000000 10050808768 10521235456 1109214224 11.925 99 11 22 1 1158645024 47234304 2196496
12:39:45 45649 8192000000 10048647872 10498310144 1087418272 11.718 97 9 18 0 1120322640 31264160 1640208
12:41:37 49995 8192000000 10055186704 10483867648 1086150416 11.706 98 29 58 1 1133281776 40757328 6374032
12:43:38 59505 8192000000 10118426368 10568654848 1095564224 11.796 97 23 45 2 1174380160 73733232 5082704
12:46:29 141308 8192000000 10074545888 10586574848 1041786816 11.282 92 5 10 0 1090208640 47507728 914096
12:49:56 48281 8192000000 10121104848 10559782912 1142723264 12.241 100 18 36 0 1222549264 75791040 4034960
12:51:46 68142 8192000000 10121745296 10573742080 1117502192 12.003 98 12 22 0 1180075264 60592080 1980992
12:53:56 93535 8192000000 10077288064 10561531904 1140124064 12.217 100 14 28 1 1179658528 36769168 2765296
12:56:35 48956 8192000000 10062794320 10518372352 1139020240 12.206 100 11 20 0 1165383264 24442224 1920800
12:58:28 66728 8192000000 10066157232 10528575488 1127244432 12.095 99 6 12 1 1157168816 28813136 1111248
13:00:35 65724 8192000000 10104567072 10543742976 1120846912 12.035 98 24 48 1 1179144160 53314176 4983072
13:02:47 49918 8192000000 10139634496 10574884864 1134154768 12.161 99 23 46 1 1190690672 51484816 5051088
13:04:50 36836 8192000000 10168417440 10616332288 1148928944 12.299 100 37 74 3 1243152544 86370528 7853072
13:06:27 78779 8192000000 10151908384 10649272320 1132889184 12.149 99 25 50 0 1248434144 109553936 5991024
13:09:32 38393 8192000000 10083908064 10555633664 1113803952 11.968 98 28 57 0 1157160016 37932800 5423264
13:11:25 33526 8192000000 10132365520 10593722368 1136429312 12.182 100 41 82 0 1209191488 64496112 8266064
13:13:01 97805 8192000000 10076667808 10578128896 1132561936 12.146 100 18 36 0 1188623776 52502096 3559744
13:15:49 60064 8192000000 10100924768 10568142848 1136043952 12.178 99 13 26 0 1197558320 59343168 2171200
13:17:54 48245 8192000000 10076861936 10547118080 1114736960 11.977 98 14 29 0 1151163520 33833488 2593072
13:19:48 101426 8192000000 10059768080 10498142208 1099904768 11.837 97 16 32 0 1131755680 28901936 2948976
13:22:30 32223 8192000000 10094452992 10513711104 1086311920 11.708 96 34 68 2 1126568016 34079440 6176656
13:24:07 17311 8192000000 10132681536 10559971328 1117393664 12.002 98 39 78 1 1207596512 81878624 8324224
13:25:29 72905 8192000000 10110079456 10673242112 1093399440 11.775 97 11 22 1 1187080944 91056432 2625072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 103 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2020 13:28:18 to 18-12-2020 13: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          237450
MON$OLDEST_ACTIVE               237451
MON$OLDEST_SNAPSHOT             237451
MON$NEXT_TRANSACTION            237451
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                       2089888
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             805
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2298 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2298 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2298 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.2298 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 ---
:48: 
:49: mscs-test-161.budzdorov.ru    Fri Dec 18 13:27:15 2020
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Fri Dec 18 13:27:15 2020
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

18.12.20 13: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-18 13:28:23 No severe PSQL-related problems occured
18.12.20 13:28:25. End of report.