Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2020 13:29
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16947036 kB
MemAvailable 31544700 kB
Buffers 0 kB
Cached 14757008 kB
SwapCached 3064 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-10-29 13:28:13
DTS_END                         2020-10-29 13:26:48.9730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/55458
TRN_ID                          234654
ATT_ID                          242
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: 8967.53 22091 1076103
customer order: creation 169.52 458 20342
customer order: refuse 14.42 331 1730
order to supplier: creation 44.01 1472 5281
order to supplier: removal 3.03 126894 364
invoice (draft): creation 45.37 3349 5444
invoice (draft): removal 50.16 942 6019
invoice accept: apply 40.13 18122 4816
invoice accept: cancel 45.92 843 5510
customer reserve: creation 3774.49 174 452939
customer reserve: removal 4493.47 69 539216
realization accept: apply 82.47 171 9896
realization accept: cancel 79.15 115 9498
payment from customer: creation 57.70 105 6924
payment from customer: removal 5.70 73 684
payment to supplier: creation 48.07 127 5768
payment to supplier: removal 3.32 52 398
service: total inventory turnovers 4.18 2872 501
service: total monetary turnovers 5.58 603 669
service: aggregate perf. data 0.83 12772 100
service: refresh index statistics 0.03 272268 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-10-2020 13:30:05 to 29-10-2020 13:30:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4820
TEST_TIME 2 5142
TEST_TIME 3 5700
TEST_TIME 4 5792
TEST_TIME 5 5502
TEST_TIME 6 6083
TEST_TIME 7 6266
TEST_TIME 8 6407
TEST_TIME 9 6151
TEST_TIME 10 6411
TEST_TIME 11 6360
TEST_TIME 12 6423
TEST_TIME 13 6754
TEST_TIME 14 6884
TEST_TIME 15 7308
TEST_TIME 16 7711
TEST_TIME 17 7677
TEST_TIME 18 7816
TEST_TIME 19 8188
TEST_TIME 20 8677
TEST_TIME 21 8589
TEST_TIME 22 8381
TEST_TIME 23 9045
TEST_TIME 24 9429
TEST_TIME 25 9535
TEST_TIME 26 9907
TEST_TIME 27 10842
TEST_TIME 28 11340
TEST_TIME 29 11101
TEST_TIME 30 11006
TEST_TIME 31 10664
TEST_TIME 32 10418
TEST_TIME 33 10514
TEST_TIME 34 10373
TEST_TIME 35 10279
TEST_TIME 36 10109
TEST_TIME 37 10010
TEST_TIME 38 10137
TEST_TIME 39 9999
TEST_TIME 40 9899
TEST_TIME 41 9740
TEST_TIME 42 9769
TEST_TIME 43 9662
TEST_TIME 44 9582
TEST_TIME 45 9679
TEST_TIME 46 9952
TEST_TIME 47 9822
TEST_TIME 48 9799
TEST_TIME 49 9867
TEST_TIME 50 9877
TEST_TIME 51 9867
TEST_TIME 52 9911
TEST_TIME 53 9820
TEST_TIME 54 9650
TEST_TIME 55 9582
TEST_TIME 56 9468
TEST_TIME 57 9374
TEST_TIME 58 9285
TEST_TIME 59 9342
TEST_TIME 60 9297
TEST_TIME 61 9373
TEST_TIME 62 9359
TEST_TIME 63 9318
TEST_TIME 64 9286
TEST_TIME 65 9330
TEST_TIME 66 9414
TEST_TIME 67 9328
TEST_TIME 68 9407
TEST_TIME 69 9457
TEST_TIME 70 9402
TEST_TIME 71 9539
TEST_TIME 72 9415
TEST_TIME 73 9311
TEST_TIME 74 9244
TEST_TIME 75 9164
TEST_TIME 76 9110
TEST_TIME 77 9068
TEST_TIME 78 9031
TEST_TIME 79 9117
TEST_TIME 80 9076
TEST_TIME 81 9073
TEST_TIME 82 9173
TEST_TIME 83 9173
TEST_TIME 84 9176
TEST_TIME 85 9217
TEST_TIME 86 9188
TEST_TIME 87 9205
TEST_TIME 88 9121
TEST_TIME 89 9072
TEST_TIME 90 9021
TEST_TIME 91 8972
TEST_TIME 92 8937
TEST_TIME 93 9033
TEST_TIME 94 8987
TEST_TIME 95 8970
TEST_TIME 96 8927
TEST_TIME 97 8996
TEST_TIME 98 8977
TEST_TIME 99 9023
TEST_TIME 100 9092
TEST_TIME 101 9098
TEST_TIME 102 9124
TEST_TIME 103 9112
TEST_TIME 104 9127
TEST_TIME 105 9088
TEST_TIME 106 9017
TEST_TIME 107 9019
TEST_TIME 108 8990
TEST_TIME 109 8938
TEST_TIME 110 8922
TEST_TIME 111 8936
TEST_TIME 112 8922
TEST_TIME 113 8895
TEST_TIME 114 8900
TEST_TIME 115 8958
TEST_TIME 116 8968
TEST_TIME 117 8997
TEST_TIME 118 9001
TEST_TIME 119 9005
TEST_TIME 120 8972

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-10-2020 13:30:09 to 29-10-2020 13:30:12.

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 1494702 1494702 0 0.00 0 16016 6 0 0
doc_list_biud 1494690 1494690 0 0.00 0 64083 31 0 0
sp_add_invoice_to_stock 4912 4816 96 1.95 17 186134 18123 0 96
sp_cancel_adding_invoice 5510 5510 0 0.00 5 72754 843 0 0
sp_cancel_client_order 1730 1730 0 0.00 5 11343 332 0 0
sp_cancel_customer_reserve 539216 539216 0 0.00 1 64091 69 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 2965 73 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 1184 53 0 0
sp_cancel_supplier_invoice 6030 6019 11 0.18 3 23279 943 0 11
sp_cancel_supplier_order 364 364 0 0.00 15 1110676 126895 0 0
sp_cancel_write_off 9498 9498 0 0.00 1 11287 116 0 0
sp_client_order 20342 20342 0 0.00 4 36924 458 0 0
sp_customer_reserve 452939 452939 0 0.00 0 153579 175 0 0
sp_fill_shopping_cart 31050 31050 0 0.00 1 12044 44 0 0
sp_fill_shopping_cart_clo_res 452929 452929 0 0.00 0 24496 19 0 0
sp_get_clo_for_invoice 4803 4803 0 0.00 0 29489 425 0 0
sp_kill_cost_storno 16090 16090 0 0.00 0 9622 52 0 0
sp_kill_qstorno_ret_qs2qd 1094313 1094313 0 0.00 0 64071 38 0 0
sp_kill_qty_storno 577032 577032 0 0.00 0 64081 80 0 0
sp_lock_selected_doc 20339 20339 0 0.00 0 10524 7 0 0
sp_make_cost_storno 27391 27391 0 0.00 0 15904 45 0 0
sp_multiply_rows_for_pdistr 27385 27385 0 0.00 0 9310 30 0 0
sp_multiply_rows_for_qdistr 20342 20342 0 0.00 0 35785 266 0 0
sp_pay_from_customer 6924 6924 0 0.00 4 15206 105 0 0
sp_pay_to_supplier 5768 5768 0 0.00 5 17346 127 0 0
sp_payment_common 12690 12690 0 0.00 1 8404 18 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 7545 279 0 0
sp_qd_handle_on_invoice_upd_sts 10309 10309 0 0.00 0 12057 371 0 0
sp_qd_handle_on_reserve_upd_sts 9896 9896 0 0.00 0 14519 53 0 0
sp_reserve_write_off 9896 9896 0 0.00 7 16123 172 0 0
sp_supplier_invoice 5444 5444 0 0.00 6 87229 3350 0 0
sp_supplier_order 5281 5281 0 0.00 5 89264 1472 0 0
srv_aggregate_perf_data 100 100 0 0.00 2864 125651 12772 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1886 129615 49454 0 0
srv_find_qd_qs_mism 1028989 1028989 0 0.00 0 15558 6 0 0
srv_make_invnt_saldo 501 501 0 0.00 42 84373 2873 0 0
srv_make_money_saldo 669 669 0 0.00 1 14651 604 0 0
srv_recalc_idx_stat 4 4 0 0.00 78219 460171 272268 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4816 4816 0 0.00 0 12963 137 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 2354 82 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 4552 16 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1973 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 556 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 279 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 1094 20 0 0
t$perf-norm:sp_cancel_supplier_order 364 364 0 0.00 0 19452 1471 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 4111 8 0 0
t$perf-norm:sp_client_order 20342 20342 0 0.00 0 5447 15 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 1594 7 0 0
t$perf-norm:sp_pay_from_customer 6924 6924 0 0.00 0 4627 9 0 0
t$perf-norm:sp_pay_to_supplier 5768 5768 0 0.00 0 2642 7 0 0
t$perf-norm:sp_reserve_write_off 9896 9896 0 0.00 0 7882 9 0 0
t$perf-norm:sp_supplier_invoice 5444 5444 0 0.00 0 3640 14 0 0
t$perf-norm:sp_supplier_order 5281 5281 0 0.00 0 4170 12 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 211 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1733 30 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 3237 16 0 0
t$perf-norm:srv_make_money_saldo 669 669 0 0.00 0 2923 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9786 9594 192 1.96 0 13114 23 0 192
v_all_customers 36828 36828 0 0.00 0 21 1 0 0
v_all_suppliers 29762 29762 0 0.00 0 20 1 0 0
v_all_wares 40684 40684 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 4752 13 0 0
v_cancel_client_order 3460 3460 0 0.00 0 3155 9 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 2675 9 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 1292 7 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 635 3 0 0
v_cancel_supplier_invoice 744 722 22 2.95 0 1250 15 0 22
v_cancel_supplier_order 728 728 0 0.00 0 1247 6 0 0
v_cancel_write_off 2066 2066 0 0.00 0 6239 16 0 0
v_max_id_clo_ord 5278 5278 0 0.00 0 1521 1 0 0
v_max_id_clo_res 946 946 0 0.00 0 4464 27 0 0
v_max_id_ord_sup 5430 5430 0 0.00 0 7114 9 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 311 2 0 0
v_max_non_paid_realizn 3855 3855 0 0.00 0 4385 4 0 0
v_min_id_clo_ord 5278 5278 0 0.00 0 4476 7 0 0
v_min_id_clo_res 946 946 0 0.00 0 3424 13 0 0
v_min_id_ord_sup 5430 5430 0 0.00 0 9154 15 0 0
v_min_non_paid_invoice 5766 5766 0 0.00 0 5734 11 0 0
v_min_non_paid_realizn 6924 6924 0 0.00 0 5355 10 0 0
v_our_firm 9994 9994 0 0.00 0 22 1 0 0
v_reserve_write_off 19788 19788 0 0.00 0 6382 7 0 0
x_lock_depdocs_on_canc_invoice 11168 11168 0 0.00 0 18616 97 0 0
x_lock_depdocs_on_canc_sup_ord 364 364 0 0.00 0 7895 318 0 0
x_make_qstorno_1000_1200 5281 5281 0 0.00 2 89224 1395 0 0
x_make_qstorno_1200_2000 5444 5444 0 0.00 3 85070 3181 0 0
x_make_qstorno_2100_3300 452938 452938 0 0.00 0 153578 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2020 13:30:13 to 29-10-2020 13:30:17.

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:52 2 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
10:57:53 30913 8192000000 9736512048 10035724288 813146464 9.029 100 88 177 0 842364672 7978976 21239232
10:59:30 21437 8192000000 9768896944 10077716480 844885840 9.349 100 89 178 0 878368048 12643152 20839056
11:00:58 12109 8192000000 9816569504 10136317952 881854416 9.718 100 87 175 1 924199808 22415824 19929568
11:02:15 13483 8192000000 9865339056 10203222016 922542640 10.121 100 71 142 1 973207440 35513360 15151440
11:03:33 9039 8192000000 9924151840 10267516928 958519040 10.475 100 87 174 0 1036084064 58912304 18652720
11:04:46 4324 8192000000 9976937680 10333147136 989011776 10.772 100 84 168 2 1088903360 83180528 16711056
11:05:52 2149 8192000000 9988542752 10349391872 1016399520 11.037 100 89 174 1 1104996416 71385328 17211568
11:06:55 2098 8192000000 10017906640 10385977344 1048914976 11.350 100 88 176 0 1132969424 65970672 18083776
11:07:59 2021 8192000000 10051438432 10433159168 1065687600 11.511 100 69 138 1 1165690192 85661920 14340672
11:09:02 60898 8192000000 9990748592 10383937536 1071967248 11.571 100 44 88 1 1104579392 22927760 9684384
11:11:03 7115 8192000000 10000123792 10400694272 1081618720 11.663 100 43 86 2 1136340256 45922096 8799440
11:12:12 5770 8192000000 9995928176 10396667904 1085162080 11.697 100 32 65 2 1120058592 27435168 7461344
11:13:20 12922 8192000000 10001883312 10415927296 1091615504 11.758 100 23 46 1 1136798656 40107664 5075488
11:14:34 57113 8192000000 10005463824 10408747008 1082977616 11.676 99 35 69 1 1105994000 15507504 7508880
11:16:33 6904 8192000000 10006736496 10405183488 1084227216 11.688 100 49 97 4 1115059456 20867680 9964560
11:17:41 7147 8192000000 9968079776 10355474432 1033344944 11.201 98 49 97 3 1063529872 18355856 11829072
11:18:49 12658 8192000000 9953485200 10355195904 1004681920 10.924 96 22 43 3 1026631152 17248752 4700480
11:20:02 27151 8192000000 9934382144 10303422464 956813936 10.458 93 47 94 1 984423824 17628608 9981280
11:21:30 4406 8192000000 9927727536 10301022208 1002717552 10.905 98 56 114 2 1021267920 6045792 12504576
11:22:36 12461 8192000000 9953046576 10350690304 1025025840 11.121 100 33 66 0 1070025472 36960720 8038912
11:23:50 10760 8192000000 9959788208 10352971776 1051816256 11.378 100 29 57 2 1075047344 16607504 6623584
11:25:03 23901 8192000000 9973872832 10353561600 1057987264 11.437 100 61 120 3 1083941424 12946208 13007952
11:26:42 7354 8192000000 9992894000 10390675456 1082715616 11.673 100 37 73 4 1119315200 28108896 8490688
11:27:59 22617 8192000000 10041669776 10437459968 1103195472 11.868 100 44 86 1 1143702240 31373056 9133712
11:29:26 11562 8192000000 10111935280 10513526784 1122607600 12.052 100 53 106 1 1222903088 89575792 10719696
11:30:45 7359 8192000000 10107389680 10511319040 1132370448 12.144 100 44 84 3 1216358064 74524400 9463216
11:31:56 7845 8192000000 10147337680 10559557632 1144222480 12.255 100 51 102 0 1261701152 106697184 10781488
11:33:07 22379 8192000000 10176096240 10587480064 1143582448 12.249 100 57 114 2 1272033888 116067248 12384192
11:34:35 18304 8192000000 10150811968 10583601152 1151751584 12.326 100 25 50 1 1262424848 105159792 5513472
11:36:13 2741 8192000000 10200783104 10616610816 1164419440 12.445 100 65 130 1 1312426448 134313312 13693696
11:37:31 4172 8192000000 10221458832 10626519040 1157219776 12.377 100 52 103 1 1326684400 158368224 11096400
11:38:40 2752 8192000000 10288798496 10682826752 1155305392 12.359 99 81 160 0 1383252832 209390960 18556480
11:39:47 14144 8192000000 10310638512 10715447296 1161817424 12.420 100 68 135 0 1408924880 232176272 14931184
11:41:08 7302 8192000000 10351779136 10745991168 1148804112 12.298 100 81 161 1 1449404640 282791696 17808832
11:42:18 1926 8192000000 10361554368 10755710976 1154073648 12.348 100 91 179 2 1458631520 284803536 19754336
11:43:26 1885 8192000000 10387887776 10777735168 1148475648 12.295 99 86 170 1 1473069312 305461264 19132400
11:44:32 5066 8192000000 10370619456 10771763200 1126729392 12.091 100 59 117 1 1470961648 329697616 14534640
11:45:39 5238 8192000000 10411703664 10837889024 1141597504 12.231 100 47 92 2 1512386224 359617456 11171264
11:46:50 10520 8192000000 10373543920 10794422272 1125128544 12.075 100 40 80 2 1477880000 342858976 9892480
11:48:02 12253 8192000000 10437212464 10854494208 1133419504 12.154 100 33 66 0 1527018576 384626048 8973024
11:49:19 13444 8192000000 10424119632 10890280960 1141460800 12.229 100 36 72 2 1553578224 402411456 9705968
11:51:07 19119 8192000000 10348903984 10805665792 1138631120 12.203 100 26 52 1 1471778208 325989568 7157520
11:52:36 13280 8192000000 10373173232 10850062336 1136484016 12.182 100 20 40 0 1477807520 335572560 5750944
11:53:51 84451 8192000000 10103043520 10593370112 1117389872 12.002 99 5 10 0 1170472496 51908352 1174272
11:57:12 74736 8192000000 10087449936 10516697088 1106352384 11.898 97 32 64 0 1160272304 47511568 6408352
11:59:40 8998 8192000000 10100594320 10539212800 1133307504 12.153 100 38 77 1 1199690736 59234064 7149168
12:00:55 125336 8192000000 10137175904 10612826112 1131519280 12.136 99 14 28 0 1191641104 57187696 2934128
12:04:01 53570 8192000000 10111629136 10617671680 1114297136 11.973 98 33 66 3 1232078320 110117456 7663728
12:06:04 63629 8192000000 10103112496 10568339456 1127386768 12.097 99 17 34 0 1194398880 63586432 3425680
12:08:08 53475 8192000000 10112316176 10571374592 1137390944 12.191 99 17 34 0 1220492624 79180384 3921296
12:10:02 74074 8192000000 10157134080 10617036800 1106138688 11.896 96 19 38 0 1225115328 114432080 4544560
12:12:26 114082 8192000000 10121740240 10566184960 1116365632 11.993 98 13 26 2 1195294368 76267376 2661360
12:15:25 67550 8192000000 10092124944 10562793472 1118763488 12.015 98 10 20 0 1195464608 74593584 2107536
12:17:48 30364 8192000000 10099250320 10523189248 1118991232 12.017 98 31 61 3 1163782288 38559408 6231648
12:19:29 129615 8192000000 10078223392 10512936960 1126585648 12.089 99 31 62 1 1156147920 22493216 7069056
12:22:39 55961 8192000000 10105390176 10559561728 1133604240 12.155 99 13 26 0 1197534992 61129280 2801472
12:24:48 78406 8192000000 10061719776 10566582272 1094827280 11.789 96 11 22 0 1159570016 62414384 2328352
12:27:08 81340 8192000000 10065728896 10516377600 1108711152 11.920 98 18 36 0 1158372288 45401872 4259264
12:29:30 30990 8192000000 10078092448 10513567744 1105554336 11.890 98 24 48 1 1156534064 45900896 5078832
12:31:04 119812 8192000000 10083486416 10566483968 1081194944 11.659 95 14 28 1 1142222896 57917616 3110336
12:34:04 52503 8192000000 10082711728 10560757760 1086255824 11.707 96 23 46 1 1175015248 83825408 4934016
12:35:57 46125 8192000000 10098180784 10500333568 1086415856 11.709 97 45 90 1 1141803712 45965440 9422416
12:37:45 106718 8192000000 10016815376 10490728448 1059404448 11.451 96 8 16 0 1110335200 49501904 1428848
12:40:32 71283 8192000000 10034622000 10473775104 1098293584 11.821 100 10 20 1 1136821824 36550608 1977632
12:42:43 55893 8192000000 10057616208 10486415360 1085491952 11.700 98 14 28 0 1147633264 59252000 2889312
12:44:44 74842 8192000000 10077663872 10571395072 1117575712 12.004 100 14 28 1 1189408336 69050832 2781792
12:47:04 35191 8192000000 10149732560 10579562496 1117219264 12.001 99 33 66 1 1205955360 81790016 6946080
12:48:46 70081 8192000000 10071023920 10485010432 1119985984 12.027 99 30 57 3 1177166224 51107744 6072496
12:50:57 50439 8192000000 10113641120 10541412352 1104135568 11.877 98 31 62 0 1169175872 58802640 6237664
12:52:50 28201 8192000000 10073617424 10511978496 1101012512 11.847 98 39 78 2 1142188800 33360656 7815632
12:54:18 87114 8192000000 10055649568 10513321984 1117871440 12.007 100 22 44 0 1173101632 51018336 4211856
12:56:51 64787 8192000000 10044555488 10491609088 1102221696 11.859 98 3 6 0 1116693440 14150048 321696
12:58:56 80415 8192000000 10061615712 10587131904 1124341824 12.068 99 9 18 0 1212924656 86608544 1974288
13:01:18 52763 8192000000 10119370880 10548535296 1135517216 12.173 100 29 58 0 1196941424 55315840 6108368
13:03:16 123926 8192000000 10186278944 10660143104 1152378192 12.332 100 18 36 1 1281110720 124858512 3874016
13:06:32 67384 8192000000 10081560208 10536521728 1113896128 11.969 97 20 39 0 1162070976 44409024 3765824
13:08:57 103610 8192000000 10103794448 10590617600 1117912256 12.007 98 8 16 0 1165807104 46270896 1623952
13:11:42 120898 8192000000 10091627552 10571132928 1138882032 12.205 99 15 30 1 1199705840 57625056 3198752
13:14:56 59807 8192000000 10072534512 10524991488 1058824912 11.445 93 8 16 0 1104898576 44182464 1891200
13:17:09 34423 8192000000 10066702400 10501623808 1115248016 11.982 99 25 50 1 1168682768 47813264 5621488
13:18:47 52842 8192000000 10159812528 10600083456 1114450848 11.975 98 27 54 5 1195325904 75194624 5680432
13:21:18 83945 8192000000 10078308944 10523402240 1057749376 11.435 94 14 25 3 1128584128 68210544 2624208
13:23:45 47649 8192000000 10061261360 10518769664 1098674784 11.825 98 12 24 0 1155489584 54329216 2485584
13:25:33 37599 8192000000 10098203808 10516160512 1076193440 11.611 97 34 68 1 1158666112 75980960 6491712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2020 13:30:19 to 29-10-2020 13:30:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 96 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2020 13:30:35 to 29-10-2020 13:30:37.

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          240016
MON$OLDEST_ACTIVE               240017
MON$OLDEST_SNAPSHOT             240017
MON$NEXT_TRANSACTION            240017
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                       2094208
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             785
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2241 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2241 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2241 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.2241 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

29.10.20 13:30:38. 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-10-29 13:30:40 No severe PSQL-related problems occured
29.10.20 13:30:42. End of report.