Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2021 07:39
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15744300 kB
MemAvailable 30697444 kB
Buffers 0 kB
Cached 15993748 kB
SwapCached 3724 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-10-17 07:37:08
DTS_END                         2021-10-17 07:37:08.8360
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/58364
TRN_ID                          234371
ATT_ID                          669
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: 9163.46 23427 1099615
customer order: creation 170.16 419 20419
customer order: refuse 14.35 345 1722
order to supplier: creation 44.02 1354 5282
order to supplier: removal 3.63 94158 436
invoice (draft): creation 45.31 3421 5437
invoice (draft): removal 52.87 862 6344
invoice accept: apply 41.48 18339 4977
invoice accept: cancel 46.93 813 5631
customer reserve: creation 3841.45 181 460974
customer reserve: removal 4617.23 57 554068
realization accept: apply 83.31 199 9997
realization accept: cancel 75.83 132 9100
payment from customer: creation 57.55 127 6906
payment from customer: removal 5.68 93 682
payment to supplier: creation 49.68 150 5961
payment to supplier: removal 3.14 81 377
service: total inventory turnovers 3.80 3375 456
service: total monetary turnovers 6.12 480 734
service: aggregate perf. data 0.90 12993 108
service: refresh index statistics 0.03 330967 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2021 07:39:23 to 17-10-2021 07:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7158
TEST_TIME 2 4404
TEST_TIME 3 4910
TEST_TIME 4 4753
TEST_TIME 5 4608
TEST_TIME 6 4977
TEST_TIME 7 5339
TEST_TIME 8 5248
TEST_TIME 9 6013
TEST_TIME 10 6266
TEST_TIME 11 6409
TEST_TIME 12 6572
TEST_TIME 13 6821
TEST_TIME 14 7112
TEST_TIME 15 8132
TEST_TIME 16 8586
TEST_TIME 17 8346
TEST_TIME 18 8707
TEST_TIME 19 8937
TEST_TIME 20 9492
TEST_TIME 21 9710
TEST_TIME 22 10136
TEST_TIME 23 9985
TEST_TIME 24 10699
TEST_TIME 25 10719
TEST_TIME 26 10838
TEST_TIME 27 10687
TEST_TIME 28 10623
TEST_TIME 29 10509
TEST_TIME 30 10829
TEST_TIME 31 10911
TEST_TIME 32 10594
TEST_TIME 33 10316
TEST_TIME 34 10329
TEST_TIME 35 10157
TEST_TIME 36 10151
TEST_TIME 37 10105
TEST_TIME 38 10042
TEST_TIME 39 10155
TEST_TIME 40 9983
TEST_TIME 41 9953
TEST_TIME 42 9893
TEST_TIME 43 9750
TEST_TIME 44 9840
TEST_TIME 45 9741
TEST_TIME 46 9566
TEST_TIME 47 9654
TEST_TIME 48 9595
TEST_TIME 49 9602
TEST_TIME 50 9573
TEST_TIME 51 9616
TEST_TIME 52 9557
TEST_TIME 53 9672
TEST_TIME 54 9761
TEST_TIME 55 9712
TEST_TIME 56 9671
TEST_TIME 57 9731
TEST_TIME 58 9731
TEST_TIME 59 9844
TEST_TIME 60 9709
TEST_TIME 61 9583
TEST_TIME 62 9529
TEST_TIME 63 9522
TEST_TIME 64 9436
TEST_TIME 65 9500
TEST_TIME 66 9472
TEST_TIME 67 9444
TEST_TIME 68 9399
TEST_TIME 69 9422
TEST_TIME 70 9396
TEST_TIME 71 9423
TEST_TIME 72 9427
TEST_TIME 73 9414
TEST_TIME 74 9351
TEST_TIME 75 9467
TEST_TIME 76 9560
TEST_TIME 77 9569
TEST_TIME 78 9528
TEST_TIME 79 9485
TEST_TIME 80 9446
TEST_TIME 81 9410
TEST_TIME 82 9409
TEST_TIME 83 9378
TEST_TIME 84 9335
TEST_TIME 85 9290
TEST_TIME 86 9286
TEST_TIME 87 9257
TEST_TIME 88 9280
TEST_TIME 89 9271
TEST_TIME 90 9267
TEST_TIME 91 9266
TEST_TIME 92 9323
TEST_TIME 93 9336
TEST_TIME 94 9309
TEST_TIME 95 9392
TEST_TIME 96 9444
TEST_TIME 97 9427
TEST_TIME 98 9476
TEST_TIME 99 9432
TEST_TIME 100 9361
TEST_TIME 101 9311
TEST_TIME 102 9282
TEST_TIME 103 9247
TEST_TIME 104 9228
TEST_TIME 105 9226
TEST_TIME 106 9198
TEST_TIME 107 9241
TEST_TIME 108 9196
TEST_TIME 109 9213
TEST_TIME 110 9185
TEST_TIME 111 9184
TEST_TIME 112 9173
TEST_TIME 113 9287
TEST_TIME 114 9332
TEST_TIME 115 9301
TEST_TIME 116 9343
TEST_TIME 117 9350
TEST_TIME 118 9279
TEST_TIME 119 9228
TEST_TIME 120 9166

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2021 07:39:26 to 17-10-2021 07:39:30.

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 1524896 1524896 0 0.00 0 25207 7 0 0
doc_list_biud 1524895 1524895 0 0.00 0 50635 27 0 0
sp_add_invoice_to_stock 5064 4977 87 1.71 55 261699 18339 0 87
sp_cancel_adding_invoice 5631 5631 0 0.00 5 58429 814 0 0
sp_cancel_client_order 1722 1722 0 0.00 5 15590 346 0 0
sp_cancel_customer_reserve 554068 554068 0 0.00 1 50636 58 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 12211 93 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 6650 81 0 0
sp_cancel_supplier_invoice 6351 6344 7 0.11 2 28741 862 0 7
sp_cancel_supplier_order 436 436 0 0.00 13 979375 94159 0 0
sp_cancel_write_off 9100 9100 0 0.00 1 25208 132 0 0
sp_client_order 20419 20419 0 0.00 4 29168 420 0 0
sp_customer_reserve 460974 460974 0 0.00 0 137479 181 0 0
sp_fill_shopping_cart 31118 31118 0 0.00 1 14471 44 0 0
sp_fill_shopping_cart_clo_res 460971 460971 0 0.00 0 24383 16 0 0
sp_get_clo_for_invoice 4973 4973 0 0.00 0 28241 347 0 0
sp_kill_cost_storno 15790 15790 0 0.00 0 25207 56 0 0
sp_kill_qstorno_ret_qs2qd 1124016 1124016 0 0.00 0 50632 32 0 0
sp_kill_qty_storno 592271 592271 0 0.00 0 50634 69 0 0
sp_lock_selected_doc 20638 20638 0 0.00 0 7452 6 0 0
sp_make_cost_storno 27837 27837 0 0.00 0 17514 64 0 0
sp_multiply_rows_for_pdistr 27837 27837 0 0.00 0 10138 35 0 0
sp_multiply_rows_for_qdistr 20419 20419 0 0.00 0 20710 223 0 0
sp_pay_from_customer 6906 6906 0 0.00 4 15436 127 0 0
sp_pay_to_supplier 5961 5961 0 0.00 4 15627 151 0 0
sp_payment_common 12867 12867 0 0.00 1 9236 30 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 15474 306 0 0
sp_qd_handle_on_invoice_upd_sts 10604 10604 0 0.00 0 42878 359 0 0
sp_qd_handle_on_reserve_upd_sts 9997 9997 0 0.00 0 34866 53 0 0
sp_reserve_write_off 9997 9997 0 0.00 6 34883 200 0 0
sp_supplier_invoice 5437 5437 0 0.00 6 80563 3421 0 0
sp_supplier_order 5282 5282 0 0.00 5 32011 1355 0 0
srv_aggregate_perf_data 108 108 0 0.00 3177 123355 12993 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1105 173826 48376 0 0
srv_find_qd_qs_mism 1051631 1051631 0 0.00 0 22029 6 0 0
srv_make_invnt_saldo 456 456 0 0.00 66 99062 3375 0 0
srv_make_money_saldo 734 734 0 0.00 1 14470 481 0 0
srv_recalc_idx_stat 4 4 0 0.00 231190 434105 330967 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4977 4977 0 0.00 0 8271 152 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 1456 66 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 1847 9 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 3818 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 543 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 435 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 3175 24 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 23834 1482 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 3625 11 0 0
t$perf-norm:sp_client_order 20419 20419 0 0.00 0 16445 19 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 16247 23 0 0
t$perf-norm:sp_pay_from_customer 6906 6906 0 0.00 0 5591 12 0 0
t$perf-norm:sp_pay_to_supplier 5961 5961 0 0.00 0 7142 13 0 0
t$perf-norm:sp_reserve_write_off 9997 9997 0 0.00 0 6779 11 0 0
t$perf-norm:sp_supplier_invoice 5437 5437 0 0.00 0 6148 16 0 0
t$perf-norm:sp_supplier_order 5282 5282 0 0.00 0 6537 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 4454 48 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 75 2 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 2805 14 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 2080 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10120 9946 174 1.71 0 21745 20 0 174
v_all_customers 36684 36684 0 0.00 0 215 1 0 0
v_all_suppliers 30204 30204 0 0.00 0 47 1 0 0
v_all_wares 40838 40838 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 280 7 0 0
v_cancel_client_order 3444 3444 0 0.00 0 3410 6 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 12185 13 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 6240 12 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 6639 19 0 0
v_cancel_supplier_invoice 820 806 14 1.70 0 2766 14 0 14
v_cancel_supplier_order 872 872 0 0.00 0 5914 18 0 0
v_cancel_write_off 1954 1954 0 0.00 0 22061 37 0 0
v_max_id_clo_ord 5279 5279 0 0.00 0 284 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 3817 15 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 8985 7 0 0
v_max_non_paid_invoice 1558 1558 0 0.00 0 413 2 0 0
v_max_non_paid_realizn 3767 3767 0 0.00 0 1970 5 0 0
v_min_id_clo_ord 5279 5279 0 0.00 0 14372 9 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 1752 9 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 6006 10 0 0
v_min_non_paid_invoice 5961 5961 0 0.00 0 7509 25 0 0
v_min_non_paid_realizn 6906 6906 0 0.00 0 9233 19 0 0
v_our_firm 10432 10432 0 0.00 0 18 1 0 0
v_reserve_write_off 19992 19992 0 0.00 0 21940 11 0 0
x_lock_depdocs_on_canc_invoice 11572 11572 0 0.00 0 25192 98 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 18424 378 0 0
x_make_qstorno_1000_1200 5282 5282 0 0.00 2 31971 1273 0 0
x_make_qstorno_1200_2000 5437 5437 0 0.00 3 80554 3254 0 0
x_make_qstorno_2100_3300 460974 460974 0 0.00 0 137475 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2021 07:39:31 to 17-10-2021 07:39:35.

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:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:13 33315 8192000000 9726920688 10024984576 801607312 8.913 100 81 162 0 828070912 7299280 19164320
05:09:48 26179 8192000000 9767183056 10073923584 837666896 9.276 100 88 176 0 872420704 13958768 20795040
05:11:37 14072 8192000000 9832202528 10156752896 885054496 9.750 100 79 158 0 938840240 35509216 18276528
05:12:56 18354 8192000000 9875685712 10213797888 920960880 10.106 100 77 154 0 992555824 55000128 16594816
05:14:18 8542 8192000000 9927757424 10276069376 969419632 10.581 100 81 161 1 1039090736 53128608 16542496
05:15:32 2675 8192000000 9973338096 10329722880 1003701472 10.914 100 80 160 1 1086719168 67448336 15569360
05:16:36 2450 8192000000 10014164848 10374610944 1027902384 11.148 100 94 189 1 1130241248 84095904 18242960
05:17:39 1750 8192000000 10014730320 10380058624 1050844560 11.369 100 93 186 0 1125762896 55560768 19357568
05:18:43 1485 8192000000 10032090976 10406928384 1062418832 11.480 100 85 170 1 1146112816 66460096 17233888
05:19:50 5024 8192000000 9996628480 10386456576 1069793392 11.550 100 41 83 1 1119925488 41458688 8673408
05:20:56 4663 8192000000 10001220864 10386296832 1074886480 11.599 100 66 133 0 1110045504 20245856 14913168
05:22:04 8436 8192000000 9988861728 10387927040 1075002960 11.600 100 39 78 1 1104688528 21230912 8454656
05:23:16 7092 8192000000 9991615776 10394025984 1083316832 11.679 100 32 65 0 1113495312 22100544 8077936
05:24:26 62232 8192000000 10026104496 10411048960 1089255344 11.736 100 39 78 0 1114579040 16685712 8637984
05:26:29 6861 8192000000 10021036304 10431365120 1090989792 11.752 100 36 71 0 1148050064 49793328 7266944
05:27:37 5651 8192000000 10008933200 10406109184 1070019088 11.552 99 37 76 1 1113463856 35659392 7785376
05:28:43 17112 8192000000 9953457472 10349719552 990145424 10.783 93 27 54 1 1004514656 8553536 5815696
05:30:02 12987 8192000000 9937779328 10328317952 996373440 10.843 95 19 38 0 1032946224 33093552 3479232
05:31:26 4592 8192000000 9931239328 10302361600 996860704 10.848 96 52 102 1 1018531648 10224128 11446816
05:32:31 6624 8192000000 9954810928 10338111488 1032235360 11.190 100 43 88 0 1067556192 25344464 9976368
05:33:38 72096 8192000000 9949631040 10346029056 1031938160 11.187 98 38 76 0 1055691120 15201520 8551440
05:35:51 4497 8192000000 9982439952 10383339520 1058133792 11.439 100 41 84 3 1095581552 28323328 9124432
05:36:57 12866 8192000000 9983622768 10380636160 1067336128 11.527 100 38 74 0 1104899296 28595536 8967632
05:38:19 120853 8192000000 10088002816 10492690432 1107809232 11.912 100 50 100 1 1199315584 80885872 10620480
05:41:30 5463 8192000000 10099093760 10498371584 1125669424 12.081 100 49 98 3 1202350608 66145088 10536096
05:42:40 9360 8192000000 10148771760 10550153216 1133038368 12.150 100 52 104 2 1254827120 110125968 11662784
05:44:17 11449 8192000000 10199157904 10610819072 1150562224 12.315 100 58 115 0 1307637152 143624576 13450352
05:45:32 20106 8192000000 10224551936 10648350720 1141075136 12.226 99 45 89 0 1327257376 175906976 10275264
05:46:58 9206 8192000000 10229745488 10639015936 1163213968 12.433 100 54 108 0 1325849120 150829872 11805280
05:48:09 4102 8192000000 10298858448 10699321344 1174128688 12.535 100 83 162 2 1398012048 205758864 18124496
05:49:21 4092 8192000000 10335322480 10749931520 1167643024 12.475 100 84 167 1 1437291648 250280832 19367792
05:50:27 3799 8192000000 10362128224 10768310272 1164971984 12.450 100 84 167 0 1465066704 280605552 19489168
05:51:35 4153 8192000000 10359402960 10764242944 1155526656 12.361 100 75 150 2 1460446432 287515184 17404592
05:52:43 12985 8192000000 10287470640 10709950464 1150112960 12.311 100 41 82 1 1390918448 230830592 9974896
05:53:58 9749 8192000000 10265271376 10689654784 1135974352 12.178 100 32 64 0 1370354736 227099616 7280768
05:55:21 1101 8192000000 10354837248 10752118784 1139718960 12.213 100 92 182 0 1450790944 289946112 21125872
05:56:26 2897 8192000000 10305069024 10717331456 1149762352 12.307 100 64 128 0 1402635344 237437984 15435008
05:57:33 20121 8192000000 10271139968 10729885696 1131316736 12.134 100 26 52 0 1387663888 249552304 6794848
05:58:54 24723 8192000000 10226840320 10694176768 1131072832 12.131 100 22 44 1 1345585744 209095264 5417648
06:00:23 29928 8192000000 10182236224 10610049024 1106171456 11.896 98 17 33 1 1263958816 153266240 4521120
06:01:59 29254 8192000000 10081201584 10578722816 1101989600 11.857 99 9 17 0 1202113840 97902160 2222080
06:03:37 67842 8192000000 10062967408 10510397440 1029361888 11.162 92 18 36 0 1092577872 59278288 3937696
06:05:45 38615 8192000000 10079750736 10493837312 1084430304 11.690 98 26 49 1 1168935200 79293072 5211824
06:07:33 57503 8192000000 10076404704 10542116864 1094743472 11.788 97 10 20 0 1151314016 54796800 1773744
06:09:33 79724 8192000000 10068282080 10487488512 1102690144 11.863 98 33 66 0 1134940752 25159872 7090736
06:11:54 173826 8192000000 10126031984 10562207744 1118182768 12.010 98 30 60 0 1184885040 59641648 7060624
06:15:49 34424 8192000000 10097353520 10561056768 1134497376 12.164 100 18 36 0 1211836448 72929392 4409680
06:17:43 47076 8192000000 10116440496 10564833280 1137897712 12.196 100 15 30 0 1218148368 77024640 3226016
06:20:07 49960 8192000000 10113023168 10565083136 1142131168 12.236 100 25 51 0 1218521632 70070624 6319840
06:22:14 60365 8192000000 10051607408 10540011520 1127257184 12.095 99 4 8 0 1151278480 23304912 716384
06:24:26 63477 8192000000 10095432960 10574843904 1143300320 12.247 100 9 18 0 1211764496 66588528 1875648
06:26:32 62119 8192000000 10112512192 10569572352 1151927344 12.328 100 20 40 0 1204992672 48820304 4245024
06:28:35 24430 8192000000 10114990432 10540392448 1160427904 12.407 100 54 107 0 1206721600 35474208 10819488
06:30:02 41602 8192000000 10147539952 10627424256 1137201728 12.189 98 38 76 0 1246871808 101710944 7959136
06:31:51 61004 8192000000 10106002480 10548588544 1120315440 12.030 97 39 76 1 1173455328 44703088 8436800
06:33:55 74002 8192000000 10125632400 10581278720 1115156848 11.981 97 30 60 0 1169117584 47697392 6263344
06:37:20 149151 8192000000 10116769984 10587303936 1146875872 12.280 99 19 38 0 1197897472 47072608 3948992
06:40:58 55960 8192000000 10127731680 10630897664 1157350352 12.378 100 6 12 0 1234969072 76467248 1151472
06:43:41 57600 8192000000 10028660544 10469978112 1062489904 11.480 93 13 26 1 1111289632 46437008 2362720
06:45:41 21962 8192000000 10075931792 10503352320 1123439824 12.059 99 31 60 1 1160528784 30411008 6677952
06:47:04 44698 8192000000 10113110288 10544214016 1128890832 12.111 100 38 76 1 1193143200 56398608 7853760
06:48:49 93633 8192000000 10074587296 10510237696 1105467504 11.889 98 22 43 1 1143462656 33409088 4586064
06:51:23 59405 8192000000 10149927984 10603761664 1090625936 11.749 96 35 70 1 1202726960 104968672 7132352
06:53:27 96834 8192000000 10069816992 10537779200 1118384880 12.012 100 15 30 0 1189068336 67720128 2963328
06:56:04 93418 8192000000 10085878080 10518097920 1101100000 11.848 98 38 76 1 1185755648 76622464 8033184
06:58:39 21535 8192000000 10097796752 10572820480 1121174976 12.038 100 25 50 0 1211108256 84974864 4958416
07:00:04 62435 8192000000 10062503568 10527219712 1100501776 11.842 98 15 31 0 1138021488 34369408 3150304
07:02:07 30965 8192000000 10063300736 10504495104 1114185392 11.972 100 18 36 0 1160562432 42667984 3709056
07:04:02 55989 8192000000 10050042688 10509045760 1097811568 11.817 98 9 18 1 1135423744 35285664 2326512
07:05:59 39578 8192000000 10058155200 10493779968 1104282224 11.878 99 31 63 0 1150125360 39212784 6630352
07:07:46 39011 8192000000 10093231840 10531332096 1122328416 12.049 99 21 42 0 1171385856 44911520 4145920
07:09:26 60437 8192000000 10072187120 10513027072 1091250624 11.755 97 16 32 0 1155852704 61399936 3202144
07:11:26 82706 8192000000 10164574656 10623332352 1125726032 12.081 99 8 16 0 1203794544 76441648 1626864
07:13:49 56282 8192000000 10095665408 10600620032 1127482704 12.098 99 7 14 0 1183997648 55280352 1234592
07:15:50 115136 8192000000 10078594032 10530971648 1132299072 12.143 99 19 38 0 1169042320 32867568 3875680
07:18:51 27737 8192000000 10076771488 10548592640 1135757776 12.176 99 20 40 0 1176527904 36975536 3794592
07:20:22 68915 8192000000 10113125760 10574499840 1124482336 12.069 98 16 32 0 1194104672 66011792 3610544
07:22:35 65109 8192000000 10111053904 10597539840 1130245920 12.124 99 13 26 0 1200894816 67469616 3179280
07:24:40 84626 8192000000 10106805968 10554621952 1053596544 11.395 92 13 26 0 1109437760 52743584 3097632
07:27:05 56548 8192000000 10135971424 10579001344 1101420320 11.851 97 19 37 2 1196572976 90908976 4243680
07:29:03 63209 8192000000 10181134352 10663526400 1097984224 11.819 97 25 50 1 1220163360 116464048 5715088
07:31:07 50292 8192000000 10049250880 10502316032 1104529552 11.881 99 11 22 0 1145736592 38870272 2336768
07:33:02 23738 8192000000 10097972512 10571051008 1086581936 11.710 98 34 66 2 1174011488 80899360 6530192
07:34:37 64169 8192000000 10050294384 10522701824 1097564160 11.815 99 13 26 0 1149330144 49200384 2565600
07:36:41 34275 8192000000 9366289760 9697173504 478089872 5.514 44 9 18 0 513532784 33689616 1753296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2021 07:39:37 to 17-10-2021 07:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2021 07:39:53 to 17-10-2021 07:39:55.

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          234370
MON$OLDEST_ACTIVE               235232
MON$OLDEST_SNAPSHOT             235232
MON$NEXT_TRANSACTION            235232
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                       2056416
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:226: 
:227: mscs-test-161.budzdorov.ru    Sun Oct 17 07:37:24 2021
:228:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:229: 
--- end of diff output ---

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

17.10.21 07:39:56. 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-10-17 07:39:58 No severe PSQL-related problems occured
17.10.21 07:39:59. End of report.