Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.01.2021 10: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 16548776 kB
MemAvailable 31250016 kB
Buffers 0 kB
Cached 15097068 kB
SwapCached 4868 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-26 10:26:54
DTS_END                         2021-01-26 10:26:54.4870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39620
TRN_ID                          232378
ATT_ID                          614
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: 9020.73 25336 1082488
customer order: creation 167.92 422 20150
customer order: refuse 14.35 314 1722
order to supplier: creation 44.43 1446 5331
order to supplier: removal 3.48 119192 417
invoice (draft): creation 45.87 3316 5504
invoice (draft): removal 54.17 1081 6500
invoice accept: apply 39.83 16221 4779
invoice accept: cancel 48.13 828 5775
customer reserve: creation 3610.80 161 433296
customer reserve: removal 4696.43 68 563572
realization accept: apply 80.88 182 9705
realization accept: cancel 87.38 114 10485
payment from customer: creation 58.32 118 6998
payment from customer: removal 5.70 81 684
payment to supplier: creation 48.43 134 5811
payment to supplier: removal 3.38 82 406
service: total inventory turnovers 4.27 2792 512
service: total monetary turnovers 6.06 539 727
service: aggregate perf. data 0.91 13799 109
service: refresh index statistics 0.04 345837 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-01-2021 10:28:03 to 26-01-2021 10:28:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2165
TEST_TIME 2 2707
TEST_TIME 3 3409
TEST_TIME 4 3999
TEST_TIME 5 4741
TEST_TIME 6 4377
TEST_TIME 7 4703
TEST_TIME 8 4924
TEST_TIME 9 4954
TEST_TIME 10 5311
TEST_TIME 11 5594
TEST_TIME 12 5727
TEST_TIME 13 6305
TEST_TIME 14 6544
TEST_TIME 15 6833
TEST_TIME 16 6767
TEST_TIME 17 7102
TEST_TIME 18 7218
TEST_TIME 19 7304
TEST_TIME 20 7355
TEST_TIME 21 7587
TEST_TIME 22 7642
TEST_TIME 23 7749
TEST_TIME 24 8080
TEST_TIME 25 8389
TEST_TIME 26 9091
TEST_TIME 27 10454
TEST_TIME 28 11003
TEST_TIME 29 10940
TEST_TIME 30 10845
TEST_TIME 31 10773
TEST_TIME 32 10598
TEST_TIME 33 10299
TEST_TIME 34 10234
TEST_TIME 35 10207
TEST_TIME 36 10185
TEST_TIME 37 9989
TEST_TIME 38 9950
TEST_TIME 39 9984
TEST_TIME 40 9930
TEST_TIME 41 9863
TEST_TIME 42 9732
TEST_TIME 43 9829
TEST_TIME 44 9732
TEST_TIME 45 9889
TEST_TIME 46 9805
TEST_TIME 47 9851
TEST_TIME 48 9816
TEST_TIME 49 9943
TEST_TIME 50 9830
TEST_TIME 51 9969
TEST_TIME 52 9896
TEST_TIME 53 9888
TEST_TIME 54 9895
TEST_TIME 55 9755
TEST_TIME 56 9690
TEST_TIME 57 9686
TEST_TIME 58 9571
TEST_TIME 59 9479
TEST_TIME 60 9481
TEST_TIME 61 9409
TEST_TIME 62 9367
TEST_TIME 63 9364
TEST_TIME 64 9372
TEST_TIME 65 9393
TEST_TIME 66 9381
TEST_TIME 67 9439
TEST_TIME 68 9458
TEST_TIME 69 9499
TEST_TIME 70 9483
TEST_TIME 71 9476
TEST_TIME 72 9574
TEST_TIME 73 9611
TEST_TIME 74 9635
TEST_TIME 75 9516
TEST_TIME 76 9408
TEST_TIME 77 9389
TEST_TIME 78 9339
TEST_TIME 79 9268
TEST_TIME 80 9249
TEST_TIME 81 9240
TEST_TIME 82 9196
TEST_TIME 83 9218
TEST_TIME 84 9216
TEST_TIME 85 9191
TEST_TIME 86 9209
TEST_TIME 87 9205
TEST_TIME 88 9205
TEST_TIME 89 9277
TEST_TIME 90 9291
TEST_TIME 91 9375
TEST_TIME 92 9409
TEST_TIME 93 9329
TEST_TIME 94 9261
TEST_TIME 95 9208
TEST_TIME 96 9165
TEST_TIME 97 9165
TEST_TIME 98 9212
TEST_TIME 99 9179
TEST_TIME 100 9170
TEST_TIME 101 9190
TEST_TIME 102 9165
TEST_TIME 103 9171
TEST_TIME 104 9210
TEST_TIME 105 9190
TEST_TIME 106 9266
TEST_TIME 107 9263
TEST_TIME 108 9302
TEST_TIME 109 9279
TEST_TIME 110 9288
TEST_TIME 111 9251
TEST_TIME 112 9206
TEST_TIME 113 9179
TEST_TIME 114 9123
TEST_TIME 115 9095
TEST_TIME 116 9064
TEST_TIME 117 9043
TEST_TIME 118 9017
TEST_TIME 119 9056
TEST_TIME 120 9029

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-01-2021 10:28:07 to 26-01-2021 10:28:10.

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 1485628 1485628 0 0.00 0 22155 6 0 0
doc_list_biud 1485628 1485628 0 0.00 0 90561 33 0 0
sp_add_invoice_to_stock 4914 4779 135 2.74 30 206383 16222 0 135
sp_cancel_adding_invoice 5775 5775 0 0.00 4 50648 829 0 0
sp_cancel_client_order 1722 1722 0 0.00 5 16550 314 0 0
sp_cancel_customer_reserve 563572 563572 0 0.00 1 43976 69 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 4170 82 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 7967 82 0 0
sp_cancel_supplier_invoice 6511 6500 11 0.16 1 90564 1081 0 11
sp_cancel_supplier_order 417 417 0 0.00 14 1219449 119193 0 0
sp_cancel_write_off 10485 10485 0 0.00 1 12995 114 0 0
sp_client_order 20150 20150 0 0.00 4 22536 422 0 0
sp_customer_reserve 433296 433296 0 0.00 0 105850 162 0 0
sp_fill_shopping_cart 30973 30973 0 0.00 1 18125 39 0 0
sp_fill_shopping_cart_clo_res 433289 433289 0 0.00 0 22841 17 0 0
sp_get_clo_for_invoice 4770 4770 0 0.00 0 27769 367 0 0
sp_kill_cost_storno 17350 17350 0 0.00 0 7959 51 0 0
sp_kill_qstorno_ret_qs2qd 1144546 1144546 0 0.00 0 90560 38 0 0
sp_kill_qty_storno 602946 602946 0 0.00 0 90560 81 0 0
sp_lock_selected_doc 20172 20172 0 0.00 0 8495 8 0 0
sp_make_cost_storno 27284 27284 0 0.00 0 12850 59 0 0
sp_multiply_rows_for_pdistr 27284 27284 0 0.00 0 11344 29 0 0
sp_multiply_rows_for_qdistr 20150 20150 0 0.00 0 10732 245 0 0
sp_pay_from_customer 6998 6998 0 0.00 4 17710 118 0 0
sp_pay_to_supplier 5811 5811 0 0.00 5 14217 134 0 0
sp_payment_common 12809 12809 0 0.00 1 12613 20 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 16545 275 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 25405 373 0 0
sp_qd_handle_on_reserve_upd_sts 9705 9705 0 0.00 0 7198 43 0 0
sp_reserve_write_off 9705 9705 0 0.00 6 21662 182 0 0
sp_supplier_invoice 5504 5504 0 0.00 7 108048 3316 0 0
sp_supplier_order 5331 5331 0 0.00 5 47028 1446 0 0
srv_aggregate_perf_data 109 109 0 0.00 2809 142926 13799 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1280 127267 42803 0 0
srv_find_qd_qs_mism 1037346 1037346 0 0.00 0 22154 5 0 0
srv_make_invnt_saldo 512 512 0 0.00 58 102534 2792 0 0
srv_make_money_saldo 727 727 0 0.00 1 12677 540 0 0
srv_recalc_idx_stat 5 5 0 0.00 125533 528378 345837 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4781 4781 0 0.00 0 8462 132 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 9521 105 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 5245 16 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 313 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 549 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 791 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 863 19 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 18297 1339 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 1993 10 0 0
t$perf-norm:sp_client_order 20150 20150 0 0.00 0 8652 16 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 433 3 0 0
t$perf-norm:sp_pay_from_customer 6998 6998 0 0.00 0 7648 8 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 10353 10 0 0
t$perf-norm:sp_reserve_write_off 9705 9705 0 0.00 0 22189 16 0 0
t$perf-norm:sp_supplier_invoice 5504 5504 0 0.00 0 6374 15 0 0
t$perf-norm:sp_supplier_order 5331 5331 0 0.00 0 2070 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 171 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 398 12 0 0
t$perf-norm:srv_make_invnt_saldo 512 512 0 0.00 0 770 7 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 510 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 110 24 0 0
v_add_invoice_to_stock 9810 9540 270 2.75 0 8313 20 0 270
v_all_customers 37182 37182 0 0.00 0 18 1 0 0
v_all_suppliers 30304 30304 0 0.00 0 17 1 0 0
v_all_wares 40300 40300 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 155 5 0 0
v_cancel_client_order 3444 3444 0 0.00 0 4917 7 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 4153 9 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 1355 5 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1201 6 0 0
v_cancel_supplier_invoice 846 824 22 2.60 0 9137 33 0 22
v_cancel_supplier_order 834 834 0 0.00 0 315 3 0 0
v_cancel_write_off 1970 1970 0 0.00 0 4906 20 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 766 1 0 0
v_max_id_clo_res 943 943 0 0.00 0 2069 19 0 0
v_max_id_ord_sup 5495 5495 0 0.00 0 4825 5 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 218 2 0 0
v_max_non_paid_realizn 3610 3610 0 0.00 0 1296 3 0 0
v_min_id_clo_ord 5328 5328 0 0.00 0 3078 5 0 0
v_min_id_clo_res 943 943 0 0.00 0 6117 27 0 0
v_min_id_ord_sup 5495 5495 0 0.00 0 6522 9 0 0
v_min_non_paid_invoice 5811 5811 0 0.00 0 7124 10 0 0
v_min_non_paid_realizn 6998 6998 0 0.00 0 5675 15 0 0
v_our_firm 9894 9894 0 0.00 0 28 1 0 0
v_reserve_write_off 19408 19408 0 0.00 0 9118 8 0 0
x_lock_depdocs_on_canc_invoice 11863 11863 0 0.00 0 14984 99 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 14813 384 0 0
x_make_qstorno_1000_1200 5331 5331 0 0.00 2 47015 1375 0 0
x_make_qstorno_1200_2000 5504 5504 0 0.00 4 105001 3169 0 0
x_make_qstorno_2100_3300 433296 433296 0 0.00 0 105849 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-01-2021 10:28:11 to 26-01-2021 10:28:15.

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
07:56:58 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
07:57:59 26321 8192000000 9718227328 10013024256 795605744 8.852 100 83 166 0 823274192 7585120 20083328
07:59:30 23023 8192000000 9763906704 10070855680 837456240 9.274 100 86 171 1 870489712 12564832 20468640
08:00:58 14778 8192000000 9803862896 10118746112 868445792 9.585 100 83 167 0 909726640 22053728 19227120
08:02:16 15717 8192000000 9841532240 10173513728 906590016 9.964 100 76 153 0 956076656 32523392 16963248
08:03:35 8842 8192000000 9904903600 10251186176 957480624 10.464 100 71 142 0 1032218176 60269168 14468384
08:04:48 5918 8192000000 9987623920 10338963456 997606592 10.855 100 83 166 1 1086303104 71930640 16765872
08:05:56 2863 8192000000 10012956816 10374737920 1021513424 11.087 100 86 172 1 1121029040 82660992 16854624
08:07:01 2308 8192000000 10018547392 10384449536 1045754016 11.320 100 82 164 0 1134941856 72137632 17050208
08:08:04 2066 8192000000 10036911104 10404511744 1061456384 11.470 100 89 175 0 1145247904 66086784 17704736
08:09:08 21720 8192000000 10007412176 10401878016 1065697856 11.511 100 46 93 0 1140212976 65413952 9101168
08:10:31 3438 8192000000 10031713616 10420072448 1074161696 11.592 100 57 114 3 1147340384 62183376 10995312
08:11:35 10178 8192000000 9987834960 10381172736 1081306848 11.660 100 27 52 1 1104985872 18132192 5546832
08:12:46 35474 8192000000 10011776768 10395402240 1084270352 11.688 100 61 121 0 1115819728 18711808 12837568
08:14:22 7795 8192000000 10005252752 10415316992 1091252192 11.755 100 39 78 4 1125031328 25442752 8336384
08:15:30 6597 8192000000 10008346464 10401894400 1091439696 11.756 100 46 94 0 1137214496 36089488 9685312
08:16:38 14113 8192000000 10003747408 10413162496 1087488352 11.719 100 22 42 1 1118992752 26575136 4929264
08:17:53 34514 8192000000 9965550816 10361524224 1032544176 11.193 96 56 112 2 1067752208 22398816 12809216
08:19:28 5965 8192000000 9990303488 10373959680 1037382992 11.240 98 39 80 0 1086304544 41067424 7854128
08:20:38 4563 8192000000 9931626704 10304159744 984797392 10.731 96 35 67 0 1007786512 15770576 7218544
08:21:43 7918 8192000000 9945625744 10338574336 1027955744 11.149 100 33 63 1 1056148608 20998512 7194352
08:22:51 39572 8192000000 9941188192 10317471744 1006870224 10.945 98 48 94 5 1038966016 21166480 10929312
08:24:31 5515 8192000000 9971968768 10360250368 1046936576 11.331 100 39 78 1 1081125200 26136256 8052368
08:25:37 6251 8192000000 9960624080 10356740096 1057728896 11.435 100 31 62 1 1085721952 20866272 7126784
08:26:44 10581 8192000000 10007728256 10413408256 1084807472 11.693 100 17 34 1 1105910224 18071376 3031376
08:28:14 42107 8192000000 10082992464 10480345088 1104378544 11.879 100 60 120 2 1170988656 54614768 11995344
08:30:07 32219 8192000000 10096727168 10505760768 1117026592 11.999 100 36 71 1 1184304912 59839776 7438544
08:31:41 9438 8192000000 10106563744 10518003712 1130896224 12.130 100 33 64 0 1224289600 87091408 6301968
08:32:55 12303 8192000000 10154975520 10572300288 1143303696 12.247 100 49 98 0 1263068272 109642160 10122416
08:34:12 1748 8192000000 10168118560 10578182144 1141656240 12.231 99 60 118 1 1273479072 119578624 12244208
08:35:23 14052 8192000000 10226825456 10637205504 1164210592 12.443 100 55 110 1 1332231552 155083184 12937776
08:36:41 4399 8192000000 10280937440 10688626688 1173916992 12.533 100 67 131 1 1376148128 187142640 15088496
08:38:03 1469 8192000000 10263104880 10684506112 1175374720 12.547 100 69 137 0 1384745408 193271936 16098752
08:39:15 3799 8192000000 10279232000 10688524288 1176763744 12.560 100 65 130 0 1385628240 194642464 14222032
08:40:22 4777 8192000000 10311500688 10729553920 1170105328 12.498 100 68 133 1 1424426848 239556032 14765488
08:41:31 4708 8192000000 10330262544 10735214592 1163397984 12.435 99 79 152 2 1423402336 243283520 16720832
08:42:40 6515 8192000000 10334900224 10736861184 1146497216 12.277 100 63 126 0 1429464432 268562032 14405184
08:43:51 17382 8192000000 10353117312 10788229120 1164926752 12.449 100 40 80 0 1470235856 295021744 10287360
08:45:18 1279 8192000000 10411042560 10805501952 1155745680 12.363 100 86 170 2 1511735264 336135456 19854128
08:46:22 4882 8192000000 10430393424 10841382912 1150593216 12.315 99 48 96 0 1522053136 359118384 12341536
08:47:35 6854 8192000000 10472425648 10887843840 1153598912 12.343 99 49 96 0 1561620000 395474256 12546832
08:48:49 7645 8192000000 10526440848 10960334848 1152822336 12.336 100 48 96 1 1624277472 458308864 13146272
08:50:11 7737 8192000000 10549930976 10987446272 1138101936 12.198 99 57 113 1 1646510640 493359984 15048720
08:51:23 12649 8192000000 10496984128 10959659008 1099142016 11.829 97 28 56 0 1596383152 488915280 8325856
08:52:37 30978 8192000000 10333450976 10857480192 1122459168 12.050 100 22 44 0 1486616064 357544272 6612624
08:54:11 60184 8192000000 10079999216 10568863744 1081907984 11.666 97 5 10 0 1150235168 67316320 1010864
08:56:33 55939 8192000000 10053623120 10510700544 1102085488 11.857 98 13 26 0 1148507488 44297888 2124112
08:58:30 127267 8192000000 10121091888 10561503232 1115625280 11.986 99 31 62 0 1182717088 60153024 6938784
09:02:06 48437 8192000000 10104471936 10572967936 1140865184 12.224 100 22 44 1 1189588304 44600464 4122656
09:03:56 28928 8192000000 10158636672 10598526976 1143567872 12.249 99 54 107 0 1239784640 83562848 12653920
09:05:34 51426 8192000000 10117558208 10602065920 1135850016 12.176 100 10 20 0 1231912752 93681264 2381472
09:07:25 35777 8192000000 10092976512 10534121472 1132275632 12.143 100 33 66 0 1206820112 67630624 6913856
09:10:37 40990 8192000000 10132217232 10598129664 1150534560 12.315 100 25 50 0 1237596592 80877424 6184608
09:12:21 46204 8192000000 10130543776 10557292544 1134753184 12.166 99 19 38 0 1199284240 60555200 3975856
09:14:22 68739 8192000000 10078686784 10558332928 1133471056 12.154 98 20 40 1 1205575872 67613568 4491248
09:16:31 28221 8192000000 10132229184 10563256320 1161995536 12.422 100 35 68 1 1226496944 57405152 7096256
09:18:02 48513 8192000000 10115323280 10561171456 1158172080 12.386 99 11 23 1 1222650032 62180368 2297584
09:20:01 77679 8192000000 10099276144 10552590336 1101050624 11.848 95 11 22 1 1158630080 55184864 2394592
09:22:21 45962 8192000000 10079870224 10534424576 1124691440 12.071 99 15 30 1 1167866800 39937680 3237680
09:24:23 53582 8192000000 10075688784 10538668032 1134611824 12.165 99 7 14 0 1174158080 38046064 1500192
09:26:17 57806 8192000000 10058034912 10548273152 1112622384 11.957 98 8 15 0 1152450080 38047040 1780656
09:28:33 46730 8192000000 10104231296 10547888128 1117506896 12.003 97 16 32 2 1166158032 45434720 3216416
09:30:21 81598 8192000000 10084264208 10555006976 1055182512 11.410 92 16 32 1 1137623456 78906816 3534128
09:32:48 57843 8192000000 10115731648 10543390720 1115612464 11.986 99 22 44 0 1196070176 75596704 4861008
09:34:46 19646 8192000000 10083309712 10520809472 1118019296 12.008 100 32 64 2 1178895232 54298144 6577792
09:36:11 22877 8192000000 10063225440 10487885824 1087407232 11.718 97 37 75 0 1117873520 22589936 7876352
09:37:39 97836 8192000000 10069936304 10530242560 1074804976 11.598 95 21 43 1 1127490400 48272816 4412608
09:40:17 82512 8192000000 10065462432 10534981632 1120617040 12.033 99 11 22 0 1156491904 33595088 2279776
09:43:39 47102 8192000000 10073996336 10534514688 1135860000 12.177 100 5 10 0 1174840240 38031344 948896
09:45:29 66043 8192000000 10101816048 10557280256 1133071888 12.150 99 16 32 0 1202962240 66546752 3343600
09:47:45 92434 8192000000 10110994800 10546028544 1110137840 11.934 97 14 28 0 1179786848 66664688 2984320
09:50:19 51527 8192000000 10102385616 10536161280 1105085360 11.886 97 13 26 0 1161086544 53260112 2741072
09:52:21 53214 8192000000 10123999280 10556313600 1108541968 11.919 97 23 46 0 1181960016 68613520 4804528
09:54:21 53950 8192000000 10106278048 10581688320 1136960560 12.187 100 20 40 0 1240379744 98651296 4767888
09:56:15 64383 8192000000 10133576352 10607407104 1130576608 12.127 99 18 36 0 1220469920 86090816 3802496
09:58:21 115958 8192000000 10051689152 10484592640 1110402096 11.936 98 18 36 0 1132734960 18907040 3425824
10:01:26 59369 8192000000 10078767104 10522890240 1135968608 12.178 100 9 18 0 1169367376 31765568 1633200
10:03:26 75447 8192000000 10068378160 10589081600 1117398592 12.002 98 8 16 0 1183900320 64992656 1509072
10:05:46 62309 8192000000 10076387664 10509893632 1127781152 12.100 99 18 36 0 1167620256 36748880 3090224
10:07:48 61387 8192000000 10128529648 10578247680 1148555984 12.296 100 42 84 2 1199789312 41448608 9784720
10:09:53 31125 8192000000 10110060496 10539405312 1129157440 12.113 99 43 85 1 1182873904 45021088 8695376
10:11:28 43824 8192000000 10112348208 10566459392 1107029184 11.904 97 30 60 2 1172843040 59817104 5996752
10:13:13 81365 8192000000 10106481632 10564022272 1085971968 11.704 95 14 28 1 1169535792 80379488 3184336
10:16:24 46512 8192000000 10093291904 10576887808 1121746368 12.043 100 13 24 3 1183625008 59319616 2559024
10:18:43 60638 8192000000 10055516608 10516267008 1113912304 11.969 99 25 49 2 1170023664 50182912 5928448
10:20:46 51436 8192000000 10056434928 10521370624 1124645888 12.071 100 10 19 0 1177169872 50411184 2112800
10:22:38 60510 8192000000 10068897440 10530017280 1130053792 12.122 100 15 30 2 1172857344 39623008 3180544
10:24:38 54078 8192000000 10044322064 10531778560 1096357136 11.803 98 20 40 1 1165663728 64636032 4670560
10:26:34 27163 8192000000 9294147104 9615147008 422275792 4.902 38 11 22 0 467088032 42501440 2310800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-01-2021 10:28:17 to 26-01-2021 10:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-01-2021 10:28:33 to 26-01-2021 10:28:35.

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          234870
MON$OLDEST_ACTIVE               234871
MON$OLDEST_SNAPSHOT             234871
MON$NEXT_TRANSACTION            234871
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                       2101312
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2342 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.2342 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Jan 26 10:27:07 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Jan 26 10:27:12 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Jan 26 10:27:15 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

26.01.21 10:28:36. 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-26 10:28:39 No severe PSQL-related problems occured
26.01.21 10:28:40. End of report.