Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2020 13:28
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 16778184 kB
MemAvailable 31537196 kB
Buffers 0 kB
Cached 14864544 kB
SwapCached 5152 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-21 13:27:07
DTS_END                         2020-10-21 13:26:53.6750
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/47236
TRN_ID                          232405
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: 8962.72 24434 1075526
customer order: creation 161.93 391 19431
customer order: refuse 14.23 297 1708
order to supplier: creation 43.40 1282 5208
order to supplier: removal 3.33 111176 399
invoice (draft): creation 43.32 3611 5198
invoice (draft): removal 52.11 848 6253
invoice accept: apply 39.63 18233 4756
invoice accept: cancel 47.47 940 5696
customer reserve: creation 3601.68 180 432201
customer reserve: removal 4662.61 63 559513
realization accept: apply 77.83 190 9340
realization accept: cancel 93.43 109 11212
payment from customer: creation 56.69 116 6803
payment from customer: removal 5.04 114 605
payment to supplier: creation 45.98 127 5517
payment to supplier: removal 3.25 99 390
service: total inventory turnovers 3.64 3585 437
service: total monetary turnovers 6.32 516 758
service: aggregate perf. data 0.81 20032 97
service: refresh index statistics 0.03 326777 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2020 13:28:21 to 21-10-2020 13:28:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4999
TEST_TIME 2 3672
TEST_TIME 3 3141
TEST_TIME 4 4216
TEST_TIME 5 4236
TEST_TIME 6 4526
TEST_TIME 7 4986
TEST_TIME 8 5708
TEST_TIME 9 5701
TEST_TIME 10 6150
TEST_TIME 11 6219
TEST_TIME 12 6733
TEST_TIME 13 6804
TEST_TIME 14 6704
TEST_TIME 15 6976
TEST_TIME 16 7352
TEST_TIME 17 7411
TEST_TIME 18 7699
TEST_TIME 19 7765
TEST_TIME 20 8114
TEST_TIME 21 8329
TEST_TIME 22 8729
TEST_TIME 23 8858
TEST_TIME 24 9275
TEST_TIME 25 9440
TEST_TIME 26 9885
TEST_TIME 27 9958
TEST_TIME 28 10156
TEST_TIME 29 10219
TEST_TIME 30 10351
TEST_TIME 31 10721
TEST_TIME 32 10737
TEST_TIME 33 10505
TEST_TIME 34 10214
TEST_TIME 35 10097
TEST_TIME 36 10127
TEST_TIME 37 10026
TEST_TIME 38 9963
TEST_TIME 39 9956
TEST_TIME 40 9838
TEST_TIME 41 9846
TEST_TIME 42 9703
TEST_TIME 43 9643
TEST_TIME 44 9579
TEST_TIME 45 9417
TEST_TIME 46 9309
TEST_TIME 47 9328
TEST_TIME 48 9219
TEST_TIME 49 9102
TEST_TIME 50 9019
TEST_TIME 51 8933
TEST_TIME 52 8993
TEST_TIME 53 9054
TEST_TIME 54 9396
TEST_TIME 55 9377
TEST_TIME 56 9437
TEST_TIME 57 9462
TEST_TIME 58 9517
TEST_TIME 59 9462
TEST_TIME 60 9549
TEST_TIME 61 9534
TEST_TIME 62 9567
TEST_TIME 63 9438
TEST_TIME 64 9384
TEST_TIME 65 9269
TEST_TIME 66 9278
TEST_TIME 67 9241
TEST_TIME 68 9192
TEST_TIME 69 9250
TEST_TIME 70 9261
TEST_TIME 71 9211
TEST_TIME 72 9173
TEST_TIME 73 9256
TEST_TIME 74 9213
TEST_TIME 75 9264
TEST_TIME 76 9259
TEST_TIME 77 9331
TEST_TIME 78 9308
TEST_TIME 79 9443
TEST_TIME 80 9376
TEST_TIME 81 9273
TEST_TIME 82 9191
TEST_TIME 83 9139
TEST_TIME 84 9083
TEST_TIME 85 9040
TEST_TIME 86 8999
TEST_TIME 87 8988
TEST_TIME 88 9055
TEST_TIME 89 9057
TEST_TIME 90 9040
TEST_TIME 91 9002
TEST_TIME 92 9006
TEST_TIME 93 9030
TEST_TIME 94 9132
TEST_TIME 95 9178
TEST_TIME 96 9199
TEST_TIME 97 9206
TEST_TIME 98 9160
TEST_TIME 99 9159
TEST_TIME 100 9109
TEST_TIME 101 9073
TEST_TIME 102 9012
TEST_TIME 103 8960
TEST_TIME 104 8944
TEST_TIME 105 8923
TEST_TIME 106 8992
TEST_TIME 107 9004
TEST_TIME 108 8995
TEST_TIME 109 8996
TEST_TIME 110 8973
TEST_TIME 111 9005
TEST_TIME 112 9005
TEST_TIME 113 8991
TEST_TIME 114 9036
TEST_TIME 115 9084
TEST_TIME 116 9090
TEST_TIME 117 9028
TEST_TIME 118 8993
TEST_TIME 119 8971
TEST_TIME 120 8964

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2020 13:28:25 to 21-10-2020 13:28:28.

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 1476848 1476848 0 0.00 0 23934 7 0 0
doc_list_biud 1476845 1476845 0 0.00 0 52665 30 0 0
sp_add_invoice_to_stock 4882 4756 126 2.58 40 189276 18234 0 126
sp_cancel_adding_invoice 5696 5696 0 0.00 5 110420 940 0 0
sp_cancel_client_order 1708 1708 0 0.00 4 7232 298 0 0
sp_cancel_customer_reserve 559513 559513 0 0.00 1 31261 63 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 3 12630 115 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 6177 99 0 0
sp_cancel_supplier_invoice 6268 6253 15 0.23 3 23559 848 0 15
sp_cancel_supplier_order 399 399 0 0.00 11 941094 111176 0 0
sp_cancel_write_off 11212 11212 0 0.00 1 13651 109 0 0
sp_client_order 19431 19431 0 0.00 4 26331 392 0 0
sp_customer_reserve 432201 432201 0 0.00 0 48697 181 0 0
sp_fill_shopping_cart 29818 29818 0 0.00 1 31940 43 0 0
sp_fill_shopping_cart_clo_res 432199 432199 0 0.00 0 29765 17 0 0
sp_get_clo_for_invoice 4754 4754 0 0.00 0 54288 431 0 0
sp_kill_cost_storno 17903 17903 0 0.00 0 23177 57 0 0
sp_kill_qstorno_ret_qs2qd 1136890 1136890 0 0.00 0 31255 34 0 0
sp_kill_qty_storno 598873 598873 0 0.00 0 52665 74 0 0
sp_lock_selected_doc 19587 19587 0 0.00 0 7282 7 0 0
sp_make_cost_storno 26413 26413 0 0.00 0 17723 67 0 0
sp_multiply_rows_for_pdistr 26412 26412 0 0.00 0 13710 30 0 0
sp_multiply_rows_for_qdistr 19431 19431 0 0.00 0 26318 214 0 0
sp_pay_from_customer 6803 6803 0 0.00 4 14318 117 0 0
sp_pay_to_supplier 5517 5517 0 0.00 5 14440 127 0 0
sp_payment_common 12320 12320 0 0.00 1 13443 28 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 0 7218 270 0 0
sp_qd_handle_on_invoice_upd_sts 10448 10448 0 0.00 0 52665 410 0 0
sp_qd_handle_on_reserve_upd_sts 9340 9340 0 0.00 0 17913 51 0 0
sp_reserve_write_off 9340 9340 0 0.00 6 18292 190 0 0
sp_supplier_invoice 5198 5198 0 0.00 8 119058 3611 0 0
sp_supplier_order 5208 5208 0 0.00 5 28363 1282 0 0
srv_aggregate_perf_data 97 97 0 0.00 2568 282868 20032 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1185 167161 47001 0 0
srv_find_qd_qs_mism 1031212 1031212 0 0.00 0 9195 5 0 0
srv_make_invnt_saldo 437 437 0 0.00 73 77440 3585 0 0
srv_make_money_saldo 758 758 0 0.00 1 11784 517 0 0
srv_recalc_idx_stat 4 4 0 0.00 60152 489466 326777 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4756 4756 0 0.00 0 7127 161 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 2293 76 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 5711 12 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 1653 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 7715 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 209 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 787 16 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 22180 1557 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 2510 7 0 0
t$perf-norm:sp_client_order 19431 19431 0 0.00 0 8160 17 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 2689 6 0 0
t$perf-norm:sp_pay_from_customer 6803 6803 0 0.00 0 8127 9 0 0
t$perf-norm:sp_pay_to_supplier 5517 5517 0 0.00 0 3010 7 0 0
t$perf-norm:sp_reserve_write_off 9340 9340 0 0.00 0 8126 12 0 0
t$perf-norm:sp_supplier_invoice 5198 5198 0 0.00 0 2077 15 0 0
t$perf-norm:sp_supplier_order 5208 5208 0 0.00 0 8598 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 167 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 175 5 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 1499 10 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 423 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9756 9504 252 2.58 0 5920 17 0 252
v_all_customers 35482 35482 0 0.00 0 67 1 0 0
v_all_suppliers 28716 28716 0 0.00 0 22 1 0 0
v_all_wares 38856 38856 0 0.00 0 106 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 4800 14 0 0
v_cancel_client_order 3416 3416 0 0.00 0 3361 4 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 3076 9 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 5157 9 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 6132 16 0 0
v_cancel_supplier_invoice 812 782 30 3.69 0 2889 14 0 30
v_cancel_supplier_order 798 798 0 0.00 0 2184 13 0 0
v_cancel_write_off 1906 1906 0 0.00 0 2281 14 0 0
v_max_id_clo_ord 5205 5205 0 0.00 0 682 1 0 0
v_max_id_clo_res 941 941 0 0.00 0 3254 20 0 0
v_max_id_ord_sup 5185 5185 0 0.00 0 4770 5 0 0
v_max_non_paid_invoice 1549 1549 0 0.00 0 2382 6 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 1572 4 0 0
v_min_id_clo_ord 5205 5205 0 0.00 0 6229 12 0 0
v_min_id_clo_res 941 941 0 0.00 0 4964 12 0 0
v_min_id_ord_sup 5185 5185 0 0.00 0 2085 5 0 0
v_min_non_paid_invoice 5517 5517 0 0.00 0 7668 16 0 0
v_min_non_paid_realizn 6803 6803 0 0.00 0 13439 24 0 0
v_our_firm 9898 9898 0 0.00 0 11 1 0 0
v_reserve_write_off 18680 18680 0 0.00 0 9025 7 0 0
x_lock_depdocs_on_canc_invoice 11558 11558 0 0.00 0 13964 108 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 15342 366 0 0
x_make_qstorno_1000_1200 5208 5208 0 0.00 2 28353 1200 0 0
x_make_qstorno_1200_2000 5198 5198 0 0.00 3 118877 3452 0 0
x_make_qstorno_2100_3300 432201 432201 0 0.00 0 45854 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2020 13:28:29 to 21-10-2020 13:28:33.

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 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
10:57:58 22693 8192000000 9720340160 10016354304 792837408 8.824 100 80 160 0 819993968 7272288 19884272
10:59:27 21241 8192000000 9774349280 10084077568 850311648 9.403 100 82 164 0 881845264 11641536 19892080
11:00:53 15160 8192000000 9810465360 10129608704 879955600 9.699 100 86 172 0 920048560 20368224 19724736
11:02:12 14870 8192000000 9880113920 10210353152 919554000 10.092 100 84 168 1 982247792 44154400 18539392
11:03:30 5450 8192000000 9909914672 10257047552 957585248 10.465 100 76 153 1 1035747840 61639152 16523440
11:04:38 5670 8192000000 9946881232 10299424768 991586192 10.797 100 78 156 1 1053544144 46910432 15047520
11:05:47 3621 8192000000 9986172928 10349539328 1024922032 11.120 100 86 170 1 1101327056 59597280 16807744
11:06:52 1350 8192000000 10016590272 10381836288 1053520016 11.394 100 92 182 1 1141268448 68694112 19054320
11:07:56 2023 8192000000 10034832960 10409152512 1060039472 11.457 100 80 159 1 1153118176 76722288 16356416
11:09:00 4750 8192000000 9980148560 10374078464 1063940832 11.494 100 36 71 0 1101893120 30700544 7251744
11:10:05 9043 8192000000 10002290272 10404380672 1073802144 11.588 100 41 81 0 1119236560 37643152 7791264
11:11:14 7344 8192000000 9983534176 10387542016 1078680624 11.635 100 34 67 1 1101732960 16023536 7028800
11:12:27 65265 8192000000 10016842432 10414579712 1087583888 11.720 100 37 73 2 1107014864 11958016 7472960
11:14:34 6794 8192000000 10028080688 10433150976 1084920592 11.694 99 35 68 2 1129116704 37077168 7118944
11:15:43 6418 8192000000 10007098240 10409467904 1078881136 11.637 99 40 80 0 1108733840 21466752 8385952
11:16:51 7907 8192000000 9985272464 10377015296 1052948064 11.389 98 38 75 2 1090769152 29607920 8213168
11:18:05 42412 8192000000 9940429488 10318598144 984509680 10.728 92 35 70 1 1007052112 14673408 7869024
11:19:49 3831 8192000000 9938235248 10313334784 1015520256 11.029 99 47 93 1 1037326304 11444384 10361664
11:20:54 9016 8192000000 9925527280 10303082496 989834096 10.780 96 38 74 1 1021195216 23029312 8331808
11:22:05 6448 8192000000 9949482384 10328449024 1034902640 11.216 100 40 77 3 1059170048 15820112 8447296
11:23:13 31742 8192000000 9971887152 10372628480 1034296064 11.210 99 24 45 2 1071772240 33010672 4465504
11:24:45 7297 8192000000 9976982640 10372509696 1059981040 11.456 100 28 56 2 1093547712 27283936 6282736
11:25:53 6245 8192000000 9984133648 10383003648 1065962896 11.514 100 44 84 3 1094704240 19339984 9401360
11:27:02 40819 8192000000 10004279616 10408132608 1089670496 11.740 100 34 69 3 1111373888 13374512 8328880
11:28:46 39120 8192000000 10021026016 10421612544 1103574496 11.872 100 30 58 2 1142161168 33024944 5561728
11:30:49 35675 8192000000 10082082992 10482434048 1122198144 12.048 100 43 84 2 1166776880 35600960 8977776
11:32:33 5538 8192000000 10149265968 10551578624 1141549872 12.230 100 73 143 2 1256406480 99974096 14882512
11:33:42 3404 8192000000 10187316528 10582208512 1148349600 12.294 100 72 143 3 1286760992 123361696 15049696
11:34:47 5096 8192000000 10167451072 10581426176 1148587184 12.296 100 59 118 1 1281894192 120820144 12486864
11:35:56 1569 8192000000 10213787808 10607665152 1153347152 12.341 100 74 149 0 1316440912 147619088 15474672
11:37:01 9209 8192000000 10223741024 10633306112 1153149536 12.339 100 60 120 1 1330562320 163974352 13438432
11:38:15 1184 8192000000 10228770288 10641678336 1150934512 12.318 100 80 160 2 1336185488 166710752 18540224
11:39:24 19012 8192000000 10202302560 10637115392 1132992784 12.150 99 36 70 1 1303800128 162624720 8182624
11:41:00 2176 8192000000 10233657424 10641121280 1127427024 12.097 99 49 97 0 1332971104 193387472 12156608
11:42:06 3128 8192000000 10243918512 10655756288 1144753024 12.260 100 64 126 3 1344001696 184922144 14326528
11:43:13 2254 8192000000 10272801344 10665398272 1125222656 12.076 99 79 158 0 1352640224 209544464 17873104
11:44:20 1600 8192000000 10304227984 10696228864 1133972976 12.159 100 89 176 2 1401910944 249075600 18862368
11:45:29 2167 8192000000 10305689056 10688573440 1110523616 11.937 100 87 174 0 1398429152 267499344 20406192
11:46:35 2584 8192000000 10330076320 10744033280 1114642064 11.976 100 74 146 1 1428191024 295533072 18015888
11:47:43 6592 8192000000 10303074112 10711564288 1120465088 12.031 100 48 95 1 1404442496 272509104 11468304
11:48:58 10473 8192000000 10276372976 10694987776 1108153408 11.915 98 37 74 0 1362220432 244359312 9707712
11:50:15 18243 8192000000 10258654640 10694090752 1116646864 11.995 100 31 60 0 1364155856 239510336 7998656
11:51:38 6400 8192000000 10248985056 10678968320 1115713808 11.986 99 44 88 1 1346564576 220229712 10621056
11:52:46 22014 8192000000 10183615552 10607382528 1090733360 11.750 98 17 34 0 1261134880 166150656 4250864
11:54:09 30770 8192000000 10117292224 10575654912 1105689312 11.892 99 17 34 1 1264895616 155152400 4053904
11:56:08 35306 8192000000 10162693440 10653306880 1133131856 12.151 100 16 32 2 1266582400 130036640 3413904
11:58:05 48164 8192000000 10078849712 10552975360 1141527840 12.230 100 12 24 1 1192465552 48465808 2471904
11:59:57 149437 8192000000 10151340064 10615005184 1149655248 12.306 100 41 82 1 1247364576 89277712 8431616
12:03:27 49901 8192000000 10101539440 10547834880 1127992208 12.102 98 22 44 2 1191418624 58426208 5000208
12:05:29 64500 8192000000 10111050880 10551398400 1151892784 12.327 100 19 38 0 1211334480 54952912 4488784
12:07:36 55575 8192000000 10060323552 10506723328 1135325616 12.172 99 12 24 1 1167634048 29724960 2583472
12:09:43 35814 8192000000 10086954880 10526932992 1145499488 12.267 100 17 34 1 1181928032 33042384 3386160
12:11:27 89188 8192000000 10114264224 10582159360 1150655104 12.316 100 20 40 2 1219084576 63910720 4518752
12:13:57 19828 8192000000 10107633008 10565943296 1155546960 12.362 100 12 23 1 1201295888 42693808 3055120
12:15:24 51345 8192000000 10202600784 10653593600 1171988080 12.515 100 25 49 1 1285579360 107791328 5799952
12:18:26 53830 8192000000 10222150192 10689646592 1161397040 12.416 99 15 30 2 1339557584 174658704 3501840
12:20:22 78241 8192000000 10115295248 10547310592 1118682992 12.015 96 24 48 1 1183206208 59584768 4938448
12:22:40 46446 8192000000 10113178256 10581733376 1160730240 12.410 100 15 30 2 1229879248 65995040 3153968
12:24:30 32399 8192000000 10071292000 10501386240 1099515296 11.833 95 29 57 2 1126020592 20768992 5736304
12:26:04 32753 8192000000 10127155712 10577043456 1138404624 12.201 99 57 114 0 1218406928 68272000 11730304
12:27:40 98501 8192000000 10104756416 10597404672 1127319040 12.096 98 13 26 2 1178922400 48943552 2659808
12:30:55 65358 8192000000 10105087568 10571841536 1113637360 11.967 97 7 14 0 1193863456 78951120 1274976
12:34:38 52783 8192000000 10065243440 10542174208 1128711760 12.109 100 25 50 2 1214086032 80401728 4972544
12:36:33 56345 8192000000 10075688688 10520817664 1078886992 11.637 94 15 29 0 1130832256 48500528 3444736
12:38:34 45142 8192000000 10103568224 10516271104 1056928352 11.427 94 25 50 0 1126418832 64249232 5241248
12:40:41 66262 8192000000 10054355568 10542972928 1074888512 11.599 97 22 44 0 1146282224 66656688 4737024
12:42:52 53758 8192000000 10087308800 10551042048 1075867568 11.608 97 24 48 2 1129663760 48716480 5079712
12:44:50 167161 8192000000 10026874928 10473832448 1056222288 11.420 95 17 33 1 1105380464 45299104 3859072
12:48:40 70290 8192000000 10060407728 10534612992 1110254160 11.935 100 10 20 0 1157384928 45008448 2122320
12:50:50 66018 8192000000 10088140144 10525392896 1102582096 11.862 99 17 33 2 1149374448 43359376 3432976
12:53:04 56655 8192000000 10136114288 10572038144 1114594912 11.976 99 24 48 0 1189345120 69437440 5312768
12:55:09 60810 8192000000 10085919024 10558722048 1090033728 11.743 98 17 34 0 1155236640 61641440 3561472
12:57:10 50320 8192000000 10113870576 10542747648 1126681552 12.090 100 21 42 1 1199045360 68349184 4014624
12:59:04 60554 8192000000 10135444896 10562891776 1126696352 12.090 99 22 44 0 1180746992 49959264 4091376
13:01:11 130244 8192000000 10079009040 10528051200 1107469264 11.908 98 6 12 0 1159756448 51119120 1168064
13:04:22 53722 8192000000 10136236320 10561835008 1119204960 12.019 99 34 67 0 1183852528 56788208 7859360
13:06:16 84763 8192000000 10058334240 10547851264 1115688240 11.986 99 17 33 0 1164743152 45309520 3745392
13:08:43 39936 8192000000 10077249472 10546110464 1121530000 12.041 99 7 14 0 1176125344 53075600 1519744
13:10:24 99709 8192000000 10107407664 10576703488 1131026752 12.131 100 11 22 1 1214673616 81053040 2593824
13:13:13 80216 8192000000 10079338944 10518900736 1094066144 11.781 96 19 39 0 1137795776 39826208 3903424
13:15:55 58143 8192000000 10104292000 10530799616 1120411376 12.031 99 36 71 2 1188142912 60435216 7296320
13:18:00 61655 8192000000 10104206704 10530627584 1105259408 11.888 97 17 34 0 1155705392 46788320 3657664
13:20:04 83324 8192000000 10137209824 10654404608 1130603680 12.127 99 15 30 0 1223577984 90092304 2882000
13:22:39 86382 8192000000 10055429840 10526121984 1122695456 12.052 100 7 14 0 1160736304 36788944 1251904
13:25:06 53980 8192000000 10053320032 10508349440 1103104880 11.867 98 9 18 1 1144234752 39388960 1740912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-10-2020 13:28:35 to 21-10-2020 13:28:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 126 v_add_invoice_to_stock 335544517
user_exc 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2020 13:28:50 to 21-10-2020 13:28:52.

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          237376
MON$OLDEST_ACTIVE               237377
MON$OLDEST_SNAPSHOT             237377
MON$NEXT_TRANSACTION            237377
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                       2115584
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             772
MON$NEXT_STATEMENT              53


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

21.10.20 13:28:53. 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-21 13:28:55 No severe PSQL-related problems occured
21.10.20 13:28:57. End of report.