Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2021 13:37
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 15757148 kB
MemAvailable 30716336 kB
Buffers 0 kB
Cached 15922948 kB
SwapCached 4696 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-04 13:37:10
DTS_END                         2021-10-04 13:37:10.6990
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/57508
TRN_ID                          235378
ATT_ID                          698
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: 9297.32 23609 1115678
customer order: creation 171.75 399 20610
customer order: refuse 14.81 373 1777
order to supplier: creation 45.53 1457 5463
order to supplier: removal 3.23 131302 387
invoice (draft): creation 46.30 3727 5556
invoice (draft): removal 53.12 970 6374
invoice accept: apply 42.86 20146 5143
invoice accept: cancel 47.85 984 5742
customer reserve: creation 3910.53 199 469264
customer reserve: removal 4663.95 70 559674
realization accept: apply 84.80 206 10176
realization accept: cancel 84.06 146 10087
payment from customer: creation 59.73 107 7167
payment from customer: removal 5.78 96 694
payment to supplier: creation 48.88 134 5866
payment to supplier: removal 3.38 83 406
service: total inventory turnovers 3.54 4151 425
service: total monetary turnovers 6.28 611 753
service: aggregate perf. data 0.92 15150 110
service: refresh index statistics 0.03 291864 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2021 13:37:57 to 04-10-2021 13:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4711
TEST_TIME 2 3166
TEST_TIME 3 3938
TEST_TIME 4 3655
TEST_TIME 5 4221
TEST_TIME 6 4421
TEST_TIME 7 5295
TEST_TIME 8 5152
TEST_TIME 9 5065
TEST_TIME 10 5286
TEST_TIME 11 5497
TEST_TIME 12 5724
TEST_TIME 13 6102
TEST_TIME 14 6861
TEST_TIME 15 6886
TEST_TIME 16 7134
TEST_TIME 17 7249
TEST_TIME 18 7361
TEST_TIME 19 7331
TEST_TIME 20 7265
TEST_TIME 21 7491
TEST_TIME 22 7574
TEST_TIME 23 8212
TEST_TIME 24 8311
TEST_TIME 25 8492
TEST_TIME 26 8734
TEST_TIME 27 8797
TEST_TIME 28 8900
TEST_TIME 29 9132
TEST_TIME 30 9062
TEST_TIME 31 9435
TEST_TIME 32 10193
TEST_TIME 33 11128
TEST_TIME 34 10850
TEST_TIME 35 10696
TEST_TIME 36 10503
TEST_TIME 37 10268
TEST_TIME 38 10279
TEST_TIME 39 10129
TEST_TIME 40 10154
TEST_TIME 41 10148
TEST_TIME 42 10095
TEST_TIME 43 9982
TEST_TIME 44 9885
TEST_TIME 45 9784
TEST_TIME 46 9707
TEST_TIME 47 9627
TEST_TIME 48 9685
TEST_TIME 49 9625
TEST_TIME 50 9640
TEST_TIME 51 9671
TEST_TIME 52 9676
TEST_TIME 53 9822
TEST_TIME 54 9816
TEST_TIME 55 9860
TEST_TIME 56 9829
TEST_TIME 57 10013
TEST_TIME 58 9969
TEST_TIME 59 9941
TEST_TIME 60 9829
TEST_TIME 61 9679
TEST_TIME 62 9722
TEST_TIME 63 9736
TEST_TIME 64 9646
TEST_TIME 65 9624
TEST_TIME 66 9631
TEST_TIME 67 9593
TEST_TIME 68 9543
TEST_TIME 69 9577
TEST_TIME 70 9622
TEST_TIME 71 9576
TEST_TIME 72 9682
TEST_TIME 73 9726
TEST_TIME 74 9728
TEST_TIME 75 9690
TEST_TIME 76 9734
TEST_TIME 77 9693
TEST_TIME 78 9729
TEST_TIME 79 9656
TEST_TIME 80 9621
TEST_TIME 81 9563
TEST_TIME 82 9546
TEST_TIME 83 9522
TEST_TIME 84 9470
TEST_TIME 85 9435
TEST_TIME 86 9404
TEST_TIME 87 9417
TEST_TIME 88 9397
TEST_TIME 89 9395
TEST_TIME 90 9385
TEST_TIME 91 9381
TEST_TIME 92 9432
TEST_TIME 93 9435
TEST_TIME 94 9508
TEST_TIME 95 9521
TEST_TIME 96 9562
TEST_TIME 97 9566
TEST_TIME 98 9540
TEST_TIME 99 9627
TEST_TIME 100 9594
TEST_TIME 101 9526
TEST_TIME 102 9485
TEST_TIME 103 9451
TEST_TIME 104 9409
TEST_TIME 105 9371
TEST_TIME 106 9320
TEST_TIME 107 9284
TEST_TIME 108 9261
TEST_TIME 109 9339
TEST_TIME 110 9314
TEST_TIME 111 9286
TEST_TIME 112 9294
TEST_TIME 113 9305
TEST_TIME 114 9299
TEST_TIME 115 9331
TEST_TIME 116 9295
TEST_TIME 117 9292
TEST_TIME 118 9303
TEST_TIME 119 9366
TEST_TIME 120 9298

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-10-2021 13:38:00 to 04-10-2021 13:38:04.

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 1549754 1549754 0 0.00 0 29190 7 0 0
doc_list_biud 1549754 1549754 0 0.00 0 82443 32 0 0
sp_add_invoice_to_stock 5272 5143 129 2.44 35 177914 20147 0 129
sp_cancel_adding_invoice 5742 5742 0 0.00 4 117771 984 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 14575 373 0 0
sp_cancel_customer_reserve 559674 559674 0 0.00 1 82445 71 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 9124 97 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 2387 83 0 0
sp_cancel_supplier_invoice 6385 6374 11 0.17 2 36525 971 0 11
sp_cancel_supplier_order 387 387 0 0.00 12 1224969 131302 0 0
sp_cancel_write_off 10087 10087 0 0.00 1 10943 147 0 0
sp_client_order 20610 20610 0 0.00 4 29018 400 0 0
sp_customer_reserve 469264 469264 0 0.00 0 68408 199 0 0
sp_fill_shopping_cart 31618 31618 0 0.00 1 46597 48 0 0
sp_fill_shopping_cart_clo_res 469262 469262 0 0.00 0 25389 17 0 0
sp_get_clo_for_invoice 5141 5141 0 0.00 0 59969 437 0 0
sp_kill_cost_storno 16929 16929 0 0.00 0 11274 70 0 0
sp_kill_qstorno_ret_qs2qd 1136196 1136196 0 0.00 0 82411 38 0 0
sp_kill_qty_storno 599358 599358 0 0.00 0 82443 82 0 0
sp_lock_selected_doc 21043 21043 0 0.00 0 9685 6 0 0
sp_make_cost_storno 28350 28350 0 0.00 0 28055 66 0 0
sp_multiply_rows_for_pdistr 28350 28350 0 0.00 0 6181 32 0 0
sp_multiply_rows_for_qdistr 20610 20610 0 0.00 0 29000 212 0 0
sp_pay_from_customer 7167 7167 0 0.00 4 17178 107 0 0
sp_pay_to_supplier 5866 5866 0 0.00 4 11020 134 0 0
sp_payment_common 13033 13033 0 0.00 1 6278 20 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 14568 342 0 0
sp_qd_handle_on_invoice_upd_sts 10883 10883 0 0.00 0 29163 398 0 0
sp_qd_handle_on_reserve_upd_sts 10176 10176 0 0.00 0 23033 61 0 0
sp_reserve_write_off 10176 10176 0 0.00 7 29209 206 0 0
sp_supplier_invoice 5556 5556 0 0.00 6 96811 3727 0 0
sp_supplier_order 5463 5463 0 0.00 7 33186 1457 0 0
srv_aggregate_perf_data 110 110 0 0.00 3098 211787 15151 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1676 125644 40306 0 0
srv_find_qd_qs_mism 1067496 1067496 0 0.00 0 13262 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 51 137016 4151 0 0
srv_make_money_saldo 753 753 0 0.00 1 11968 611 0 0
srv_recalc_idx_stat 4 4 0 0.00 232705 432153 291865 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5143 5143 0 0.00 0 29053 183 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 5595 95 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 23845 43 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 1483 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 6083 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 270 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 2715 31 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 27468 1885 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1174 8 0 0
t$perf-norm:sp_client_order 20610 20610 0 0.00 0 28373 24 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 2098 8 0 0
t$perf-norm:sp_pay_from_customer 7167 7167 0 0.00 0 6097 8 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 4560 8 0 0
t$perf-norm:sp_reserve_write_off 10176 10176 0 0.00 0 7343 9 0 0
t$perf-norm:sp_supplier_invoice 5556 5556 0 0.00 0 17256 29 0 0
t$perf-norm:sp_supplier_order 5463 5463 0 0.00 0 6390 21 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 444 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 201 5 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 2105 24 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 28514 46 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 10540 10282 258 2.44 0 2796 17 0 258
v_all_customers 37764 37764 0 0.00 0 163 1 0 0
v_all_suppliers 30666 30666 0 0.00 0 21 1 0 0
v_all_wares 41220 41220 0 0.00 0 108 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1170 10 0 0
v_cancel_client_order 3554 3554 0 0.00 0 2772 5 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 1990 7 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 1362 5 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1501 6 0 0
v_cancel_supplier_invoice 850 828 22 2.58 0 776 15 0 22
v_cancel_supplier_order 774 774 0 0.00 0 322 4 0 0
v_cancel_write_off 1972 1972 0 0.00 0 782 13 0 0
v_max_id_clo_ord 5463 5463 0 0.00 0 6243 3 0 0
v_max_id_clo_res 998 998 0 0.00 0 3180 17 0 0
v_max_id_ord_sup 5545 5545 0 0.00 0 6452 6 0 0
v_max_non_paid_invoice 1541 1541 0 0.00 0 3912 5 0 0
v_max_non_paid_realizn 3907 3907 0 0.00 0 782 3 0 0
v_min_id_clo_ord 5463 5463 0 0.00 0 4843 6 0 0
v_min_id_clo_res 998 998 0 0.00 0 1503 6 0 0
v_min_id_ord_sup 5545 5545 0 0.00 0 7939 12 0 0
v_min_non_paid_invoice 5866 5866 0 0.00 0 4714 13 0 0
v_min_non_paid_realizn 7167 7167 0 0.00 0 6273 14 0 0
v_our_firm 9976 9976 0 0.00 0 31 1 0 0
v_reserve_write_off 20352 20352 0 0.00 0 5388 5 0 0
x_lock_depdocs_on_canc_invoice 11702 11702 0 0.00 0 24252 133 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 7782 388 0 0
x_make_qstorno_1000_1200 5463 5463 0 0.00 2 33068 1360 0 0
x_make_qstorno_1200_2000 5556 5556 0 0.00 3 91465 3552 0 0
x_make_qstorno_2100_3300 469264 469264 0 0.00 0 68407 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2021 13:38:05 to 04-10-2021 13:38:09.

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
11:07:14 6 8192000000 8800243680 8812093440 5882912 0.071 2 2 3 0 6034864 90592 61360
11:08:15 18868 8192000000 9719516464 10017034240 797718080 8.873 100 83 162 1 826457936 8103824 20636032
11:09:40 24275 8192000000 9766437328 10075623424 842017312 9.320 100 92 184 0 877661296 13341024 22302960
11:11:12 20816 8192000000 9810089872 10128674816 875548800 9.655 100 84 168 0 913126080 18008528 19568752
11:12:37 15257 8192000000 9870855136 10202693632 911415584 10.011 100 81 162 0 986070240 56534096 18120560
11:13:55 6757 8192000000 9902746896 10247524352 960888320 10.498 100 80 158 0 1018009392 40298176 16822896
11:15:04 7408 8192000000 9972254560 10324398080 992836704 10.809 100 75 150 1 1081196160 73361632 14997824
11:16:16 7814 8192000000 9982416640 10346823680 1025460288 11.125 100 79 158 1 1104943856 63692832 15790736
11:17:27 3631 8192000000 10009402464 10375041024 1044104112 11.304 100 91 183 1 1130137888 67053104 18980672
11:18:33 4034 8192000000 10022489296 10394140672 1058498080 11.442 100 85 168 1 1133403392 57772272 17133040
11:19:40 22222 8192000000 10003841232 10390114304 1070323472 11.555 100 60 121 1 1158684784 76478528 11882784
11:21:05 3350 8192000000 10025731136 10426847232 1076930016 11.618 100 41 82 0 1164938848 79977744 8031088
11:22:10 6570 8192000000 10015575872 10416099328 1087145600 11.716 100 54 107 0 1134526752 35641440 11739712
11:23:19 4817 8192000000 9997145600 10403119104 1093307488 11.774 100 43 88 0 1133028592 30544544 9176560
11:24:24 41567 8192000000 10023382336 10414678016 1096734608 11.807 100 64 129 1 1129401936 18688976 13978352
11:26:07 3888 8192000000 10019068384 10421415936 1098364912 11.822 100 54 108 1 1135447552 25278176 11804464
11:27:14 3942 8192000000 10001013456 10399531008 1095507712 11.795 100 45 90 1 1112621776 5940128 11173936
11:28:19 56312 8192000000 9991674960 10382426112 1070807200 11.560 98 45 90 2 1090093616 9242160 10044256
11:30:15 8258 8192000000 9950645472 10345496576 1016638128 11.040 96 33 66 2 1037686688 13186592 7861968
11:31:28 8400 8192000000 9933191408 10317651968 985994880 10.743 96 51 101 1 1014387632 16657552 11735200
11:32:39 56657 8192000000 9932162096 10326609920 1015361024 11.027 99 28 58 0 1036413872 14334400 6718448
11:34:36 6266 8192000000 9970729520 10364551168 1047165760 11.333 100 42 82 1 1086115776 29977632 8972384
11:35:43 4922 8192000000 9972049056 10373906432 1060301904 11.459 100 42 85 2 1098775696 29422400 9051392
11:36:53 10145 8192000000 9990370352 10398621696 1074298464 11.593 100 16 32 1 1095354960 17745408 3311088
11:38:09 64001 8192000000 10038473520 10447110144 1100158272 11.839 100 36 69 1 1136277072 29073904 7044896
11:40:31 23347 8192000000 10071836000 10475667456 1119906784 12.026 100 47 92 2 1169059616 38679504 10473328
11:42:00 47874 8192000000 10146223216 10579787776 1135573344 12.174 100 45 88 2 1252482928 107847584 9062000
11:43:54 1835 8192000000 10186025664 10588123136 1145246816 12.265 100 79 156 0 1293714544 131427952 17039776
11:44:58 11459 8192000000 10162027248 10575671296 1149729840 12.307 100 39 80 0 1264628944 105516256 9382848
11:46:13 6831 8192000000 10203010544 10614583296 1161248752 12.415 100 49 96 0 1301555520 130038304 10268464
11:47:25 18817 8192000000 10240519264 10660315136 1170569232 12.502 100 62 125 1 1348067280 163953168 13544880
11:48:49 2736 8192000000 10269936688 10679881728 1179621760 12.587 100 82 164 2 1375627952 177644400 18361792
11:49:58 1924 8192000000 10286421728 10701168640 1178079680 12.572 100 80 160 0 1384337504 188328816 17929008
11:51:03 3211 8192000000 10291500592 10720698368 1184296416 12.630 100 82 160 1 1395208112 192640576 18271120
11:52:12 2603 8192000000 10303061920 10710487040 1194098912 12.721 100 85 167 2 1393009216 180797200 18113104
11:53:19 4254 8192000000 10330390496 10736488448 1183084384 12.619 100 82 160 0 1429108208 227830752 18193072
11:54:29 5441 8192000000 10320354224 10722533376 1163499392 12.436 100 90 180 0 1413702368 230556176 19646800
11:55:43 1675 8192000000 10303169376 10703880192 1132947504 12.149 99 84 168 0 1392229568 239164128 20117936
11:56:49 4722 8192000000 10345137456 10753236992 1124238512 12.067 98 68 132 0 1439713312 299011472 16463328
11:58:07 1940 8192000000 10439933408 10837192704 1147926576 12.290 100 87 170 1 1539206512 370344384 20935552
11:59:14 2359 8192000000 10459196336 10871783424 1143965696 12.253 99 66 131 0 1554510576 394582944 15961936
12:00:23 4359 8192000000 10437406128 10865209344 1150561008 12.315 100 67 132 1 1545450528 378222560 16666960
12:01:57 7965 8192000000 10437644592 10874159104 1138974176 12.206 99 51 100 2 1529248960 377492912 12781872
12:03:11 15038 8192000000 10448903056 10883923968 1158226272 12.387 100 38 77 0 1547558624 379405952 9926400
12:04:28 18563 8192000000 10467277504 10926231552 1141747584 12.232 99 39 78 0 1543868912 391894416 10226912
12:05:59 23099 8192000000 10455002160 10902409216 1142500816 12.239 99 34 66 1 1527701376 377076528 8124032
12:07:25 24026 8192000000 10485498864 10924105728 1141349680 12.228 100 29 58 1 1566926592 418054176 7522736
12:09:29 32140 8192000000 10210651824 10831319040 1129061248 12.113 100 20 40 1 1423621216 289269152 5290816
12:11:02 21587 8192000000 10072018544 10492219392 1134223632 12.161 100 46 89 0 1159492400 15561216 9707552
12:12:28 91910 8192000000 10107626480 10577068032 1143872336 12.252 100 30 60 1 1197316496 46445744 6998416
12:15:03 22105 8192000000 10108922912 10573836288 1149140304 12.301 100 40 80 1 1221274576 63168064 8966208
12:16:26 40613 8192000000 10133964256 10575036416 1128057376 12.103 99 43 87 0 1214226608 77107248 9061984
12:18:35 43970 8192000000 10074187056 10547601408 1135088432 12.169 100 27 51 1 1177783312 37476496 5218384
12:20:29 56070 8192000000 10095085264 10562166784 1142878384 12.243 100 14 29 0 1189094768 42656928 3559456
12:22:25 125644 8192000000 10103409568 10607644672 1138102864 12.198 99 14 28 0 1228622192 87484256 3035072
12:25:33 48822 8192000000 10101100848 10551787520 1118559840 12.013 98 12 24 0 1167021488 45962576 2499072
12:27:23 41881 8192000000 10124784288 10592591872 1132091264 12.141 99 20 40 0 1231464848 94679296 4694288
12:29:06 18525 8192000000 10121195504 10567483392 1145438688 12.267 100 16 32 1 1208936736 59704592 3793456
12:30:26 53388 8192000000 10109091136 10588880896 1135093824 12.169 99 19 37 4 1214475008 75268272 4112912
12:32:21 65109 8192000000 10137352752 10595729408 1144437072 12.257 99 29 58 1 1202318352 51606560 6274720
12:34:29 48389 8192000000 10078692160 10550255616 1121658784 12.043 98 15 30 0 1171692688 47174944 2858960
12:36:30 108139 8192000000 10105181232 10549653504 1104954192 11.885 97 19 38 0 1186790352 77461632 4374528
12:39:20 44931 8192000000 10096549872 10559979520 1134420512 12.163 100 20 40 0 1191657424 52962368 4274544
12:41:14 56858 8192000000 10075131904 10579873792 1115142208 11.981 98 4 8 0 1165926096 50147168 636720
12:43:11 46892 8192000000 10060069760 10535653376 1131045312 12.131 100 14 28 0 1173333776 39631424 2657040
12:45:07 23330 8192000000 10101334224 10534526976 1131526160 12.136 100 48 96 0 1193125120 51958464 9640496
12:46:33 95291 8192000000 10094989968 10590330880 1124851216 12.073 99 19 38 1 1164630544 35833712 3945616
12:49:33 62564 8192000000 10104781648 10599845888 1111686144 11.948 98 13 26 1 1193290368 79020176 2584048
12:52:29 24694 8192000000 10088815408 10521317376 1108309616 11.916 99 43 86 0 1154417312 37462800 8644896
12:53:59 40318 8192000000 10051150208 10492977152 1097619216 11.815 98 20 40 1 1137050528 35709456 3721856
12:55:43 54226 8192000000 10067971424 10537353216 1121367296 12.040 100 40 80 2 1172619168 42850912 8400960
12:58:04 66197 8192000000 10049451344 10516152320 1093707664 11.778 98 26 52 1 1130071920 31635984 4728272
13:00:11 41397 8192000000 10047755088 10552311808 1123338512 12.059 100 4 8 0 1160798960 36727072 733376
13:01:52 50067 8192000000 10046186608 10496864256 1095668464 11.797 98 11 22 0 1133760976 35639968 2452544
13:03:49 50491 8192000000 10059443392 10563002368 1094346000 11.784 98 12 24 0 1176165040 79093488 2725552
13:05:40 43127 8192000000 10066612752 10507927552 1095807536 11.798 98 24 49 0 1150911456 49827760 5276160
13:07:29 65323 8192000000 10115035856 10557931520 1106239136 11.897 97 26 52 3 1186241408 74929664 5072608
13:09:40 45514 8192000000 10103428608 10538840064 1117663824 12.005 98 27 54 1 1187488992 63745984 6079184
13:11:28 41838 8192000000 10077656848 10613895168 1118558208 12.013 98 20 40 0 1210624976 87716128 4350640
13:13:12 65412 8192000000 10100356736 10595868672 1132912032 12.149 99 22 44 0 1194417968 57231216 4274720
13:15:20 77243 8192000000 10108577536 10603790336 1125511968 12.079 98 16 32 0 1197062368 68309504 3240896
13:17:40 124068 8192000000 10090182464 10542882816 1132719136 12.147 99 30 59 0 1176321024 37298272 6303616
13:20:44 31633 8192000000 10047469296 10526699520 1115121488 11.981 98 11 22 0 1150833008 33523744 2187776
13:22:16 67556 8192000000 10097430320 10565079040 1121474512 12.041 98 11 22 0 1175291120 51356320 2460288
13:24:26 78544 8192000000 10140093296 10637250560 1097870880 11.817 96 17 34 0 1196978640 94910368 4197392
13:26:46 51213 8192000000 10077565680 10536177664 1094609648 11.786 96 18 36 0 1129306880 30839808 3857424
13:28:37 42345 8192000000 10085450128 10555105280 1111644192 11.948 99 27 54 1 1173953040 56949056 5359792
13:30:32 53148 8192000000 10074487728 10551091200 1101862672 11.855 98 32 64 1 1162047520 53473408 6711440
13:32:25 54821 8192000000 10042385408 10524913664 1094274912 11.783 99 18 36 0 1173224384 74630944 4318528
13:34:32 44698 8192000000 10116673568 10622312448 1129630480 12.118 100 23 46 0 1226039872 91645808 4763584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2021 13:38:11 to 04-10-2021 13:38:26.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2021 13:38:27 to 04-10-2021 13:38:29.

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          235377
MON$OLDEST_ACTIVE               236045
MON$OLDEST_SNAPSHOT             236045
MON$NEXT_TRANSACTION            236045
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                       2061472
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.2622 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2622 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2622 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 ---
:60: 
:61: mscs-test-161.budzdorov.ru    Mon Oct  4 13:37:13 2021
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Mon Oct  4 13:37:28 2021
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
--- end of diff output ---

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

04.10.21 13:38:30. 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-04 13:38:32 No severe PSQL-related problems occured
04.10.21 13:38:34. End of report.