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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-02 07:26:54
DTS_END                         2021-01-02 07:26:54.1430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34992
TRN_ID                          228111
ATT_ID                          590
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: 8615.73 23369 1033888
customer order: creation 162.02 415 19442
customer order: refuse 13.63 320 1636
order to supplier: creation 43.68 1228 5241
order to supplier: removal 3.43 120265 411
invoice (draft): creation 42.34 3249 5081
invoice (draft): removal 48.40 1031 5808
invoice accept: apply 39.03 17596 4683
invoice accept: cancel 43.95 911 5274
customer reserve: creation 3607.83 172 432940
customer reserve: removal 4343.85 75 521262
realization accept: apply 77.51 176 9301
realization accept: cancel 68.99 123 8279
payment from customer: creation 56.83 104 6819
payment from customer: removal 4.99 86 599
payment to supplier: creation 45.63 123 5475
payment to supplier: removal 3.53 105 423
service: total inventory turnovers 3.96 3071 475
service: total monetary turnovers 5.39 513 647
service: aggregate perf. data 0.73 23879 88
service: refresh index statistics 0.03 293928 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2021 07:28:09 to 02-01-2021 07:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4078
TEST_TIME 2 4522
TEST_TIME 3 5314
TEST_TIME 4 5442
TEST_TIME 5 5089
TEST_TIME 6 5206
TEST_TIME 7 5203
TEST_TIME 8 5030
TEST_TIME 9 5294
TEST_TIME 10 5416
TEST_TIME 11 5479
TEST_TIME 12 5635
TEST_TIME 13 5698
TEST_TIME 14 5863
TEST_TIME 15 6174
TEST_TIME 16 6911
TEST_TIME 17 7083
TEST_TIME 18 6897
TEST_TIME 19 7316
TEST_TIME 20 7661
TEST_TIME 21 8244
TEST_TIME 22 8535
TEST_TIME 23 8892
TEST_TIME 24 9653
TEST_TIME 25 10834
TEST_TIME 26 10876
TEST_TIME 27 10795
TEST_TIME 28 10437
TEST_TIME 29 10146
TEST_TIME 30 10165
TEST_TIME 31 10137
TEST_TIME 32 9966
TEST_TIME 33 9748
TEST_TIME 34 9734
TEST_TIME 35 9659
TEST_TIME 36 9629
TEST_TIME 37 9475
TEST_TIME 38 9430
TEST_TIME 39 9320
TEST_TIME 40 9206
TEST_TIME 41 9258
TEST_TIME 42 9220
TEST_TIME 43 9115
TEST_TIME 44 9107
TEST_TIME 45 9202
TEST_TIME 46 9180
TEST_TIME 47 9296
TEST_TIME 48 9349
TEST_TIME 49 9350
TEST_TIME 50 9337
TEST_TIME 51 9360
TEST_TIME 52 9389
TEST_TIME 53 9282
TEST_TIME 54 9134
TEST_TIME 55 9073
TEST_TIME 56 9083
TEST_TIME 57 8955
TEST_TIME 58 8882
TEST_TIME 59 8927
TEST_TIME 60 8978
TEST_TIME 61 8951
TEST_TIME 62 8959
TEST_TIME 63 8996
TEST_TIME 64 8993
TEST_TIME 65 9005
TEST_TIME 66 8996
TEST_TIME 67 9077
TEST_TIME 68 9061
TEST_TIME 69 9106
TEST_TIME 70 9119
TEST_TIME 71 9124
TEST_TIME 72 9072
TEST_TIME 73 8990
TEST_TIME 74 8954
TEST_TIME 75 8905
TEST_TIME 76 8825
TEST_TIME 77 8764
TEST_TIME 78 8781
TEST_TIME 79 8811
TEST_TIME 80 8786
TEST_TIME 81 8801
TEST_TIME 82 8781
TEST_TIME 83 8825
TEST_TIME 84 8867
TEST_TIME 85 8904
TEST_TIME 86 8892
TEST_TIME 87 8896
TEST_TIME 88 8885
TEST_TIME 89 8898
TEST_TIME 90 8921
TEST_TIME 91 8828
TEST_TIME 92 8773
TEST_TIME 93 8721
TEST_TIME 94 8746
TEST_TIME 95 8744
TEST_TIME 96 8706
TEST_TIME 97 8709
TEST_TIME 98 8816
TEST_TIME 99 8798
TEST_TIME 100 8761
TEST_TIME 101 8847
TEST_TIME 102 8853
TEST_TIME 103 8809
TEST_TIME 104 8834
TEST_TIME 105 8893
TEST_TIME 106 8905
TEST_TIME 107 8911
TEST_TIME 108 8884
TEST_TIME 109 8815
TEST_TIME 110 8783
TEST_TIME 111 8737
TEST_TIME 112 8715
TEST_TIME 113 8666
TEST_TIME 114 8645
TEST_TIME 115 8647
TEST_TIME 116 8624
TEST_TIME 117 8604
TEST_TIME 118 8613
TEST_TIME 119 8655
TEST_TIME 120 8616

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-01-2021 07:28:13 to 02-01-2021 07:28:16.

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 1434777 1434777 0 0.00 0 14487 6 0 0
doc_list_biud 1434777 1434777 0 0.00 0 39612 34 0 0
sp_add_invoice_to_stock 4824 4683 141 2.92 32 170251 17596 0 141
sp_cancel_adding_invoice 5274 5274 0 0.00 5 86374 912 0 0
sp_cancel_client_order 1636 1636 0 0.00 5 9426 320 0 0
sp_cancel_customer_reserve 521262 521262 0 0.00 1 21847 76 0 0
sp_cancel_pay_from_customer 599 599 0 0.00 3 10479 86 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 12167 106 0 0
sp_cancel_supplier_invoice 5824 5808 16 0.27 3 20721 1031 0 16
sp_cancel_supplier_order 411 411 0 0.00 5 1135328 120266 0 0
sp_cancel_write_off 8279 8279 0 0.00 1 11189 124 0 0
sp_client_order 19442 19442 0 0.00 4 20788 415 0 0
sp_customer_reserve 432940 432940 0 0.00 0 50020 172 0 0
sp_fill_shopping_cart 29751 29751 0 0.00 1 28316 42 0 0
sp_fill_shopping_cart_clo_res 432940 432940 0 0.00 0 17352 19 0 0
sp_get_clo_for_invoice 4683 4683 0 0.00 0 32891 451 0 0
sp_kill_cost_storno 14575 14575 0 0.00 0 6964 60 0 0
sp_kill_qstorno_ret_qs2qd 1057022 1057022 0 0.00 0 21842 41 0 0
sp_kill_qty_storno 556654 556654 0 0.00 0 39612 87 0 0
sp_lock_selected_doc 19378 19378 0 0.00 0 12158 5 0 0
sp_make_cost_storno 26278 26278 0 0.00 0 13221 51 0 0
sp_multiply_rows_for_pdistr 26278 26278 0 0.00 0 8797 31 0 0
sp_multiply_rows_for_qdistr 19442 19442 0 0.00 0 16753 245 0 0
sp_pay_from_customer 6819 6819 0 0.00 4 12386 105 0 0
sp_pay_to_supplier 5475 5475 0 0.00 5 13589 124 0 0
sp_payment_common 12294 12294 0 0.00 1 6054 20 0 0
sp_qd_handle_on_cancel_clo 1636 1636 0 0.00 0 6567 281 0 0
sp_qd_handle_on_invoice_upd_sts 9957 9957 0 0.00 0 39610 383 0 0
sp_qd_handle_on_reserve_upd_sts 9301 9301 0 0.00 0 8917 49 0 0
sp_reserve_write_off 9301 9301 0 0.00 7 13897 177 0 0
sp_supplier_invoice 5081 5081 0 0.00 7 91079 3250 0 0
sp_supplier_order 5241 5241 0 0.00 5 31220 1228 0 0
srv_aggregate_perf_data 88 88 0 0.00 3159 564183 23880 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1723 139749 46832 0 0
srv_find_qd_qs_mism 989064 989064 0 0.00 0 12969 5 0 0
srv_make_invnt_saldo 475 475 0 0.00 54 118399 3071 0 0
srv_make_money_saldo 647 647 0 0.00 1 17690 513 0 0
srv_recalc_idx_stat 4 4 0 0.00 87957 473758 293928 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4683 4683 0 0.00 0 22459 154 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 1 7492 91 0 0
t$perf-norm:sp_cancel_client_order 1636 1636 0 0.00 0 3547 14 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 694 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 599 599 0 0.00 0 1258 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 349 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 511 13 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 30063 1282 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 967 4 0 0
t$perf-norm:sp_client_order 19442 19442 0 0.00 0 5277 17 0 0
t$perf-norm:sp_customer_reserve 877 877 0 0.00 0 8127 13 0 0
t$perf-norm:sp_pay_from_customer 6819 6819 0 0.00 0 11882 10 0 0
t$perf-norm:sp_pay_to_supplier 5475 5475 0 0.00 0 7166 11 0 0
t$perf-norm:sp_reserve_write_off 9301 9301 0 0.00 0 13256 9 0 0
t$perf-norm:sp_supplier_invoice 5081 5081 0 0.00 0 6631 19 0 0
t$perf-norm:sp_supplier_order 5241 5241 0 0.00 0 5131 14 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 360 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1126 10 0 0
t$perf-norm:srv_make_money_saldo 647 647 0 0.00 0 766 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9648 9366 282 2.92 0 8917 18 0 282
v_all_customers 36178 36178 0 0.00 0 15 1 0 0
v_all_suppliers 28594 28594 0 0.00 0 68 1 0 0
v_all_wares 38884 38884 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 2592 9 0 0
v_cancel_client_order 3272 3272 0 0.00 0 8973 8 0 0
v_cancel_customer_prepayment 1198 1198 0 0.00 0 10022 19 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 4084 9 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1077 6 0 0
v_cancel_supplier_invoice 764 732 32 4.18 0 681 13 0 32
v_cancel_supplier_order 822 822 0 0.00 0 4448 11 0 0
v_cancel_write_off 1824 1824 0 0.00 0 10874 24 0 0
v_max_id_clo_ord 5241 5241 0 0.00 0 315 1 0 0
v_max_id_clo_res 877 877 0 0.00 0 6776 18 0 0
v_max_id_ord_sup 5068 5068 0 0.00 0 3172 3 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 719 2 0 0
v_max_non_paid_realizn 3498 3498 0 0.00 0 3444 3 0 0
v_min_id_clo_ord 5241 5241 0 0.00 0 2972 4 0 0
v_min_id_clo_res 877 877 0 0.00 0 4098 17 0 0
v_min_id_ord_sup 5068 5068 0 0.00 0 5042 5 0 0
v_min_non_paid_invoice 5475 5475 0 0.00 0 4978 12 0 0
v_min_non_paid_realizn 6819 6819 0 0.00 0 5051 16 0 0
v_our_firm 9348 9348 0 0.00 0 66 1 0 0
v_reserve_write_off 18602 18602 0 0.00 0 12296 10 0 0
x_lock_depdocs_on_canc_invoice 10716 10716 0 0.00 0 24984 113 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 13555 435 0 0
x_make_qstorno_1000_1200 5241 5241 0 0.00 2 31211 1164 0 0
x_make_qstorno_1200_2000 5081 5081 0 0.00 3 91058 3079 0 0
x_make_qstorno_2100_3300 432940 432940 0 0.00 0 50018 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2021 07:28:17 to 02-01-2021 07:28:21.

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
04:56:57 8 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:59 19799 8192000000 9719199584 10017976320 799234368 8.889 100 81 161 1 827362512 7879328 20248816
04:59:23 19576 8192000000 9770510528 10076504064 847140976 9.371 100 84 168 0 878390144 11216352 20032816
05:00:45 21037 8192000000 9814656560 10131247104 879011888 9.690 100 85 170 1 923165792 24766736 19387168
05:02:09 12490 8192000000 9867563888 10197393408 911436784 10.012 100 76 152 0 973692288 45487008 16768496
05:03:25 14019 8192000000 9896827104 10237526016 951913088 10.410 100 72 144 0 1010111824 42783568 15415168
05:04:42 4599 8192000000 9967686080 10320027648 1000524608 10.884 100 80 156 2 1075305488 58736192 16044688
05:05:48 3186 8192000000 10019014240 10377687040 1030783008 11.176 100 94 187 0 1130238768 80403632 19052128
05:06:52 2342 8192000000 10044285952 10411941888 1055271536 11.411 100 94 187 3 1153567616 79367536 18928544
05:07:57 1655 8192000000 10042360176 10416254976 1070339520 11.555 100 94 189 0 1148001696 57568240 20093936
05:09:00 59357 8192000000 9984499104 10394226688 1076242480 11.612 100 27 54 2 1118699104 36623728 5832896
05:11:00 2381 8192000000 9991268096 10382483456 1085524160 11.700 100 36 72 3 1107563040 11080608 10958272
05:12:03 5839 8192000000 10012061504 10411769856 1094924864 11.789 100 31 62 0 1128755088 27639376 6190848
05:13:10 9151 8192000000 10004171168 10404904960 1106111376 11.896 100 42 84 1 1130882112 15166640 9604096
05:14:21 51027 8192000000 10017062432 10422112256 1103994736 11.876 100 28 53 2 1120540640 10342464 6203440
05:16:13 7535 8192000000 9999514224 10399313920 1089599104 11.739 99 39 77 0 1119689440 21178560 8911776
05:17:21 7796 8192000000 9995897968 10392002560 1043217824 11.296 95 35 71 1 1073028736 22607888 7203024
05:18:29 8042 8192000000 9963484896 10357067776 1023932208 11.110 97 39 78 3 1049200496 16594576 8673712
05:19:40 41667 8192000000 9928504352 10304520192 957709712 10.467 92 40 78 3 987272480 20655824 8906944
05:21:22 7563 8192000000 9922123600 10300956672 963540400 10.524 95 38 75 2 983149856 12223664 7385792
05:22:35 7316 8192000000 9959272368 10342518784 1039142912 11.256 100 46 92 0 1063612112 13453840 11015360
05:23:44 5251 8192000000 9978792512 10377322496 1047496032 11.337 100 40 82 2 1099056800 42765232 8795536
05:24:51 46769 8192000000 9961472784 10363424768 1058127760 11.439 100 22 44 1 1088735840 25723392 4884688
05:26:38 5934 8192000000 9999397536 10398023680 1080303104 11.650 100 29 57 1 1111472912 24820480 6349328
05:27:49 18423 8192000000 10066328368 10466226176 1101541568 11.852 100 58 115 2 1160896080 48070256 11284256
05:29:22 13217 8192000000 10075511328 10474352640 1125177136 12.076 100 45 88 3 1187713520 53108416 9427968
05:30:39 7880 8192000000 10130172816 10538717184 1136909808 12.186 100 42 84 0 1238161280 92147744 9103728
05:31:53 10482 8192000000 10190959408 10596151296 1154141040 12.348 100 54 107 2 1291337072 125627536 11568496
05:33:08 9620 8192000000 10221457984 10637221888 1163967072 12.440 100 41 82 1 1322188992 149125120 9096800
05:34:21 8764 8192000000 10273658976 10683469824 1182337072 12.612 100 67 134 0 1382596080 184503728 15755280
05:35:34 13694 8192000000 10320125424 10736668672 1187538304 12.660 100 52 104 2 1422532064 221931440 13062320
05:36:53 4755 8192000000 10325037648 10742693888 1167936544 12.478 98 69 133 4 1410983680 227990352 15056784
05:38:02 5664 8192000000 10387384128 10796810240 1187727120 12.662 99 70 138 0 1483119424 279619120 15773184
05:39:15 1723 8192000000 10430102112 10834182144 1201467312 12.790 100 84 167 3 1531854960 311396304 18991344
05:40:21 2918 8192000000 10474432864 10885873664 1197841968 12.756 100 70 139 0 1583424656 369122208 16460480
05:41:31 3035 8192000000 10543438528 10949705728 1190076720 12.684 100 74 148 0 1632857424 425052880 17727824
05:42:41 12661 8192000000 10508389760 10984779776 1191914944 12.701 100 53 104 2 1634666144 429767040 12984160
05:44:04 6630 8192000000 10481961872 10908348416 1162126048 12.423 99 47 92 2 1582389264 408182256 12080960
05:45:20 9458 8192000000 10514901152 10952073216 1164036880 12.441 99 49 98 0 1613042928 436086384 12919664
05:46:33 13528 8192000000 10472347408 10930466816 1159920400 12.403 100 51 102 0 1600598960 426996480 13682080
05:47:49 16437 8192000000 10415042160 10895462400 1140872096 12.224 100 30 60 0 1557469360 408042016 8555248
05:49:09 9775 8192000000 10413397600 10849337344 1095733824 11.797 98 29 57 2 1495770528 392003408 8033296
05:50:20 32432 8192000000 10246801808 10694471680 1065273664 11.507 96 20 40 4 1341294448 270780544 5240240
05:51:55 2902 8192000000 10070093904 10469310464 1111107344 11.943 99 73 143 2 1150370320 23124256 16138720
05:52:59 40107 8192000000 10045195104 10514518016 1087372160 11.718 98 10 20 0 1174299920 84479968 2447792
05:54:39 68467 8192000000 10099738752 10536087552 1113935024 11.970 100 21 41 3 1192648448 74367088 4346336
05:57:01 42030 8192000000 10042642816 10483847168 1074066736 11.591 96 22 43 0 1102857552 24680608 4110208
05:58:46 97979 8192000000 10118715776 10565496832 1089911728 11.742 97 25 50 2 1193433904 98807200 4714976
06:01:41 69812 8192000000 10041621568 10514948096 1105171392 11.887 99 27 51 2 1134596512 24553328 4871792
06:04:11 41138 8192000000 10061660960 10501324800 1103500528 11.871 99 37 71 3 1156682832 45307968 7874336
06:05:58 92482 8192000000 10064521072 10497900544 1082474704 11.671 97 17 34 2 1144904224 59095120 3334400
06:08:33 49623 8192000000 10059720112 10514919424 1106667760 11.901 99 10 20 1 1158481168 49564704 2248704
06:10:23 85927 8192000000 10087941584 10542243840 1107542528 11.909 98 19 39 1 1206464736 94642592 4279616
06:12:49 62760 8192000000 10096817072 10581315584 1122941440 12.055 99 15 30 2 1186245024 60124272 3179312
06:15:18 56168 8192000000 10081073856 10516525056 1127934912 12.102 99 19 38 0 1169564640 37903408 3726320
06:17:16 57865 8192000000 10086297952 10542891008 1121373488 12.040 98 20 40 0 1174361680 49045024 3943168
06:19:14 139749 8192000000 10097265344 10548588544 1136250496 12.180 100 27 54 0 1180458064 38417824 5789744
06:22:34 35229 8192000000 10076145632 10588352512 1141387776 12.229 100 4 8 0 1188651856 46566672 697408
06:24:10 76530 8192000000 10123780048 10578903040 1137960288 12.196 99 13 26 1 1211543872 70810016 2773568
06:26:47 87046 8192000000 10090106080 10538782720 1083294576 11.679 94 18 36 1 1132378048 45297152 3786320
06:29:15 39128 8192000000 10079310288 10507923456 1103468032 11.871 97 39 78 0 1149749712 38390432 7891248
06:30:55 58933 8192000000 10061245424 10493661184 1106768304 11.902 97 24 48 1 1152414640 40412096 5234240
06:32:56 90497 8192000000 10085607440 10560958464 1113033456 11.961 97 15 30 0 1158781488 42689664 3058368
06:35:32 55045 8192000000 10076775280 10536824832 1093152736 11.773 96 13 26 2 1138478784 42854816 2471232
06:37:32 33670 8192000000 10065139664 10509774848 1110912336 11.941 99 24 48 2 1163714512 47708720 5093456
06:39:09 103814 8192000000 10063374608 10526154752 1117943616 12.008 99 45 90 3 1186313984 58832288 9538080
06:41:57 69870 8192000000 10056969184 10504953856 1102990896 11.866 98 6 12 1 1142423504 37919808 1512800
06:45:43 36306 8192000000 10048464752 10484142080 1095140608 11.792 98 21 41 1 1124615904 24925520 4549776
06:47:20 98776 8192000000 10104308352 10535723008 1094293568 11.783 97 23 46 0 1166187424 66751840 5142016
06:49:59 52320 8192000000 10088153760 10558668800 1115612272 11.986 99 20 40 0 1187611824 67530656 4468896
06:51:54 49214 8192000000 10061642224 10494275584 1078141456 11.630 97 22 44 1 1126839632 44306432 4391744
06:54:18 26984 8192000000 10071604720 10494103552 1067913536 11.532 96 45 89 3 1114575968 37734512 8927920
06:55:45 21702 8192000000 10090909104 10532835328 1103402512 11.870 99 34 67 2 1177423344 67426368 6594464
06:58:50 58549 8192000000 10083786144 10546626560 1120865648 12.035 99 7 14 0 1181086448 58866288 1354512
07:00:49 63763 8192000000 10094584928 10581532672 1084663568 11.692 96 15 30 2 1170947920 83168752 3115600
07:02:59 67798 8192000000 10135881856 10594267136 1122438016 12.050 99 20 40 0 1201455008 74670112 4346880
07:05:16 61808 8192000000 10087830784 10536415232 1105368288 11.889 97 20 40 0 1152689984 42894208 4427488
07:07:27 52221 8192000000 10097961728 10570608640 1114531616 11.975 99 6 12 0 1179543040 63846416 1165008
07:09:28 39369 8192000000 10125925712 10551308288 1126842720 12.092 99 37 74 0 1198985120 64293776 7848624
07:11:08 104365 8192000000 10103942160 10604830720 1139405008 12.210 100 11 21 1 1211638912 69937376 2296528
07:14:24 138084 8192000000 10076847952 10553090048 1134381360 12.163 100 14 28 1 1187933744 50699008 2853376
07:17:45 64097 8192000000 10064258592 10516762624 1109589952 11.929 97 17 34 1 1146997760 33291408 4116400
07:19:50 65615 8192000000 10096117392 10554081280 1101400576 11.851 97 8 16 1 1151670384 48857840 1411968
07:21:56 59582 8192000000 10115650128 10550763520 1119201696 12.019 98 22 44 1 1195582688 71414576 4966416
07:24:01 40382 8192000000 10128396400 10565808128 1140448880 12.220 100 25 50 2 1231200400 85197984 5553536
07:25:42 44688 8192000000 10134583504 10578096128 1122825936 12.054 98 20 40 0 1204218064 76925248 4466880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-01-2021 07:28:23 to 02-01-2021 07:28:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2021 07:28:39 to 02-01-2021 07:28:41.

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          221385
MON$OLDEST_ACTIVE               228241
MON$OLDEST_SNAPSHOT             228241
MON$NEXT_TRANSACTION            228241
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                       2067936
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             786
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Sat Jan  2 07:26:55 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Sat Jan  2 07:26:58 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Sat Jan  2 07:27:03 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
--- end of diff output ---

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

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