Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2020 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 16683216 kB
MemAvailable 31341132 kB
Buffers 0 kB
Cached 14859496 kB
SwapCached 3700 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-12-24 10:26:55
DTS_END                         2020-12-24 10:26:55.4750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37258
TRN_ID                          236416
ATT_ID                          644
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: 9177.24 20659 1101269
customer order: creation 172.93 416 20752
customer order: refuse 14.58 305 1750
order to supplier: creation 45.75 1303 5490
order to supplier: removal 3.26 108023 391
invoice (draft): creation 45.70 3642 5484
invoice (draft): removal 50.82 833 6098
invoice accept: apply 41.58 17341 4990
invoice accept: cancel 46.17 741 5540
customer reserve: creation 3870.81 169 464497
customer reserve: removal 4595.58 60 551469
realization accept: apply 83.55 158 10026
realization accept: cancel 79.63 126 9556
payment from customer: creation 58.33 98 7000
payment from customer: removal 5.71 92 685
payment to supplier: creation 48.25 106 5790
payment to supplier: removal 3.39 64 407
service: total inventory turnovers 4.02 2934 482
service: total monetary turnovers 6.23 542 747
service: aggregate perf. data 0.93 16954 111
service: refresh index statistics 0.03 259275 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2020 10:27:32 to 24-12-2020 10:27:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2676
TEST_TIME 2 3457
TEST_TIME 3 4705
TEST_TIME 4 5472
TEST_TIME 5 5332
TEST_TIME 6 5370
TEST_TIME 7 5304
TEST_TIME 8 5172
TEST_TIME 9 5261
TEST_TIME 10 5496
TEST_TIME 11 5966
TEST_TIME 12 5989
TEST_TIME 13 6356
TEST_TIME 14 6627
TEST_TIME 15 7124
TEST_TIME 16 7557
TEST_TIME 17 7869
TEST_TIME 18 8188
TEST_TIME 19 9022
TEST_TIME 20 8977
TEST_TIME 21 9074
TEST_TIME 22 9062
TEST_TIME 23 9982
TEST_TIME 24 10966
TEST_TIME 25 10920
TEST_TIME 26 10817
TEST_TIME 27 10564
TEST_TIME 28 10533
TEST_TIME 29 10371
TEST_TIME 30 10258
TEST_TIME 31 10167
TEST_TIME 32 10231
TEST_TIME 33 10198
TEST_TIME 34 10022
TEST_TIME 35 9848
TEST_TIME 36 9834
TEST_TIME 37 9781
TEST_TIME 38 9679
TEST_TIME 39 9815
TEST_TIME 40 9938
TEST_TIME 41 9941
TEST_TIME 42 9966
TEST_TIME 43 9994
TEST_TIME 44 9910
TEST_TIME 45 9872
TEST_TIME 46 10002
TEST_TIME 47 10043
TEST_TIME 48 10090
TEST_TIME 49 9895
TEST_TIME 50 9762
TEST_TIME 51 9659
TEST_TIME 52 9624
TEST_TIME 53 9604
TEST_TIME 54 9550
TEST_TIME 55 9571
TEST_TIME 56 9539
TEST_TIME 57 9456
TEST_TIME 58 9504
TEST_TIME 59 9645
TEST_TIME 60 9564
TEST_TIME 61 9618
TEST_TIME 62 9667
TEST_TIME 63 9615
TEST_TIME 64 9677
TEST_TIME 65 9706
TEST_TIME 66 9825
TEST_TIME 67 9726
TEST_TIME 68 9592
TEST_TIME 69 9528
TEST_TIME 70 9476
TEST_TIME 71 9390
TEST_TIME 72 9307
TEST_TIME 73 9270
TEST_TIME 74 9263
TEST_TIME 75 9227
TEST_TIME 76 9243
TEST_TIME 77 9207
TEST_TIME 78 9289
TEST_TIME 79 9275
TEST_TIME 80 9262
TEST_TIME 81 9255
TEST_TIME 82 9309
TEST_TIME 83 9269
TEST_TIME 84 9318
TEST_TIME 85 9297
TEST_TIME 86 9206
TEST_TIME 87 9178
TEST_TIME 88 9140
TEST_TIME 89 9125
TEST_TIME 90 9073
TEST_TIME 91 9036
TEST_TIME 92 9093
TEST_TIME 93 9118
TEST_TIME 94 9121
TEST_TIME 95 9099
TEST_TIME 96 9096
TEST_TIME 97 9153
TEST_TIME 98 9195
TEST_TIME 99 9210
TEST_TIME 100 9229
TEST_TIME 101 9267
TEST_TIME 102 9255
TEST_TIME 103 9343
TEST_TIME 104 9331
TEST_TIME 105 9244
TEST_TIME 106 9196
TEST_TIME 107 9126
TEST_TIME 108 9100
TEST_TIME 109 9141
TEST_TIME 110 9098
TEST_TIME 111 9119
TEST_TIME 112 9138
TEST_TIME 113 9096
TEST_TIME 114 9078
TEST_TIME 115 9133
TEST_TIME 116 9175
TEST_TIME 117 9167
TEST_TIME 118 9147
TEST_TIME 119 9202
TEST_TIME 120 9179

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-12-2020 10:27:35 to 24-12-2020 10:27:39.

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 1530099 1530099 0 0.00 0 20038 6 0 0
doc_list_biud 1530098 1530098 0 0.00 0 70359 28 0 0
sp_add_invoice_to_stock 5097 4990 107 2.09 26 205617 17341 0 107
sp_cancel_adding_invoice 5540 5540 0 0.00 6 78157 741 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 10292 306 0 0
sp_cancel_customer_reserve 551469 551469 0 0.00 1 70360 61 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 9895 92 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 1321 64 0 0
sp_cancel_supplier_invoice 6112 6098 14 0.22 2 23910 834 0 14
sp_cancel_supplier_order 391 391 0 0.00 9 1094563 108024 0 0
sp_cancel_write_off 9556 9556 0 0.00 1 7328 127 0 0
sp_client_order 20752 20752 0 0.00 4 22581 417 0 0
sp_customer_reserve 464497 464497 0 0.00 0 110962 169 0 0
sp_fill_shopping_cart 31709 31709 0 0.00 1 25432 43 0 0
sp_fill_shopping_cart_clo_res 464483 464483 0 0.00 0 23400 19 0 0
sp_get_clo_for_invoice 4976 4976 0 0.00 0 34911 432 0 0
sp_kill_cost_storno 16188 16188 0 0.00 0 7514 57 0 0
sp_kill_qstorno_ret_qs2qd 1118983 1118983 0 0.00 0 70331 33 0 0
sp_kill_qty_storno 589806 589806 0 0.00 0 70359 71 0 0
sp_lock_selected_doc 20587 20587 0 0.00 0 10184 5 0 0
sp_make_cost_storno 27792 27792 0 0.00 0 12941 48 0 0
sp_multiply_rows_for_pdistr 27792 27792 0 0.00 0 8841 28 0 0
sp_multiply_rows_for_qdistr 20752 20752 0 0.00 0 18325 232 0 0
sp_pay_from_customer 7000 7000 0 0.00 5 13380 98 0 0
sp_pay_to_supplier 5790 5790 0 0.00 5 11735 106 0 0
sp_payment_common 12790 12790 0 0.00 1 6609 17 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 6059 267 0 0
sp_qd_handle_on_invoice_upd_sts 10516 10516 0 0.00 0 23579 341 0 0
sp_qd_handle_on_reserve_upd_sts 10026 10026 0 0.00 0 16002 57 0 0
sp_reserve_write_off 10026 10026 0 0.00 6 16091 158 0 0
sp_supplier_invoice 5484 5484 0 0.00 6 107926 3643 0 0
sp_supplier_order 5490 5490 0 0.00 6 39487 1303 0 0
srv_aggregate_perf_data 111 111 0 0.00 2463 499596 16955 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1705 142604 47190 0 0
srv_find_qd_qs_mism 1053020 1053020 0 0.00 0 20037 6 0 0
srv_make_invnt_saldo 482 482 0 0.00 61 104196 2934 0 0
srv_make_money_saldo 747 747 0 0.00 1 16723 543 0 0
srv_recalc_idx_stat 4 4 0 0.00 146883 502410 259276 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 0 9850 138 0 0
t$perf-norm:sp_cancel_adding_invoice 344 344 0 0.00 0 1502 67 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 2202 10 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 1014 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 448 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 6001 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 2475 19 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 18363 1385 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 2186 5 0 0
t$perf-norm:sp_client_order 20752 20752 0 0.00 0 11466 16 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 3370 8 0 0
t$perf-norm:sp_pay_from_customer 7000 7000 0 0.00 0 9126 7 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 6206 10 0 0
t$perf-norm:sp_reserve_write_off 10026 10026 0 0.00 0 2393 6 0 0
t$perf-norm:sp_supplier_invoice 5484 5484 0 0.00 0 5830 15 0 0
t$perf-norm:sp_supplier_order 5490 5490 0 0.00 0 4004 12 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 364 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 219 4 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 3439 15 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 2773 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 10162 9948 214 2.10 0 5399 16 0 214
v_all_customers 37460 37460 0 0.00 0 47 1 0 0
v_all_suppliers 30024 30024 0 0.00 0 21 1 0 0
v_all_wares 41504 41504 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 688 688 0 0.00 0 968 8 0 0
v_cancel_client_order 3500 3500 0 0.00 0 2428 6 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 3761 7 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 968 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 681 4 0 0
v_cancel_supplier_invoice 794 766 28 3.52 0 3460 17 0 28
v_cancel_supplier_order 782 782 0 0.00 0 328 3 0 0
v_cancel_write_off 1958 1958 0 0.00 0 2861 14 0 0
v_max_id_clo_ord 5488 5488 0 0.00 0 719 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 3012 16 0 0
v_max_id_ord_sup 5469 5469 0 0.00 0 4933 4 0 0
v_max_non_paid_invoice 1735 1735 0 0.00 0 137 2 0 0
v_max_non_paid_realizn 3831 3831 0 0.00 0 4297 5 0 0
v_min_id_clo_ord 5488 5488 0 0.00 0 4114 6 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 4976 17 0 0
v_min_id_ord_sup 5469 5469 0 0.00 0 6253 6 0 0
v_min_non_paid_invoice 5790 5790 0 0.00 0 3156 10 0 0
v_min_non_paid_realizn 7000 7000 0 0.00 0 6159 12 0 0
v_our_firm 10382 10382 0 0.00 0 19 1 0 0
v_reserve_write_off 20052 20052 0 0.00 0 4060 5 0 0
x_lock_depdocs_on_canc_invoice 11255 11255 0 0.00 0 12191 105 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 8855 299 0 0
x_make_qstorno_1000_1200 5490 5490 0 0.00 1 39480 1236 0 0
x_make_qstorno_1200_2000 5484 5484 0 0.00 2 104077 3461 0 0
x_make_qstorno_2100_3300 464497 464497 0 0.00 0 110960 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2020 10:27:40 to 24-12-2020 10:27:44.

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 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:58:00 20410 8192000000 9713628416 10005995520 793068352 8.826 100 90 180 0 822588320 7469648 22050320
07:59:30 20107 8192000000 9756320320 10061766656 833148688 9.231 100 86 172 0 865276640 11935328 20192624
08:00:57 17000 8192000000 9799399344 10111438848 865599344 9.556 100 85 170 0 904927904 19582016 19746544
08:02:18 10079 8192000000 9848533264 10180583424 915150448 10.048 100 78 154 1 960639008 28937488 16551072
08:03:31 11723 8192000000 9933716048 10273521664 956252832 10.452 100 87 175 1 1036140240 61878768 18008640
08:04:47 4529 8192000000 9964623248 10313908224 983711024 10.720 100 89 178 2 1081810784 79965440 18134320
08:05:53 2458 8192000000 9982344624 10337820672 1007989152 10.956 100 92 184 0 1099103328 72573552 18540624
08:06:57 1939 8192000000 10019996928 10386067456 1039444752 11.259 100 89 177 0 1131938976 74112896 18381328
08:08:00 1301 8192000000 10055707568 10422042624 1060318464 11.460 100 94 188 1 1161428720 81132560 19977696
08:09:02 9895 8192000000 9993607072 10378989568 1059511792 11.452 100 44 86 2 1129122784 60386208 9224784
08:10:14 4247 8192000000 9988827568 10380451840 1077817616 11.627 100 42 83 1 1117487360 30775760 8893984
08:11:19 3199 8192000000 10000306432 10395959296 1087771040 11.721 100 38 76 2 1119212176 23573280 7867856
08:12:25 41125 8192000000 9998768704 10400714752 1092080512 11.762 100 31 61 2 1128498464 29597824 6820128
08:14:08 9235 8192000000 10028966160 10429792256 1096978416 11.809 100 31 62 1 1128601104 25506624 6116064
08:15:20 4026 8192000000 10028299184 10424160256 1100048064 11.838 100 60 118 3 1132407968 18645712 13714192
08:16:28 6239 8192000000 10006252480 10396143616 1056019168 11.418 96 37 74 5 1078493200 14228704 8245328
08:17:35 49944 8192000000 9976251856 10385993728 1021241648 11.084 94 18 34 0 1038847776 13951616 3654512
08:19:26 4987 8192000000 9952085984 10321031168 1022996032 11.101 98 49 96 2 1062154544 27537312 11621200
08:20:31 5158 8192000000 9915178352 10293051392 998435136 10.863 99 33 66 2 1033125632 26434208 8256288
08:21:37 9962 8192000000 9939020480 10335457280 1003108768 10.909 98 42 84 3 1037233968 25028176 9097024
08:22:48 42243 8192000000 9947183040 10342916096 994308224 10.823 96 17 32 3 1020474720 23072288 3094208
08:24:33 6661 8192000000 9974014128 10359566336 1057362912 11.431 100 52 104 1 1086684720 17539072 11782736
08:25:40 4928 8192000000 9971635728 10365399040 1060202160 11.458 100 37 75 0 1086553216 18318192 8032864
08:26:48 43801 8192000000 10003651280 10395127808 1088256624 11.726 100 46 95 2 1113700576 15425552 10018400
08:28:39 8742 8192000000 10069664432 10464108544 1102306736 11.860 100 59 118 1 1162930224 47809424 12814064
08:29:51 1875 8192000000 10081207632 10472214528 1122138368 12.047 100 83 164 1 1190496672 50590848 17767456
08:30:56 3259 8192000000 10106086240 10509488128 1133334528 12.153 100 52 104 1 1217531488 73636960 10560000
08:32:05 8679 8192000000 10164591920 10566475776 1149902544 12.309 100 62 124 0 1269744992 106291872 13550576
08:33:18 16818 8192000000 10164277632 10574688256 1158462848 12.389 100 43 86 3 1271805184 103770528 9571808
08:34:40 3633 8192000000 10219643328 10624552960 1174039744 12.535 100 65 127 1 1330670944 141839104 14792096
08:35:46 13426 8192000000 10294943952 10703491072 1180843904 12.598 100 70 140 0 1392296144 195689648 15762592
08:37:08 6112 8192000000 10314702880 10724130816 1172165776 12.517 100 69 136 1 1419405136 231512096 15727264
08:38:19 9918 8192000000 10304956576 10736062464 1165170800 12.452 99 48 96 0 1407476176 230743936 11561440
08:39:33 1700 8192000000 10326267712 10728652800 1171324320 12.509 100 90 180 3 1428650144 237127280 20198544
08:40:36 3948 8192000000 10370623600 10776358912 1163561952 12.437 100 78 157 1 1475837568 294364256 17911360
08:41:43 2399 8192000000 10378582048 10780839936 1163457072 12.436 100 77 153 3 1474353072 293560656 17335344
08:42:47 9635 8192000000 10368654224 10788855808 1157349120 12.378 100 45 90 4 1474793104 306148144 11295840
08:44:08 5157 8192000000 10365990192 10765451264 1125096880 12.075 100 80 159 2 1464135072 320874464 18163728
08:45:18 3433 8192000000 10346159024 10761752576 1116928688 11.998 98 66 130 1 1435642112 303170624 15542800
08:46:24 9460 8192000000 10332207856 10766548992 1134021280 12.159 99 35 70 1 1419274896 276585344 8668272
08:47:41 29654 8192000000 10341661728 10817888256 1133073216 12.150 100 19 38 1 1439752656 301315328 5364112
08:49:40 28380 8192000000 10208422432 10675048448 1120644896 12.033 100 19 39 1 1356917872 231229856 5043120
08:51:19 24382 8192000000 10063987488 10488582144 1101974656 11.856 98 35 70 0 1154418448 43858224 8585568
08:52:45 26149 8192000000 10104154320 10529091584 1105250912 11.887 99 16 32 3 1180716160 72090352 3374896
08:54:38 71360 8192000000 10071390960 10518491136 1097248704 11.812 99 44 88 2 1165198752 58221520 9728528
08:57:13 11197 8192000000 10057542688 10497490944 1104319600 11.879 99 13 26 2 1160583744 53569776 2694368
08:58:25 36138 8192000000 10067046880 10541678592 1107689056 11.911 100 27 54 2 1192691664 79025440 5977168
09:00:04 142604 8192000000 10115943728 10572845056 1093834624 11.779 97 18 36 2 1155412016 57396672 4180720
09:04:51 78083 8192000000 10124547280 10561998848 1137822064 12.195 100 17 34 0 1216529216 75065680 3641472
09:07:18 20704 8192000000 10090795792 10515886080 1109488912 11.928 97 49 99 0 1159753856 40044064 10220880
09:08:39 29359 8192000000 10088109696 10523234304 1127644640 12.099 99 38 72 1 1174009760 38601344 7763776
09:10:11 79670 8192000000 10127982288 10581323776 1115986000 11.989 97 19 38 1 1187398224 67280000 4132224
09:12:48 116345 8192000000 10095851888 10617970688 1133249424 12.152 99 15 29 2 1237230800 100886128 3095248
09:15:45 80521 8192000000 10103256592 10549145600 1134040752 12.159 98 27 52 1 1166194176 26481088 5672336
09:18:06 64354 8192000000 10100085872 10599141376 1148571312 12.296 100 7 14 0 1204912272 54742784 1598176
09:20:11 35371 8192000000 10083220272 10554634240 1120094224 12.028 98 7 14 0 1187602144 66383680 1124240
09:21:48 56581 8192000000 10146270176 10579193856 1151950656 12.328 100 21 42 0 1235197712 78666624 4580432
09:23:50 67604 8192000000 10115217072 10581000192 1125347920 12.077 98 13 26 0 1239534816 110499504 3687392
09:25:58 47947 8192000000 10073351152 10505498624 1092487584 11.766 96 25 50 0 1121296688 23883200 4925904
09:27:46 65894 8192000000 10057396400 10515484672 1094647424 11.787 98 10 20 0 1155951712 59171696 2132592
09:29:58 33982 8192000000 10078790176 10523021312 1047702480 11.339 94 40 80 0 1136343840 79744128 8897232
09:31:36 58614 8192000000 10097347232 10564710400 1106645904 11.901 99 10 20 0 1169419104 60689600 2083600
09:33:39 91635 8192000000 10038882112 10465554432 1099931408 11.837 98 27 54 1 1131585712 25770640 5883664
09:36:23 58351 8192000000 10024642368 10462404608 1070338272 11.555 96 15 30 1 1104202752 30850448 3014032
09:38:23 50867 8192000000 10079606128 10536853504 1110515600 11.937 99 15 28 1 1167185536 53927280 2742656
09:40:14 56776 8192000000 10056333856 10533310464 1085531712 11.700 98 6 12 0 1146080208 59302432 1246064
09:42:11 42948 8192000000 10087157392 10556076032 1089181872 11.735 97 16 32 0 1184105920 91521760 3402288
09:43:56 50214 8192000000 10065395888 10539978752 1107631856 11.910 99 24 45 2 1164612736 51619408 5361472
09:45:49 34516 8192000000 10067734608 10501697536 1110114448 11.934 100 50 100 2 1156910736 36747392 10048896
09:47:23 50323 8192000000 10104279904 10532859904 1106171056 11.896 98 25 50 0 1167152416 56229872 4751488
09:49:19 75944 8192000000 10057210560 10569871360 1096460384 11.804 98 20 40 1 1184117744 83388400 4268960
09:51:35 125651 8192000000 10050343440 10515042304 1100744272 11.845 98 28 56 2 1168656304 61731600 6180432
09:54:51 65442 8192000000 10027528992 10514341888 1074925968 11.599 96 7 14 0 1125165648 48843696 1395984
09:56:57 90896 8192000000 10075041312 10574639104 1101942240 11.856 97 22 44 0 1159979424 53016912 5020272
09:59:28 70232 8192000000 10060513632 10525310976 1099250160 11.831 97 11 22 0 1136922432 35199840 2472432
10:01:43 44186 8192000000 10144626576 10583035904 1132205008 12.142 99 37 74 1 1188465952 48324016 7936928
10:03:37 99675 8192000000 10100831008 10545770496 1127453232 12.097 99 26 52 1 1217131872 84576192 5102448
10:06:24 73786 8192000000 10114600800 10599575552 1149547920 12.305 100 13 26 0 1221901792 69757184 2596688
10:08:38 109720 8192000000 10098325760 10626740224 1115187344 11.982 97 8 16 0 1192756528 76002432 1566752
10:11:31 55280 8192000000 10107406624 10566610944 1136197264 12.180 99 14 28 0 1204313856 64675824 3440768
10:13:31 86643 8192000000 10119804432 10586669056 1131204528 12.133 99 16 32 1 1210387680 75219024 3964128
10:15:59 38664 8192000000 10097188784 10545033216 1105719312 11.892 97 15 30 1 1138148400 29662352 2766736
10:17:43 60067 8192000000 10086493104 10534010880 1087866480 11.722 96 16 32 0 1141130336 50450016 2813840
10:19:51 45624 8192000000 10148863312 10598858752 1124223504 12.067 99 25 50 0 1204306960 74626496 5456960
10:21:38 62036 8192000000 10100967408 10536964096 1099058368 11.829 97 38 74 1 1168609904 61974880 7576656
10:23:41 64270 8192000000 10095531696 10523430912 1098726560 11.826 98 32 64 1 1142354240 36784848 6842832
10:25:55 59211 8192000000 10069755904 10480300032 1070156928 11.554 96 22 44 0 1130249392 55961696 4130768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2020 10:27:46 to 24-12-2020 10:28:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2020 10:28:02 to 24-12-2020 10:28:04.

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          235235
MON$OLDEST_ACTIVE               237628
MON$OLDEST_SNAPSHOT             237628
MON$NEXT_TRANSACTION            237628
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                       2101376
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             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2303 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.2303 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Dec 24 10:26:59 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Dec 24 10:27:00 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Dec 24 10:27:01 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

24.12.20 10:28:05. 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-12-24 10:28:07 No severe PSQL-related problems occured
24.12.20 10:28:08. End of report.