Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.11.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 16874032 kB
MemAvailable 31474888 kB
Buffers 0 kB
Cached 14769676 kB
SwapCached 4712 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-11-20 13:26:53
DTS_END                         2020-11-20 13:26:53.2760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34560
TRN_ID                          240975
ATT_ID                          655
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: 8666.03 28803 1039924
customer order: creation 165.78 415 19894
customer order: refuse 15.02 358 1802
order to supplier: creation 46.37 1326 5564
order to supplier: removal 3.16 115883 379
invoice (draft): creation 46.08 3867 5530
invoice (draft): removal 48.15 934 5778
invoice accept: apply 41.36 18379 4963
invoice accept: cancel 43.10 1009 5172
customer reserve: creation 3748.58 182 449829
customer reserve: removal 4215.84 69 505901
realization accept: apply 82.81 194 9937
realization accept: cancel 84.81 121 10177
payment from customer: creation 57.80 146 6936
payment from customer: removal 5.73 86 687
payment to supplier: creation 46.91 152 5629
payment to supplier: removal 3.63 100 435
service: total inventory turnovers 3.85 3118 462
service: total monetary turnovers 6.11 533 733
service: aggregate perf. data 0.93 14418 112
service: refresh index statistics 0.03 414775 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-11-2020 13:28:08 to 20-11-2020 13:28:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3669
TEST_TIME 2 4924
TEST_TIME 3 4157
TEST_TIME 4 3921
TEST_TIME 5 4114
TEST_TIME 6 4200
TEST_TIME 7 5139
TEST_TIME 8 5653
TEST_TIME 9 6172
TEST_TIME 10 6413
TEST_TIME 11 7127
TEST_TIME 12 7397
TEST_TIME 13 7486
TEST_TIME 14 7377
TEST_TIME 15 7885
TEST_TIME 16 8029
TEST_TIME 17 8199
TEST_TIME 18 8320
TEST_TIME 19 8957
TEST_TIME 20 9015
TEST_TIME 21 9020
TEST_TIME 22 8882
TEST_TIME 23 9065
TEST_TIME 24 8982
TEST_TIME 25 9384
TEST_TIME 26 9237
TEST_TIME 27 9411
TEST_TIME 28 10311
TEST_TIME 29 10946
TEST_TIME 30 10890
TEST_TIME 31 10596
TEST_TIME 32 10490
TEST_TIME 33 10449
TEST_TIME 34 10236
TEST_TIME 35 10132
TEST_TIME 36 10081
TEST_TIME 37 9944
TEST_TIME 38 9873
TEST_TIME 39 9723
TEST_TIME 40 9659
TEST_TIME 41 9646
TEST_TIME 42 9483
TEST_TIME 43 9384
TEST_TIME 44 9297
TEST_TIME 45 9302
TEST_TIME 46 9292
TEST_TIME 47 9248
TEST_TIME 48 9162
TEST_TIME 49 9239
TEST_TIME 50 9275
TEST_TIME 51 9308
TEST_TIME 52 9327
TEST_TIME 53 9331
TEST_TIME 54 9269
TEST_TIME 55 9343
TEST_TIME 56 9355
TEST_TIME 57 9308
TEST_TIME 58 9300
TEST_TIME 59 9235
TEST_TIME 60 9145
TEST_TIME 61 9104
TEST_TIME 62 9026
TEST_TIME 63 8979
TEST_TIME 64 8883
TEST_TIME 65 8899
TEST_TIME 66 8861
TEST_TIME 67 8944
TEST_TIME 68 8984
TEST_TIME 69 8974
TEST_TIME 70 8937
TEST_TIME 71 8947
TEST_TIME 72 8989
TEST_TIME 73 9059
TEST_TIME 74 9009
TEST_TIME 75 9028
TEST_TIME 76 9054
TEST_TIME 77 9008
TEST_TIME 78 8933
TEST_TIME 79 8843
TEST_TIME 80 8834
TEST_TIME 81 8859
TEST_TIME 82 8803
TEST_TIME 83 8775
TEST_TIME 84 8789
TEST_TIME 85 8815
TEST_TIME 86 8811
TEST_TIME 87 8894
TEST_TIME 88 8892
TEST_TIME 89 8912
TEST_TIME 90 9005
TEST_TIME 91 8980
TEST_TIME 92 8986
TEST_TIME 93 8911
TEST_TIME 94 8862
TEST_TIME 95 8923
TEST_TIME 96 8862
TEST_TIME 97 8819
TEST_TIME 98 8790
TEST_TIME 99 8775
TEST_TIME 100 8768
TEST_TIME 101 8752
TEST_TIME 102 8821
TEST_TIME 103 8792
TEST_TIME 104 8832
TEST_TIME 105 8842
TEST_TIME 106 8887
TEST_TIME 107 8947
TEST_TIME 108 8927
TEST_TIME 109 8936
TEST_TIME 110 8929
TEST_TIME 111 8864
TEST_TIME 112 8791
TEST_TIME 113 8837
TEST_TIME 114 8787
TEST_TIME 115 8741
TEST_TIME 116 8703
TEST_TIME 117 8696
TEST_TIME 118 8677
TEST_TIME 119 8680
TEST_TIME 120 8675

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-11-2020 13:28:12 to 20-11-2020 13:28:15.

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 1456869 1456869 0 0.00 0 30158 7 0 0
doc_list_biud 1456865 1456865 0 0.00 0 44319 31 0 0
sp_add_invoice_to_stock 5096 4963 133 2.60 34 177122 18380 0 133
sp_cancel_adding_invoice 5172 5172 0 0.00 5 70891 1009 0 0
sp_cancel_client_order 1802 1802 0 0.00 5 10869 359 0 0
sp_cancel_customer_reserve 505901 505901 0 0.00 1 36500 69 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 4921 86 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 4861 100 0 0
sp_cancel_supplier_invoice 5786 5778 8 0.13 2 23933 935 0 8
sp_cancel_supplier_order 379 379 0 0.00 7 927585 115884 0 0
sp_cancel_write_off 10177 10177 0 0.00 1 9662 121 0 0
sp_client_order 19894 19894 0 0.00 4 19240 415 0 0
sp_customer_reserve 449829 449829 0 0.00 0 49817 182 0 0
sp_fill_shopping_cart 30978 30978 0 0.00 1 22616 55 0 0
sp_fill_shopping_cart_clo_res 449818 449818 0 0.00 0 30050 22 0 0
sp_get_clo_for_invoice 4948 4948 0 0.00 0 38040 451 0 0
sp_kill_cost_storno 16471 16471 0 0.00 0 11272 62 0 0
sp_kill_qstorno_ret_qs2qd 1028136 1028136 0 0.00 0 44317 37 0 0
sp_kill_qty_storno 544093 544093 0 0.00 0 44319 81 0 0
sp_lock_selected_doc 20760 20760 0 0.00 0 8288 6 0 0
sp_make_cost_storno 27450 27450 0 0.00 0 18630 64 0 0
sp_multiply_rows_for_pdistr 27449 27449 0 0.00 0 10537 34 0 0
sp_multiply_rows_for_qdistr 19894 19894 0 0.00 0 17770 229 0 0
sp_pay_from_customer 6936 6936 0 0.00 4 10589 146 0 0
sp_pay_to_supplier 5629 5629 0 0.00 5 11668 153 0 0
sp_payment_common 12565 12565 0 0.00 1 9786 34 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 10855 326 0 0
sp_qd_handle_on_invoice_upd_sts 10120 10120 0 0.00 0 35245 410 0 0
sp_qd_handle_on_reserve_upd_sts 9936 9936 0 0.00 0 14186 56 0 0
sp_reserve_write_off 9937 9937 0 0.00 7 24026 195 0 0
sp_supplier_invoice 5530 5530 0 0.00 6 105195 3868 0 0
sp_supplier_order 5564 5564 0 0.00 5 58178 1326 0 0
srv_aggregate_perf_data 112 112 0 0.00 2790 135763 14418 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1097 143440 44643 0 0
srv_find_qd_qs_mism 993644 993644 0 0.00 0 30157 6 0 0
srv_make_invnt_saldo 462 462 0 0.00 29 105943 3118 0 0
srv_make_money_saldo 733 733 0 0.00 1 16213 533 0 0
srv_recalc_idx_stat 4 4 0 0.00 254519 508973 414775 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4963 4963 0 0.00 0 28092 177 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 23530 150 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 4754 17 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 1610 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 6211 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 406 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 861 14 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 20337 1541 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 14592 21 0 0
t$perf-norm:sp_client_order 19894 19894 0 0.00 0 9995 15 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 3518 7 0 0
t$perf-norm:sp_pay_from_customer 6936 6936 0 0.00 0 3277 5 0 0
t$perf-norm:sp_pay_to_supplier 5629 5629 0 0.00 0 7725 10 0 0
t$perf-norm:sp_reserve_write_off 9937 9937 0 0.00 0 9549 9 0 0
t$perf-norm:sp_supplier_invoice 5530 5530 0 0.00 0 5877 15 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 5763 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 236 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 87 4 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 782 10 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 204 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 35 10 0 0
v_add_invoice_to_stock 10162 9896 266 2.61 0 4337 18 0 266
v_all_customers 36218 36218 0 0.00 0 18 1 0 0
v_all_suppliers 29982 29982 0 0.00 0 21 1 0 0
v_all_wares 39788 39788 0 0.00 0 104 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 3091 11 0 0
v_cancel_client_order 3604 3604 0 0.00 0 2774 4 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 2664 8 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 836 4 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 629 5 0 0
v_cancel_supplier_invoice 892 876 16 1.79 0 10539 30 0 16
v_cancel_supplier_order 758 758 0 0.00 0 267 5 0 0
v_cancel_write_off 2050 2050 0 0.00 0 1479 14 0 0
v_max_id_clo_ord 5564 5564 0 0.00 0 3299 2 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 3586 12 0 0
v_max_id_ord_sup 5519 5519 0 0.00 0 5697 7 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 309 3 0 0
v_max_non_paid_realizn 3708 3708 0 0.00 0 5708 5 0 0
v_min_id_clo_ord 5564 5564 0 0.00 0 1863 7 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 2949 10 0 0
v_min_id_ord_sup 5519 5519 0 0.00 0 5923 15 0 0
v_min_non_paid_invoice 5629 5629 0 0.00 0 7254 20 0 0
v_min_non_paid_realizn 6936 6936 0 0.00 0 9772 31 0 0
v_our_firm 10026 10026 0 0.00 0 17 1 0 0
v_reserve_write_off 19872 19872 0 0.00 0 4934 5 0 0
x_lock_depdocs_on_canc_invoice 10512 10512 0 0.00 0 36016 124 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 11248 347 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 2 58168 1232 0 0
x_make_qstorno_1200_2000 5530 5530 0 0.00 3 105156 3656 0 0
x_make_qstorno_2100_3300 449828 449828 0 0.00 0 49816 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-11-2020 13:28:16 to 20-11-2020 13:28:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:57 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:57 49818 8192000000 9717137424 10009444352 782531696 8.719 100 92 184 2 813577808 8115616 22930496
10:59:54 20753 8192000000 9763117248 10067202048 829004608 9.189 100 84 168 1 861863728 12855488 20003632
11:01:19 18115 8192000000 9809707680 10130538496 871023712 9.610 100 76 152 0 911244976 22871184 17350080
11:02:40 6450 8192000000 9873924512 10209300480 923930992 10.135 100 79 158 1 989603696 48406944 17265760
11:03:48 9233 8192000000 9932210688 10278711296 963613216 10.524 100 81 162 2 1038649376 58058656 16977504
11:05:00 3105 8192000000 9984044448 10338930688 1004968272 10.927 100 88 176 0 1097914928 74946880 17999776
11:06:04 3306 8192000000 10004733024 10364686336 1029154880 11.160 100 86 167 1 1112628320 66876336 16597104
11:07:10 2297 8192000000 10038971856 10406809600 1058233760 11.440 100 93 186 1 1147630080 70592608 18803712
11:08:14 6171 8192000000 10030648928 10422435840 1072658736 11.577 100 77 152 1 1160726176 72132416 15935024
11:09:21 6444 8192000000 10003454128 10392342528 1073952272 11.590 100 43 83 2 1125752160 43488944 8310944
11:10:29 4349 8192000000 9999861264 10394353664 1081867584 11.665 100 46 87 3 1125934832 34782464 9284784
11:11:35 5438 8192000000 10006787552 10412068864 1090537312 11.748 100 37 74 1 1128860816 29547472 8776032
11:12:43 48756 8192000000 10024494608 10416812032 1097736048 11.816 100 57 115 1 1128207312 18211104 12260160
11:14:32 3498 8192000000 10021223776 10435076096 1100567392 11.843 100 30 60 2 1137116128 31013712 5535024
11:15:43 6363 8192000000 10018110672 10423889920 1102396576 11.860 100 39 80 1 1136047440 24877648 8773216
11:16:50 6214 8192000000 9994761776 10393083904 1056797872 11.426 97 40 78 3 1087389648 21604480 8987296
11:17:57 44208 8192000000 9959535744 10345943040 1030772224 11.176 97 35 69 2 1058728960 20620368 7336368
11:19:47 9202 8192000000 9964872640 10360885248 1019218384 11.064 98 45 88 2 1055798416 27334176 9245856
11:20:57 15281 8192000000 9926603664 10315608064 963710080 10.525 94 34 68 0 1005016448 33767584 7538784
11:22:16 8131 8192000000 9947700368 10341330944 1031722608 11.185 99 34 68 3 1052669552 13310496 7636448
11:23:26 29435 8192000000 9958317712 10366627840 1041802544 11.282 99 29 58 2 1076954400 28866128 6285728
11:24:58 5112 8192000000 9986923760 10384646144 1067593872 11.529 100 43 85 2 1094749248 18046128 9109248
11:26:05 5521 8192000000 10005292576 10404155392 1078917536 11.637 100 46 92 3 1098627520 8791136 10918848
11:27:15 25671 8192000000 10012434912 10406633472 1091568784 11.758 100 55 105 1 1112971600 10488992 10913824
11:28:44 131502 8192000000 10074972512 10491052032 1113600080 11.966 100 39 78 4 1192534544 70587264 8347200
11:31:57 40268 8192000000 10133921248 10555437056 1123603632 12.061 100 36 71 3 1253263456 121859056 7800768
11:33:42 14270 8192000000 10135702864 10552758272 1133130208 12.151 100 54 105 2 1235904624 90747712 12026704
11:35:03 3402 8192000000 10175110784 10579947520 1156824512 12.374 100 72 144 1 1284165536 112019360 15321664
11:36:09 11641 8192000000 10191930480 10601062400 1157130544 12.376 100 68 130 5 1291491792 120539712 13821536
11:37:23 2384 8192000000 10222468016 10624077824 1161362688 12.416 100 89 178 1 1322407248 142229056 18815504
11:38:30 1404 8192000000 10219100448 10616295424 1141015152 12.225 100 92 182 1 1321766640 161410784 19340704
11:39:36 2927 8192000000 10198351824 10602958848 1149754656 12.307 100 81 154 1 1297623088 131047728 16820704
11:40:44 4960 8192000000 10257183312 10657095680 1147360048 12.285 100 80 160 0 1348831328 183594928 17876352
11:41:52 1093 8192000000 10256451296 10649554944 1150839952 12.317 100 87 169 3 1350676832 181216080 18620800
11:42:59 1692 8192000000 10310062448 10712096768 1164519296 12.446 100 93 180 2 1401409200 216798464 20091440
11:44:03 2184 8192000000 10302984400 10699558912 1132659376 12.146 98 89 177 0 1391285072 237935520 20690176
11:45:11 1995 8192000000 10311773488 10748108800 1146252816 12.274 100 75 148 1 1426353808 262692544 17408448
11:46:15 7864 8192000000 10284097488 10689581056 1138910160 12.205 100 49 98 2 1375708000 225314928 11482912
11:47:30 5469 8192000000 10291624032 10696482816 1136178224 12.180 100 65 122 2 1392173424 241384816 14610384
11:48:42 8840 8192000000 10337351904 10754543616 1145965952 12.272 100 34 66 0 1432863024 278494960 8402112
11:49:57 11798 8192000000 10357795712 10777796608 1146771280 12.279 100 36 72 0 1455469840 299612880 9085680
11:51:10 13737 8192000000 10372366080 10854256640 1149349248 12.303 100 30 58 1 1497503808 340503040 7651520
11:52:39 28682 8192000000 10417758080 10856665088 1123607088 12.061 100 22 44 1 1505251120 375414096 6229936
11:54:19 20506 8192000000 10301564208 10801524736 1102881008 11.865 98 27 54 3 1438342976 328413712 7048256
11:55:40 34429 8192000000 10104971232 10530951168 1130700352 12.128 100 43 84 1 1184997840 44999120 9298368
11:58:51 26594 8192000000 10097293824 10541666304 1126845760 12.092 100 22 44 1 1193303008 62165136 4292112
12:00:19 76031 8192000000 10092616352 10537009152 1140571696 12.221 100 20 41 0 1187261872 42530000 4160176
12:03:39 100028 8192000000 10105570144 10550239232 1127224944 12.095 98 17 34 0 1190621280 59278192 4118144
12:06:42 83149 8192000000 10070571136 10552217600 1130045472 12.122 99 15 29 2 1160503456 27701440 2756544
12:09:06 55232 8192000000 10097720208 10535632896 1147562928 12.287 100 28 55 0 1202982304 49354608 6064768
12:11:08 72698 8192000000 10095117296 10585284608 1133953344 12.159 99 9 18 0 1192479984 56956176 1570464
12:13:21 35307 8192000000 10109104192 10569101312 1140484736 12.220 99 24 48 1 1177631264 31880896 5265632
12:15:00 54602 8192000000 10120510512 10570117120 1141684864 12.231 99 18 36 2 1203207520 57388080 4134576
12:16:59 108578 8192000000 10079821904 10555559936 1112349616 11.955 96 14 28 1 1156068400 40551024 3167760
12:19:49 39154 8192000000 10128321856 10552557568 1140775152 12.223 99 45 88 1 1174991696 25048720 9167824
12:21:28 31148 8192000000 10073283296 10513293312 1139167040 12.208 99 23 46 0 1166554768 23243744 4143984
12:23:26 29270 8192000000 10093553872 10513608704 1114401888 11.974 97 41 81 2 1154939120 31929456 8607776
12:24:57 95687 8192000000 10096381696 10552778752 1121167344 12.038 98 19 38 0 1199311904 74156672 3987888
12:28:34 43986 8192000000 10057755648 10504445952 1105382784 11.889 98 8 16 0 1144187376 37348688 1455904
12:30:19 19825 8192000000 10059473136 10520924160 1124144688 12.066 100 7 14 0 1179506240 54223888 1137664
12:31:39 63077 8192000000 10078786960 10514948096 1061295584 11.469 94 24 49 0 1114499120 48095568 5107968
12:33:44 53922 8192000000 10034336752 10466877440 1074992480 11.600 96 11 22 1 1132695184 55338688 2364016
12:35:39 39119 8192000000 10033145424 10460327936 1061240960 11.468 96 24 48 0 1102254400 35735520 5277920
12:37:19 76074 8192000000 10057317936 10520305664 1060552272 11.462 95 16 32 1 1128514224 65003360 2958592
12:39:38 51063 8192000000 10030538272 10451595264 1091582480 11.758 99 31 62 1 1136307888 38012112 6713296
12:41:29 14391 8192000000 10063152944 10477158400 1106389920 11.898 99 59 118 3 1155865088 37664416 11810752
12:42:45 52627 8192000000 10074428592 10551771136 1119012112 12.018 100 40 80 1 1211369120 84362816 7994192
12:44:55 74109 8192000000 10096836576 10553716736 1124407360 12.069 100 15 30 0 1186421056 58704992 3308704
12:47:27 26747 8192000000 10047397024 10512658432 1095725632 11.797 98 11 22 1 1138095120 40440992 1928496
12:48:56 23644 8192000000 10076141808 10505338880 1094409728 11.785 97 29 57 3 1145513440 45426960 5676752
12:50:22 47011 8192000000 10090847088 10561908736 1117852384 12.007 99 26 52 2 1200688256 76574288 6261584
12:52:20 26514 8192000000 10144847776 10576023552 1115381600 11.983 98 32 64 1 1209512784 87223216 6907968
12:53:47 56866 8192000000 10143458736 10567213056 1130680704 12.128 100 33 66 3 1229598592 91313696 7604192
12:55:49 79760 8192000000 10071129888 10497388544 1122544336 12.051 99 16 32 0 1185461504 59420208 3496960
12:58:09 50073 8192000000 10125770576 10554089472 1130924000 12.130 99 26 52 4 1187514736 51311728 5279008
13:00:07 97330 8192000000 10077300688 10602627072 1134987472 12.168 100 17 34 0 1254281888 115252096 4042320
13:04:07 76230 8192000000 10108990064 10562142208 1122661216 12.052 99 27 51 2 1167401728 39270048 5470464
13:06:24 56321 8192000000 10158840720 10642087936 1149111232 12.301 100 20 40 1 1238551328 85090944 4349152
13:08:21 72616 8192000000 10107326768 10557464576 1091542016 11.757 96 17 34 0 1224857344 129454208 3861120
13:10:39 97204 8192000000 10102857760 10569867264 1115141552 11.981 98 23 46 0 1185373952 65083232 5149168
13:13:17 62765 8192000000 10064631936 10506416128 1121963888 12.046 99 13 26 2 1176463392 51715520 2783984
13:15:20 59984 8192000000 10081686704 10519367680 1121506480 12.041 98 27 53 2 1191843584 64959648 5377456
13:17:21 143440 8192000000 10081625040 10593103872 1131950480 12.140 100 18 36 0 1211349968 75859168 3540320
13:21:42 77894 8192000000 10076133296 10534789120 1088041648 11.724 96 11 22 1 1136551088 46347776 2161664
13:24:04 48594 8192000000 10108492640 10559393792 1086163104 11.706 96 14 28 0 1159245488 69833504 3248880
13:25:58 37017 8192000000 10122082304 10555953152 1118513392 12.013 99 25 50 2 1199255552 75343376 5398784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-11-2020 13:28:22 to 20-11-2020 13:28:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-11-2020 13:28:38 to 20-11-2020 13:28:40.

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          235884
MON$OLDEST_ACTIVE               241097
MON$OLDEST_SNAPSHOT             241097
MON$NEXT_TRANSACTION            241097
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                       2091648
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             809
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2271 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2271 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2271 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.2271 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 ---
:1488: 
:1489: mscs-test-161.budzdorov.ru    Fri Nov 20 13:26:54 2020
:1490:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1491: 
:1492: 
:1493: mscs-test-161.budzdorov.ru    Fri Nov 20 13:26:57 2020
:1494:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1495: 
:1496: 
:1497: mscs-test-161.budzdorov.ru    Fri Nov 20 13:26:58 2020
:1498:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1499: 
:1500: 
:1501: mscs-test-161.budzdorov.ru    Fri Nov 20 13:27:03 2020
:1502:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1503: 
--- end of diff output ---

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

20.11.20 13:28:41. 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-11-20 13:28:44 No severe PSQL-related problems occured
20.11.20 13:28:45. End of report.