Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2021 07:48
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 16697856 kB
MemAvailable 31404608 kB
Buffers 0 kB
Cached 14962872 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-12-18 07:48:18
DTS_END                         2021-12-18 07:48:18.7830
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/35472
TRN_ID                          231202
ATT_ID                          649
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: 8822.34 22945 1058681
customer order: creation 167.36 442 20083
customer order: refuse 14.86 339 1783
order to supplier: creation 46.21 1521 5545
order to supplier: removal 3.46 111623 415
invoice (draft): creation 44.21 3582 5305
invoice (draft): removal 50.19 998 6023
invoice accept: apply 40.10 18389 4812
invoice accept: cancel 45.23 812 5427
customer reserve: creation 3696.93 180 443632
customer reserve: removal 4432.29 68 531875
realization accept: apply 81.08 183 9730
realization accept: cancel 75.45 127 9054
payment from customer: creation 57.04 114 6845
payment from customer: removal 5.78 79 694
payment to supplier: creation 47.76 136 5731
payment to supplier: removal 3.29 61 395
service: total inventory turnovers 4.16 3071 499
service: total monetary turnovers 6.08 480 729
service: aggregate perf. data 0.83 14725 99
service: refresh index statistics 0.04 301963 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2021 07:48:53 to 18-12-2021 07:48:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3664
TEST_TIME 2 5021
TEST_TIME 3 4811
TEST_TIME 4 4597
TEST_TIME 5 4913
TEST_TIME 6 4793
TEST_TIME 7 5627
TEST_TIME 8 5533
TEST_TIME 9 5620
TEST_TIME 10 6027
TEST_TIME 11 6452
TEST_TIME 12 6482
TEST_TIME 13 6589
TEST_TIME 14 6815
TEST_TIME 15 7576
TEST_TIME 16 7872
TEST_TIME 17 8230
TEST_TIME 18 8184
TEST_TIME 19 8516
TEST_TIME 20 9015
TEST_TIME 21 9474
TEST_TIME 22 9461
TEST_TIME 23 9761
TEST_TIME 24 10165
TEST_TIME 25 11168
TEST_TIME 26 11174
TEST_TIME 27 10792
TEST_TIME 28 10687
TEST_TIME 29 10704
TEST_TIME 30 10410
TEST_TIME 31 10199
TEST_TIME 32 10002
TEST_TIME 33 10151
TEST_TIME 34 10056
TEST_TIME 35 9871
TEST_TIME 36 9862
TEST_TIME 37 9747
TEST_TIME 38 9666
TEST_TIME 39 9529
TEST_TIME 40 9399
TEST_TIME 41 9360
TEST_TIME 42 9334
TEST_TIME 43 9273
TEST_TIME 44 9197
TEST_TIME 45 9189
TEST_TIME 46 9175
TEST_TIME 47 9089
TEST_TIME 48 9115
TEST_TIME 49 9135
TEST_TIME 50 9182
TEST_TIME 51 9154
TEST_TIME 52 9136
TEST_TIME 53 9342
TEST_TIME 54 9331
TEST_TIME 55 9323
TEST_TIME 56 9282
TEST_TIME 57 9369
TEST_TIME 58 9327
TEST_TIME 59 9179
TEST_TIME 60 9088
TEST_TIME 61 9199
TEST_TIME 62 9096
TEST_TIME 63 9018
TEST_TIME 64 9006
TEST_TIME 65 9023
TEST_TIME 66 9041
TEST_TIME 67 9008
TEST_TIME 68 9095
TEST_TIME 69 9133
TEST_TIME 70 9141
TEST_TIME 71 9108
TEST_TIME 72 9107
TEST_TIME 73 9237
TEST_TIME 74 9220
TEST_TIME 75 9179
TEST_TIME 76 9258
TEST_TIME 77 9242
TEST_TIME 78 9221
TEST_TIME 79 9247
TEST_TIME 80 9142
TEST_TIME 81 9141
TEST_TIME 82 9059
TEST_TIME 83 9056
TEST_TIME 84 9025
TEST_TIME 85 8956
TEST_TIME 86 8889
TEST_TIME 87 8935
TEST_TIME 88 8890
TEST_TIME 89 8864
TEST_TIME 90 8918
TEST_TIME 91 8901
TEST_TIME 92 8851
TEST_TIME 93 8932
TEST_TIME 94 8937
TEST_TIME 95 8971
TEST_TIME 96 8986
TEST_TIME 97 9031
TEST_TIME 98 9069
TEST_TIME 99 9045
TEST_TIME 100 9110
TEST_TIME 101 9087
TEST_TIME 102 9030
TEST_TIME 103 8958
TEST_TIME 104 8961
TEST_TIME 105 8953
TEST_TIME 106 8913
TEST_TIME 107 8876
TEST_TIME 108 8854
TEST_TIME 109 8818
TEST_TIME 110 8812
TEST_TIME 111 8823
TEST_TIME 112 8790
TEST_TIME 113 8795
TEST_TIME 114 8826
TEST_TIME 115 8844
TEST_TIME 116 8833
TEST_TIME 117 8847
TEST_TIME 118 8862
TEST_TIME 119 8875
TEST_TIME 120 8826

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2021 07:48:57 to 18-12-2021 07:49:00.

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 1469168 1469168 0 0.00 0 14769 6 0 0
doc_list_biud 1469168 1469168 0 0.00 0 54924 31 0 0
sp_add_invoice_to_stock 4971 4812 159 3.19 41 230618 18390 0 159
sp_cancel_adding_invoice 5427 5427 0 0.00 6 72769 813 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 10339 340 0 0
sp_cancel_customer_reserve 531875 531875 0 0.00 1 37926 69 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 3805 80 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 2979 62 0 0
sp_cancel_supplier_invoice 6037 6023 14 0.23 3 54948 999 0 14
sp_cancel_supplier_order 415 415 0 0.00 9 1073916 111623 0 0
sp_cancel_write_off 9054 9054 0 0.00 1 11941 127 0 0
sp_client_order 20083 20083 0 0.00 4 43756 442 0 0
sp_customer_reserve 443632 443632 0 0.00 0 59757 181 0 0
sp_fill_shopping_cart 30930 30930 0 0.00 1 16990 47 0 0
sp_fill_shopping_cart_clo_res 443628 443628 0 0.00 0 15798 19 0 0
sp_get_clo_for_invoice 4806 4806 0 0.00 0 28902 430 0 0
sp_kill_cost_storno 15570 15570 0 0.00 0 11940 59 0 0
sp_kill_qstorno_ret_qs2qd 1079242 1079242 0 0.00 0 54922 37 0 0
sp_kill_qty_storno 569113 569113 0 0.00 0 54923 80 0 0
sp_lock_selected_doc 20328 20328 0 0.00 0 9768 4 0 0
sp_make_cost_storno 27112 27112 0 0.00 0 14760 51 0 0
sp_multiply_rows_for_pdistr 27112 27112 0 0.00 0 11040 32 0 0
sp_multiply_rows_for_qdistr 20083 20083 0 0.00 0 14465 242 0 0
sp_pay_from_customer 6845 6845 0 0.00 4 9467 115 0 0
sp_pay_to_supplier 5731 5731 0 0.00 5 11228 136 0 0
sp_payment_common 12576 12576 0 0.00 1 9447 21 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 10332 304 0 0
sp_qd_handle_on_invoice_upd_sts 10233 10233 0 0.00 0 33641 356 0 0
sp_qd_handle_on_reserve_upd_sts 9730 9730 0 0.00 0 15652 53 0 0
sp_reserve_write_off 9730 9730 0 0.00 7 18099 183 0 0
sp_supplier_invoice 5305 5305 0 0.00 6 94419 3582 0 0
sp_supplier_order 5545 5545 0 0.00 6 40471 1522 0 0
srv_aggregate_perf_data 99 99 0 0.00 2960 142408 14725 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2931 145081 47299 0 0
srv_find_qd_qs_mism 1012311 1012311 0 0.00 0 14544 5 0 0
srv_make_invnt_saldo 499 499 0 0.00 45 102370 3071 0 0
srv_make_money_saldo 729 729 0 0.00 1 15148 480 0 0
srv_recalc_idx_stat 5 5 0 0.00 221290 375750 301963 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4812 4812 0 0.00 0 14173 160 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 4992 100 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 4261 16 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 868 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 2417 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 136 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2512 17 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 37688 1536 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2404 7 0 0
t$perf-norm:sp_client_order 20083 20083 0 0.00 0 15635 18 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 4392 12 0 0
t$perf-norm:sp_pay_from_customer 6845 6845 0 0.00 0 17623 11 0 0
t$perf-norm:sp_pay_to_supplier 5731 5731 0 0.00 0 16647 13 0 0
t$perf-norm:sp_reserve_write_off 9730 9730 0 0.00 0 34772 13 0 0
t$perf-norm:sp_supplier_invoice 5305 5305 0 0.00 0 9777 18 0 0
t$perf-norm:sp_supplier_order 5545 5545 0 0.00 0 11699 20 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1025 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 183 3 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 5660 20 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 293 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 78 18 0 0
v_add_invoice_to_stock 9930 9612 318 3.20 0 4116 20 0 318
v_all_customers 36158 36158 0 0.00 0 15 1 0 0
v_all_suppliers 30054 30054 0 0.00 0 27 1 0 0
v_all_wares 40164 40164 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1201 8 0 0
v_cancel_client_order 3566 3566 0 0.00 0 3062 8 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3735 9 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 4653 8 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 2972 9 0 0
v_cancel_supplier_invoice 792 764 28 3.53 0 2513 19 0 28
v_cancel_supplier_order 830 830 0 0.00 0 5710 18 0 0
v_cancel_write_off 2028 2028 0 0.00 0 1856 12 0 0
v_max_id_clo_ord 5545 5545 0 0.00 0 3566 2 0 0
v_max_id_clo_res 975 975 0 0.00 0 2458 17 0 0
v_max_id_ord_sup 5303 5303 0 0.00 0 5388 9 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 3082 4 0 0
v_max_non_paid_realizn 3794 3794 0 0.00 0 736 3 0 0
v_min_id_clo_ord 5545 5545 0 0.00 0 8569 15 0 0
v_min_id_clo_res 975 975 0 0.00 0 650 3 0 0
v_min_id_ord_sup 5303 5303 0 0.00 0 5046 10 0 0
v_min_non_paid_invoice 5731 5731 0 0.00 0 4353 11 0 0
v_min_non_paid_realizn 6845 6845 0 0.00 0 4632 13 0 0
v_our_firm 10108 10108 0 0.00 0 15 1 0 0
v_reserve_write_off 19460 19460 0 0.00 0 4681 8 0 0
x_lock_depdocs_on_canc_invoice 11068 11068 0 0.00 0 16034 112 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 20527 347 0 0
x_make_qstorno_1000_1200 5545 5545 0 0.00 2 40458 1433 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 3 94411 3403 0 0
x_make_qstorno_2100_3300 443632 443632 0 0.00 0 56124 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2021 07:49:01 to 18-12-2021 07:49:05.

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:18:22 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:24 23579 8192000000 9722808048 10018709504 801046480 8.907 100 91 182 0 832134432 7989552 23098400
05:20:51 16014 8192000000 9760858304 10067189760 834415504 9.244 100 91 182 0 868219232 11731504 22072224
05:22:12 4240 8192000000 9798737872 10112417792 868050672 9.581 100 90 180 0 908284128 19251216 20982240
05:23:19 19150 8192000000 9865288864 10197688320 914298096 10.040 100 77 154 0 975347760 44346720 16702944
05:24:42 8404 8192000000 9903106720 10247532544 950934192 10.400 100 68 136 1 1017914800 52475744 14504864
05:25:54 5784 8192000000 9956405360 10309775360 995273616 10.833 100 72 144 0 1062251104 52431936 14545552
05:27:04 2285 8192000000 10007339456 10372239360 1025112096 11.121 100 87 174 0 1125632016 82536848 17983072
05:28:08 2082 8192000000 10025178064 10393059328 1051948016 11.379 100 87 172 0 1143562320 74519696 17094608
05:29:13 1589 8192000000 10036869200 10406428672 1066346512 11.517 100 92 185 0 1149240256 64642304 18251440
05:30:16 2046 8192000000 10038317296 10421985280 1069778336 11.550 100 64 128 0 1153618592 71010000 12830256
05:31:19 3350 8192000000 10029170768 10411040768 1082413936 11.670 100 62 127 1 1143337520 48572944 12350640
05:32:23 38106 8192000000 10028398432 10418515968 1090072896 11.743 100 61 121 1 1137864016 35384880 12406240
05:34:02 5728 8192000000 10050598928 10448084992 1095887408 11.799 100 52 104 1 1149309280 42470032 10951840
05:35:08 10422 8192000000 10030503072 10435477504 1104162576 11.877 100 40 79 0 1133176480 20827184 8186720
05:36:24 8437 8192000000 10011912768 10419134464 1098291872 11.821 100 27 53 1 1126034624 21964656 5778096
05:37:33 59921 8192000000 10005763664 10413686784 1083812032 11.684 98 28 57 2 1100675376 10861072 6002272
05:39:37 5449 8192000000 9976553936 10374029312 1049310416 11.354 98 47 94 3 1077335696 17325568 10699712
05:40:48 8981 8192000000 9930081280 10315403264 992988368 10.810 96 33 62 1 1032306752 32293568 7024816
05:41:57 68071 8192000000 9936839392 10339647488 983503248 10.718 95 27 53 2 1009557168 20495696 5558224
05:44:06 6008 8192000000 9942211168 10328846336 1038198112 11.247 100 34 67 0 1059158736 12875712 8084912
05:45:12 9510 8192000000 9985079328 10380292096 1050888912 11.369 100 40 80 2 1082632368 23355552 8387904
05:46:22 7050 8192000000 9974400912 10364796928 1059463136 11.451 100 22 44 1 1082732848 18629488 4640224
05:47:32 48872 8192000000 9993826368 10394021888 1068714320 11.540 100 27 53 0 1097528224 23402512 5411392
05:49:29 45172 8192000000 10062218912 10464665600 1097600672 11.815 100 45 88 0 1144737664 38170256 8966736
05:51:18 27536 8192000000 10089346224 10494164992 1117673248 12.005 100 36 72 0 1200309632 74297808 8338576
05:52:49 26273 8192000000 10136168224 10555219968 1135234736 12.171 100 46 92 0 1259678688 114237568 10206384
05:54:22 24442 8192000000 10204298288 10610688000 1148679072 12.297 100 49 96 1 1315166240 155921392 10565776
05:56:03 25523 8192000000 10203602128 10615152640 1155533104 12.361 100 44 87 0 1302820816 137066768 10220944
05:57:35 6047 8192000000 10244477824 10659135488 1167782112 12.476 100 60 119 1 1361251856 180262288 13207456
05:58:46 4192 8192000000 10323211072 10730573824 1178266112 12.574 100 71 142 1 1421538896 227922256 15350528
05:59:55 6596 8192000000 10352758272 10760568832 1187036480 12.656 100 70 140 0 1451949216 249661136 15251600
06:01:09 14402 8192000000 10392229728 10809614336 1181914208 12.608 100 66 132 1 1508774096 312096960 14762928
06:02:30 5828 8192000000 10464799328 10876407808 1176381280 12.556 100 76 152 3 1562706704 368249360 18076064
06:03:40 8827 8192000000 10439080256 10872041472 1167596368 12.474 100 68 134 0 1533001744 349693392 15711984
06:04:56 10283 8192000000 10420429632 10839441408 1150819872 12.317 99 43 86 0 1514505024 352150528 11534624
06:06:12 3381 8192000000 10442212128 10856300544 1166814976 12.467 100 69 138 2 1545193344 361696208 16682160
06:07:24 2931 8192000000 10465386832 10907828224 1161134064 12.414 100 82 162 2 1568127072 386755328 20237680
06:08:31 11116 8192000000 10419096912 10882719744 1166110144 12.460 100 41 82 0 1516542512 339150944 11281424
06:09:51 22741 8192000000 10406825744 10848100352 1153254128 12.340 99 34 68 1 1501281472 339327136 8700208
06:11:16 15035 8192000000 10380053568 10838675456 1153147808 12.339 100 23 46 1 1488414064 329011376 6254880
06:13:03 45523 8192000000 10146260672 10660220928 1079713920 11.645 95 33 68 0 1335231744 248170640 7347184
06:15:07 38843 8192000000 10103892592 10535776256 1136118624 12.179 100 40 76 0 1211801008 67294576 8387808
06:16:48 37935 8192000000 10123144192 10604752896 1120363520 12.030 98 27 53 1 1210406416 84160208 5882688
06:18:29 86793 8192000000 10129727376 10583945216 1126951808 12.093 99 13 27 0 1231839888 101938736 2949344
06:21:01 48334 8192000000 10106290144 10595266560 1141897344 12.233 100 22 43 1 1210003968 63056800 5049824
06:22:50 49721 8192000000 10088453808 10532835328 1144109008 12.254 99 22 44 1 1176091376 27207024 4775344
06:24:41 51063 8192000000 10083778192 10524459008 1110969120 11.942 96 33 65 0 1162020928 44404288 6647520
06:26:36 77542 8192000000 10100486304 10550607872 1105173920 11.887 96 23 46 0 1179000928 69221936 4605072
06:28:54 59054 8192000000 10137789856 10596380672 1141405952 12.229 99 23 46 0 1225321280 78650496 5264832
06:31:22 64619 8192000000 10069788720 10519625728 1105605472 11.891 97 40 78 2 1156145280 41946112 8593696
06:33:27 62592 8192000000 10059606928 10544295936 1120493184 12.032 98 8 16 0 1149018928 27216016 1309728
06:35:33 32596 8192000000 10106735856 10547539968 1145211232 12.265 99 41 80 2 1200526240 46757968 8557040
06:37:06 51593 8192000000 10093092320 10549456896 1114615360 11.976 97 14 28 0 1152946432 35536720 2794352
06:38:58 40016 8192000000 10096439456 10552143872 1148834608 12.299 100 18 36 0 1199184016 46730032 3619376
06:40:39 67604 8192000000 10108752112 10610774016 1140774688 12.223 98 16 32 0 1265786736 121254768 3757280
06:42:59 67545 8192000000 10085995264 10529181696 1118158016 12.010 97 34 66 3 1142138304 17469472 6510816
06:45:10 41527 8192000000 10130421472 10627989504 1149278608 12.303 100 36 72 1 1213078880 56807952 6992320
06:46:52 145081 8192000000 10132964496 10580279296 1133681120 12.156 98 28 56 1 1208001232 68253216 6066896
06:50:36 30547 8192000000 10054065632 10525229056 1133447008 12.154 99 8 16 0 1174357504 39736976 1173520
06:52:11 55059 8192000000 10105831760 10550059008 1129287248 12.115 99 17 35 1 1189364208 56490656 3586304
06:54:07 42165 8192000000 10084467280 10513530880 1097227856 11.811 96 31 62 0 1162239696 58869184 6142656
06:55:57 100219 8192000000 10066467424 10566066176 1113795104 11.968 99 16 32 0 1217084144 99872112 3416928
06:58:37 54545 8192000000 10070339248 10505699328 1094114640 11.782 97 28 56 0 1128839264 28639616 6085008
07:00:32 30441 8192000000 10147784720 10592718848 1098680784 11.825 97 38 77 1 1203988224 97371984 7935456
07:02:27 41794 8192000000 10073511312 10495262720 1108636176 11.920 98 24 48 0 1143172352 29805168 4731008
07:04:09 33567 8192000000 10082068528 10570829824 1127031552 12.093 100 32 64 0 1194372928 61029088 6312288
07:05:44 83585 8192000000 10126778704 10557906944 1119893696 12.026 99 18 36 0 1181423728 58194496 3335536
07:08:12 88970 8192000000 10057796560 10524733440 1109038896 11.923 98 16 32 0 1165538688 53059072 3440720
07:11:37 46344 8192000000 10060588640 10540052480 1124128304 12.066 99 12 24 0 1181773056 54952032 2692720
07:13:24 42616 8192000000 10089025888 10531827712 1138385904 12.200 100 14 28 0 1192012752 50728848 2898000
07:15:07 80117 8192000000 10087779216 10593173504 1126136608 12.085 99 14 28 0 1180105760 50972432 2996720
07:17:53 54187 8192000000 10049236128 10519068672 1102752368 11.864 97 18 34 2 1157968208 50848512 4367328
07:19:48 43596 8192000000 10106493536 10546892800 1114386336 11.974 98 32 64 0 1187437584 65891904 7159344
07:21:37 81933 8192000000 10105509568 10588028928 1118256736 12.011 98 13 25 2 1182519600 61633200 2629664
07:24:02 56391 8192000000 10087638112 10537152512 1093291776 11.774 96 19 38 0 1158000864 60996880 3712208
07:26:00 102330 8192000000 10072040432 10538414080 1069943824 11.552 95 11 22 0 1120021824 48134096 1943904
07:28:43 76022 8192000000 10081413712 10527088640 1125692992 12.081 100 19 37 2 1177873216 48716912 3463312
07:31:07 73130 8192000000 10096233088 10558214144 1133635344 12.156 99 25 48 1 1186941216 48003664 5302208
07:33:22 72460 8192000000 10040996112 10511405056 1106482240 11.899 97 8 16 0 1131124784 23451888 1190656
07:35:35 61333 8192000000 10056578432 10513289216 1127261616 12.096 99 20 40 1 1171092480 38917776 4913088
07:37:49 61026 8192000000 10077830160 10545086464 1107664368 11.910 97 23 46 0 1166166880 52883200 5619312
07:39:51 43446 8192000000 10092700544 10529054720 1130584528 12.127 99 25 50 0 1172936576 36625936 5726112
07:41:40 64433 8192000000 10106310288 10554834944 1129572656 12.117 99 20 40 0 1212562272 78152832 4836784
07:43:45 64041 8192000000 10082154768 10533224448 1115532096 11.985 98 30 60 1 1178307264 55592928 7182240
07:45:52 34783 8192000000 10098492928 10536583168 1101412592 11.851 97 36 72 1 1145416464 37018560 6985312
07:47:35 30767 8192000000 10092412208 10541629440 1104062288 11.876 98 53 103 2 1158991200 43994624 10934288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2021 07:49:07 to 18-12-2021 07:49:22.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2021 07:49:23 to 18-12-2021 07:49:25.

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          229487
MON$OLDEST_ACTIVE               231439
MON$OLDEST_SNAPSHOT             231439
MON$NEXT_TRANSACTION            231439
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                       2024576
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2692 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2692 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2692 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2692 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    Sat Dec 18 07:48:23 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Dec 18 07:48:26 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

18.12.21 07:49:26. 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-12-18 07:49:28 No severe PSQL-related problems occured
18.12.21 07:49:30. End of report.