Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.11.2021 07:47
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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 2021-11-11 18:28
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.33
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 16701352 kB
MemAvailable 31584988 kB
Buffers 0 kB
Cached 14964596 kB
SwapCached 0 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.1
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-11-19 07:46:32
DTS_END                         2021-11-19 07:46:32.8170
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/33044
TRN_ID                          232216
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: 8993.14 25512 1079177
customer order: creation 163.46 391 19615
customer order: refuse 14.23 373 1707
order to supplier: creation 44.37 1463 5324
order to supplier: removal 3.23 135998 387
invoice (draft): creation 46.28 3823 5554
invoice (draft): removal 51.08 1087 6129
invoice accept: apply 41.46 21034 4975
invoice accept: cancel 45.74 938 5489
customer reserve: creation 3806.58 209 456790
customer reserve: removal 4491.46 75 538975
realization accept: apply 80.66 193 9679
realization accept: cancel 79.61 134 9553
payment from customer: creation 57.50 121 6900
payment from customer: removal 5.46 76 655
payment to supplier: creation 47.74 163 5729
payment to supplier: removal 3.38 95 406
service: total inventory turnovers 3.63 4401 435
service: total monetary turnovers 6.28 555 754
service: aggregate perf. data 0.98 15916 117
service: refresh index statistics 0.03 323194 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-11-2021 07:47:37 to 19-11-2021 07:47:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6011
TEST_TIME 2 3764
TEST_TIME 3 4032
TEST_TIME 4 4537
TEST_TIME 5 4288
TEST_TIME 6 4625
TEST_TIME 7 4971
TEST_TIME 8 4875
TEST_TIME 9 5019
TEST_TIME 10 4958
TEST_TIME 11 5175
TEST_TIME 12 6080
TEST_TIME 13 6204
TEST_TIME 14 6438
TEST_TIME 15 6504
TEST_TIME 16 6673
TEST_TIME 17 6783
TEST_TIME 18 6902
TEST_TIME 19 7235
TEST_TIME 20 7234
TEST_TIME 21 7377
TEST_TIME 22 8152
TEST_TIME 23 8551
TEST_TIME 24 8530
TEST_TIME 25 9134
TEST_TIME 26 9454
TEST_TIME 27 9955
TEST_TIME 28 10484
TEST_TIME 29 10781
TEST_TIME 30 10553
TEST_TIME 31 10333
TEST_TIME 32 10310
TEST_TIME 33 10177
TEST_TIME 34 10168
TEST_TIME 35 10071
TEST_TIME 36 9934
TEST_TIME 37 9995
TEST_TIME 38 9901
TEST_TIME 39 9954
TEST_TIME 40 9900
TEST_TIME 41 9960
TEST_TIME 42 9846
TEST_TIME 43 9755
TEST_TIME 44 9645
TEST_TIME 45 9515
TEST_TIME 46 9410
TEST_TIME 47 9500
TEST_TIME 48 9376
TEST_TIME 49 9492
TEST_TIME 50 9390
TEST_TIME 51 9392
TEST_TIME 52 9409
TEST_TIME 53 9511
TEST_TIME 54 9481
TEST_TIME 55 9510
TEST_TIME 56 9537
TEST_TIME 57 9555
TEST_TIME 58 9683
TEST_TIME 59 9597
TEST_TIME 60 9524
TEST_TIME 61 9475
TEST_TIME 62 9463
TEST_TIME 63 9353
TEST_TIME 64 9316
TEST_TIME 65 9226
TEST_TIME 66 9176
TEST_TIME 67 9154
TEST_TIME 68 9084
TEST_TIME 69 9037
TEST_TIME 70 9259
TEST_TIME 71 9229
TEST_TIME 72 9277
TEST_TIME 73 9255
TEST_TIME 74 9300
TEST_TIME 75 9294
TEST_TIME 76 9368
TEST_TIME 77 9402
TEST_TIME 78 9350
TEST_TIME 79 9431
TEST_TIME 80 9415
TEST_TIME 81 9462
TEST_TIME 82 9401
TEST_TIME 83 9328
TEST_TIME 84 9310
TEST_TIME 85 9296
TEST_TIME 86 9268
TEST_TIME 87 9220
TEST_TIME 88 9181
TEST_TIME 89 9134
TEST_TIME 90 9107
TEST_TIME 91 9105
TEST_TIME 92 9123
TEST_TIME 93 9198
TEST_TIME 94 9149
TEST_TIME 95 9137
TEST_TIME 96 9255
TEST_TIME 97 9264
TEST_TIME 98 9259
TEST_TIME 99 9266
TEST_TIME 100 9238
TEST_TIME 101 9249
TEST_TIME 102 9248
TEST_TIME 103 9170
TEST_TIME 104 9132
TEST_TIME 105 9121
TEST_TIME 106 9070
TEST_TIME 107 9030
TEST_TIME 108 8980
TEST_TIME 109 8979
TEST_TIME 110 9005
TEST_TIME 111 8968
TEST_TIME 112 8934
TEST_TIME 113 8916
TEST_TIME 114 8956
TEST_TIME 115 8940
TEST_TIME 116 8950
TEST_TIME 117 9053
TEST_TIME 118 9034
TEST_TIME 119 9033
TEST_TIME 120 8997

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-11-2021 07:47:40 to 19-11-2021 07:47:44.

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 1500024 1500024 0 0.00 0 20172 8 0 0
doc_list_biud 1500023 1500023 0 0.00 0 77367 35 0 0
sp_add_invoice_to_stock 5079 4975 104 2.04 28 263041 21035 0 104
sp_cancel_adding_invoice 5489 5489 0 0.00 5 73020 938 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 10563 374 0 0
sp_cancel_customer_reserve 538975 538975 0 0.00 1 77368 76 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 2 2498 76 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 5072 95 0 0
sp_cancel_supplier_invoice 6137 6129 8 0.13 2 70830 1087 0 8
sp_cancel_supplier_order 387 387 0 0.00 8 1332918 135999 0 0
sp_cancel_write_off 9553 9553 0 0.00 1 7885 135 0 0
sp_client_order 19615 19615 0 0.00 4 42206 392 0 0
sp_customer_reserve 456790 456790 0 0.00 0 67965 209 0 0
sp_fill_shopping_cart 30481 30481 0 0.00 1 17882 46 0 0
sp_fill_shopping_cart_clo_res 456782 456782 0 0.00 0 16117 15 0 0
sp_get_clo_for_invoice 4966 4966 0 0.00 0 22373 305 0 0
sp_kill_cost_storno 16103 16103 0 0.00 0 7513 70 0 0
sp_kill_qstorno_ret_qs2qd 1094019 1094019 0 0.00 0 77359 41 0 0
sp_kill_qty_storno 576884 576884 0 0.00 0 77366 89 0 0
sp_lock_selected_doc 20295 20295 0 0.00 0 7848 5 0 0
sp_make_cost_storno 27273 27273 0 0.00 0 12494 57 0 0
sp_multiply_rows_for_pdistr 27273 27273 0 0.00 0 8656 36 0 0
sp_multiply_rows_for_qdistr 19615 19615 0 0.00 0 14609 194 0 0
sp_pay_from_customer 6900 6900 0 0.00 4 37545 122 0 0
sp_pay_to_supplier 5729 5729 0 0.00 5 42114 164 0 0
sp_payment_common 12629 12629 0 0.00 1 8845 24 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 10557 337 0 0
sp_qd_handle_on_invoice_upd_sts 10454 10454 0 0.00 0 26413 426 0 0
sp_qd_handle_on_reserve_upd_sts 9679 9679 0 0.00 0 17131 57 0 0
sp_reserve_write_off 9679 9679 0 0.00 7 17319 194 0 0
sp_supplier_invoice 5554 5554 0 0.00 6 126291 3824 0 0
sp_supplier_order 5324 5324 0 0.00 6 49304 1464 0 0
srv_aggregate_perf_data 117 117 0 0.00 2840 197115 15917 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1210 138724 44829 0 0
srv_find_qd_qs_mism 1031615 1031615 0 0.00 0 20171 7 0 0
srv_make_invnt_saldo 435 435 0 0.00 72 114184 4402 0 0
srv_make_money_saldo 754 754 0 0.00 1 20812 555 0 0
srv_recalc_idx_stat 4 4 0 0.00 154510 611271 323195 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4975 4975 0 0.00 0 19103 214 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 0 16104 162 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 14566 20 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 2685 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 3218 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 719 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 921 28 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 40920 1880 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 12971 18 0 0
t$perf-norm:sp_client_order 19615 19615 0 0.00 0 6472 20 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 2846 10 0 0
t$perf-norm:sp_pay_from_customer 6900 6900 0 0.00 0 10609 15 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 10870 12 0 0
t$perf-norm:sp_reserve_write_off 9679 9679 0 0.00 0 6283 10 0 0
t$perf-norm:sp_supplier_invoice 5554 5554 0 0.00 0 9933 26 0 0
t$perf-norm:sp_supplier_order 5324 5324 0 0.00 0 10523 20 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 379 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 72 2 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 3633 16 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 394 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 72 34 0 0
v_add_invoice_to_stock 10136 9928 208 2.05 0 6882 16 0 208
v_all_customers 35914 35914 0 0.00 0 29 1 0 0
v_all_suppliers 29760 29760 0 0.00 0 110 1 0 0
v_all_wares 39230 39230 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 306 7 0 0
v_cancel_client_order 3414 3414 0 0.00 0 4636 8 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 893 6 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 857 4 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1832 9 0 0
v_cancel_supplier_invoice 846 830 16 1.89 0 661 15 0 16
v_cancel_supplier_order 774 774 0 0.00 0 3268 11 0 0
v_cancel_write_off 2008 2008 0 0.00 0 3725 15 0 0
v_max_id_clo_ord 5324 5324 0 0.00 0 2048 2 0 0
v_max_id_clo_res 994 994 0 0.00 0 3637 25 0 0
v_max_id_ord_sup 5542 5542 0 0.00 0 7093 6 0 0
v_max_non_paid_invoice 1715 1715 0 0.00 0 1993 3 0 0
v_max_non_paid_realizn 3502 3502 0 0.00 0 669 3 0 0
v_min_id_clo_ord 5324 5324 0 0.00 0 4818 7 0 0
v_min_id_clo_res 994 994 0 0.00 0 2685 9 0 0
v_min_id_ord_sup 5542 5542 0 0.00 0 5613 14 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 7434 18 0 0
v_min_non_paid_realizn 6900 6900 0 0.00 0 8842 19 0 0
v_our_firm 10112 10112 0 0.00 0 12 1 0 0
v_reserve_write_off 19358 19358 0 0.00 0 4781 7 0 0
x_lock_depdocs_on_canc_invoice 11203 11203 0 0.00 0 13213 128 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 4530 377 0 0
x_make_qstorno_1000_1200 5324 5324 0 0.00 2 49287 1376 0 0
x_make_qstorno_1200_2000 5554 5554 0 0.00 3 124566 3654 0 0
x_make_qstorno_2100_3300 456790 456790 0 0.00 0 58959 191 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-11-2021 07:47:45 to 19-11-2021 07:47:49.

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
05:16:36 1009 8192000000 8964365184 9030909952 10400400 0.126 3 2 4 0 10559760 87104 72256
05:17:39 30845 8192000000 9729803760 10030276608 800922688 8.906 100 85 170 0 829252528 7337504 20992336
05:19:21 22513 8192000000 9773892352 10082607104 845295344 9.353 100 86 172 0 877999456 12340400 20363712
05:20:48 13870 8192000000 9812639760 10132860928 878038784 9.680 100 82 164 0 921822656 25184928 18598944
05:22:08 18003 8192000000 9858062224 10194735104 916029136 10.057 100 81 160 1 966187328 32401920 17756272
05:23:32 9150 8192000000 9926630432 10270093312 959185136 10.481 100 78 155 0 1036181328 60796352 16199840
05:24:45 5620 8192000000 9964616176 10324709376 998441040 10.863 100 76 148 2 1079595488 65737392 15417056
05:25:54 3101 8192000000 9989632736 10356527104 1029038528 11.159 100 73 143 1 1103255920 59701376 14516016
05:26:59 2063 8192000000 10022694736 10390335488 1056995712 11.428 100 90 180 0 1140729664 64921584 18812368
05:28:03 1307 8192000000 10037040032 10405793792 1067536288 11.529 100 97 196 2 1155725856 67988672 20200896
05:29:05 1985 8192000000 10017756448 10396336128 1073347360 11.584 100 76 153 2 1131747504 41354256 17045888
05:30:08 4740 8192000000 9984535360 10379075584 1077151264 11.620 100 37 71 2 1108354720 23183216 8020240
05:31:15 5054 8192000000 10009344208 10394443776 1085964320 11.704 100 52 100 1 1122809968 27231536 9614112
05:32:22 17494 8192000000 9995542656 10407030784 1088317616 11.727 100 32 64 0 1119948864 24488944 7142304
05:34:04 6298 8192000000 10025249024 10421387264 1093531744 11.776 100 45 88 1 1135859408 32998272 9329392
05:35:14 6703 8192000000 10020583776 10416041984 1089755392 11.740 99 36 71 1 1129101712 31569808 7776512
05:36:21 7341 8192000000 10000932768 10413645824 1089614240 11.739 99 27 55 0 1119894992 24433600 5847152
05:37:30 54960 8192000000 9995053136 10380492800 1033000512 11.197 95 50 97 0 1072356176 28702304 10653360
05:39:25 6448 8192000000 9957424528 10351575040 1023389296 11.105 98 52 104 0 1055673104 20909072 11374736
05:40:34 7987 8192000000 9923872400 10302709760 998998640 10.869 98 31 61 1 1018232176 12584688 6648848
05:41:42 7444 8192000000 9953412352 10342723584 1026789856 11.138 100 39 76 2 1061257632 25725392 8742384
05:42:51 34120 8192000000 9950248768 10331656192 1025536592 11.125 98 43 87 0 1055607312 20601200 9469520
05:44:26 6181 8192000000 9959051456 10342899712 1043725104 11.300 100 63 124 5 1074580272 16983264 13871904
05:45:35 3127 8192000000 9980019872 10368688128 1057013680 11.428 100 62 125 1 1095288368 23908608 14366080
05:46:39 4900 8192000000 9982287648 10367926272 1064175616 11.496 100 42 82 1 1090040784 16779152 9086016
05:47:51 89891 8192000000 10052574656 10452738048 1079757264 11.645 100 37 74 2 1148933376 60713808 8462304
05:50:27 11358 8192000000 10071127712 10473308160 1103628464 11.872 100 49 96 1 1183443088 68901472 10913152
05:51:44 13323 8192000000 10152506304 10546089984 1120809296 12.035 100 54 107 1 1256266448 122771136 12686016
05:53:02 8539 8192000000 10186601120 10588475392 1128544624 12.108 100 69 136 1 1296022512 152553200 14924688
05:54:17 16043 8192000000 10214369984 10619162624 1145495040 12.267 100 53 102 0 1320442352 163823104 11124208
05:55:46 12094 8192000000 10274503392 10678542336 1162810992 12.430 100 61 121 2 1381005952 204619344 13575616
05:57:02 8372 8192000000 10316132224 10736726016 1170888560 12.505 100 54 108 0 1432430416 248888560 12653296
05:58:17 2618 8192000000 10330814704 10771271680 1179127072 12.582 100 80 159 3 1429336240 231686912 18522256
05:59:23 2570 8192000000 10378550288 10776641536 1178956336 12.580 100 87 175 0 1476647104 278599600 19091168
06:00:30 4400 8192000000 10348058720 10750271488 1172523920 12.520 100 78 157 1 1453684560 263550768 17609872
06:01:39 3461 8192000000 10399632416 10796474368 1172654000 12.522 100 87 166 2 1493281136 301689024 18938112
06:02:49 5650 8192000000 10417705488 10820399104 1160282944 12.406 100 81 162 0 1516859776 337825216 18751616
06:04:01 3849 8192000000 10438325168 10842013696 1161679904 12.419 100 82 163 3 1536089904 356170464 18239536
06:05:09 1208 8192000000 10486441408 10900656128 1145635792 12.269 99 88 171 0 1564812512 399545744 19630976
06:06:16 2983 8192000000 10457334048 10854068224 1136899136 12.186 98 81 159 0 1551066368 395436336 18730896
06:07:26 4048 8192000000 10508876912 10927276032 1162207840 12.424 100 82 162 1 1597571152 414956800 20406512
06:08:42 5774 8192000000 10439518784 10864713728 1168173824 12.480 100 63 126 0 1535443712 351708464 15561424
06:09:52 11622 8192000000 10383536256 10806255616 1151946656 12.328 99 43 84 1 1483466512 320614096 10905760
06:11:08 16032 8192000000 10412089744 10889199616 1161730560 12.419 100 38 76 0 1526418256 353792224 10895472
06:13:05 13598 8192000000 10262329792 10745049088 1131637440 12.137 99 20 40 0 1367285168 230010288 5637440
06:14:25 24873 8192000000 10111847088 10571579392 1127104784 12.094 100 9 19 1 1207097680 77835664 2157232
06:16:27 58655 8192000000 10094502064 10518179840 1127709808 12.100 99 36 73 0 1167742224 32170624 7861792
06:18:29 83494 8192000000 10103063360 10571550720 1114558896 11.976 98 35 71 0 1187209904 64173840 8477168
06:21:09 79648 8192000000 10096829152 10538307584 1119602736 12.023 98 15 30 0 1180634544 57776160 3255648
06:23:30 17939 8192000000 10060419680 10495025152 1123832976 12.063 99 35 70 0 1152112640 21036240 7243424
06:24:52 24193 8192000000 10108281472 10539106304 1111130624 11.943 98 56 112 0 1192912624 70126080 11655920
06:26:25 46362 8192000000 10075151056 10540892160 1128794112 12.110 99 35 67 1 1193600128 57063552 7742464
06:28:20 7456 8192000000 10062077536 10496565248 1130168192 12.123 99 25 50 1 1167003248 31057728 5777328
06:29:28 48920 8192000000 10088546592 10543202304 1127216576 12.095 99 15 30 1 1178762112 48440288 3105248
06:32:32 24825 8192000000 10129656304 10564972544 1142932912 12.243 99 43 87 2 1223994512 71113632 9947968
06:33:57 63689 8192000000 10128228288 10580172800 1141509744 12.230 99 21 42 0 1221832176 75094704 5227728
06:36:01 42952 8192000000 10099554480 10538627072 1151688880 12.325 100 20 40 0 1201608080 45785312 4133888
06:37:49 74799 8192000000 10133475344 10608320512 1147147072 12.283 99 28 54 0 1208390992 55402432 5841488
06:40:11 49514 8192000000 10111806432 10550214656 1148344624 12.294 100 22 44 0 1198920432 45841056 4734752
06:42:06 71883 8192000000 10133573248 10609229824 1133627488 12.156 98 25 50 1 1200111920 61347296 5137136
06:44:18 71893 8192000000 10062484880 10494640128 1049678608 11.358 92 13 26 1 1116852400 64461328 2712464
06:46:31 38061 8192000000 10121169904 10561331200 1128865776 12.111 100 37 74 0 1219062192 81830576 8365840
06:48:47 50826 8192000000 10047301776 10470096896 1097864832 11.817 97 36 70 0 1127113600 22215712 7033056
06:50:40 68856 8192000000 10110964880 10559873024 1108609664 11.919 98 17 34 1 1198156688 85758320 3788704
06:53:54 84010 8192000000 10184197552 10650759168 1117449616 12.003 98 15 31 1 1244464240 123479040 3535584
06:56:18 62103 8192000000 10055984368 10503028736 1106757008 11.902 97 19 38 0 1195780016 84994784 4028224
06:58:20 69189 8192000000 10093121040 10530942976 1065408816 11.508 93 21 41 1 1117480272 47617904 4453552
07:00:30 32349 8192000000 10096498944 10515107840 1111722256 11.949 99 35 70 0 1158268496 39240400 7305840
07:02:07 53492 8192000000 10087734000 10573860864 1106766464 11.902 99 20 40 0 1187887008 77238080 3882464
07:04:09 13776 8192000000 10083440112 10505285632 1110255472 11.935 98 46 92 0 1151722416 31772624 9694320
07:05:35 86483 8192000000 10120943120 10587684864 1115971104 11.989 99 28 56 0 1182008528 60570096 5467328
07:08:03 94494 8192000000 10069981088 10500141056 1132917904 12.149 100 24 48 1 1167810672 30154176 4738592
07:10:39 53447 8192000000 10089925264 10527547392 1131516160 12.136 99 40 80 3 1192704736 52988512 8200064
07:12:33 78452 8192000000 10062249936 10528690176 1126706944 12.090 99 11 21 1 1189288464 60249712 2331808
07:14:52 66401 8192000000 10110653632 10566696960 1136275024 12.180 99 15 30 1 1200911328 61278736 3357568
07:17:02 76871 8192000000 10130849728 10599206912 1120554960 12.032 97 12 24 0 1216309824 93104928 2649936
07:19:26 52774 8192000000 10052644432 10505789440 1104323904 11.879 97 10 20 0 1140608320 34547376 1737040
07:21:22 92553 8192000000 10125608672 10647314432 1125613488 12.080 99 18 36 1 1259027008 129281712 4131808
07:23:55 48759 8192000000 10084469712 10526355456 1132251936 12.143 99 20 40 1 1170029424 33679376 4098112
07:25:46 111541 8192000000 10085593216 10531450880 1101551568 11.852 96 33 66 0 1151128832 43017984 6559280
07:28:41 138724 8192000000 10094293984 10567647232 1125818480 12.082 98 26 52 3 1201789040 70517472 5453088
07:32:07 67106 8192000000 10070310096 10541916160 1141794400 12.232 100 9 18 0 1172801392 29164832 1842160
07:34:15 81125 8192000000 10116694480 10568028160 1131357872 12.134 98 15 30 0 1187153888 52653200 3142816
07:36:36 96587 8192000000 10133422800 10610544640 1142470816 12.239 99 18 35 1 1217966848 71998320 3497712
07:39:19 58513 8192000000 10159338416 10625998848 1142349136 12.238 99 23 46 1 1252301264 104592208 5359920
07:41:33 66140 8192000000 10152106896 10613743616 1142516160 12.239 99 20 40 2 1242902416 95992976 4393280
07:43:43 58853 8192000000 10082119024 10539945984 1101927744 11.856 97 26 52 0 1170252704 62251248 6073712
07:45:48 34074 8192000000 10084229104 10510639104 1108971312 11.923 99 33 65 5 1163275440 47964416 6339712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-11-2021 07:47:51 to 19-11-2021 07:48:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-11-2021 07:48:06 to 19-11-2021 07:48:08.

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          228693
MON$OLDEST_ACTIVE               232394
MON$OLDEST_SNAPSHOT             232394
MON$NEXT_TRANSACTION            232394
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                       2048224
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Nov 19 07:46:35 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Nov 19 07:46:41 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Nov 19 07:46:41 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Nov 19 07:46:41 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Fri Nov 19 07:46:45 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

19.11.21 07:48:09. 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-11-19 07:48:11 No severe PSQL-related problems occured
19.11.21 07:48:13. End of report.