Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2021 07: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 15898688 kB
MemAvailable 30653312 kB
Buffers 0 kB
Cached 15781336 kB
SwapCached 3784 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-14 07:37:07
DTS_END                         2021-10-14 07:37:07.5800
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/39582
TRN_ID                          231142
ATT_ID                          637
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: 8784.62 24069 1054154
customer order: creation 163.13 457 19575
customer order: refuse 14.17 329 1700
order to supplier: creation 43.70 1464 5244
order to supplier: removal 3.27 123415 392
invoice (draft): creation 42.63 3785 5115
invoice (draft): removal 50.74 983 6089
invoice accept: apply 38.59 19571 4631
invoice accept: cancel 46.21 930 5545
customer reserve: creation 3588.47 191 430616
customer reserve: removal 4502.00 70 540240
realization accept: apply 79.20 191 9504
realization accept: cancel 90.76 119 10891
payment from customer: creation 55.70 119 6684
payment from customer: removal 5.39 112 647
payment to supplier: creation 47.03 133 5644
payment to supplier: removal 3.21 68 385
service: total inventory turnovers 3.67 3634 440
service: total monetary turnovers 5.89 611 707
service: aggregate perf. data 0.84 18565 101
service: refresh index statistics 0.03 306635 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2021 07:37:56 to 14-10-2021 07:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6941
TEST_TIME 2 5131
TEST_TIME 3 5041
TEST_TIME 4 4645
TEST_TIME 5 4656
TEST_TIME 6 4927
TEST_TIME 7 5223
TEST_TIME 8 5379
TEST_TIME 9 5450
TEST_TIME 10 5695
TEST_TIME 11 5970
TEST_TIME 12 6420
TEST_TIME 13 6440
TEST_TIME 14 6844
TEST_TIME 15 6982
TEST_TIME 16 7425
TEST_TIME 17 7419
TEST_TIME 18 7934
TEST_TIME 19 8269
TEST_TIME 20 8567
TEST_TIME 21 8739
TEST_TIME 22 8943
TEST_TIME 23 9424
TEST_TIME 24 9258
TEST_TIME 25 9621
TEST_TIME 26 10108
TEST_TIME 27 10817
TEST_TIME 28 10444
TEST_TIME 29 10792
TEST_TIME 30 10549
TEST_TIME 31 10482
TEST_TIME 32 10226
TEST_TIME 33 10234
TEST_TIME 34 10183
TEST_TIME 35 9903
TEST_TIME 36 9924
TEST_TIME 37 9781
TEST_TIME 38 9618
TEST_TIME 39 9494
TEST_TIME 40 9399
TEST_TIME 41 9492
TEST_TIME 42 9487
TEST_TIME 43 9395
TEST_TIME 44 9292
TEST_TIME 45 9168
TEST_TIME 46 9096
TEST_TIME 47 9154
TEST_TIME 48 9119
TEST_TIME 49 9158
TEST_TIME 50 9151
TEST_TIME 51 9150
TEST_TIME 52 9194
TEST_TIME 53 9284
TEST_TIME 54 9334
TEST_TIME 55 9360
TEST_TIME 56 9400
TEST_TIME 57 9318
TEST_TIME 58 9440
TEST_TIME 59 9459
TEST_TIME 60 9393
TEST_TIME 61 9270
TEST_TIME 62 9197
TEST_TIME 63 9176
TEST_TIME 64 9137
TEST_TIME 65 9208
TEST_TIME 66 9126
TEST_TIME 67 9134
TEST_TIME 68 9055
TEST_TIME 69 9018
TEST_TIME 70 8932
TEST_TIME 71 8861
TEST_TIME 72 8891
TEST_TIME 73 9018
TEST_TIME 74 9042
TEST_TIME 75 8964
TEST_TIME 76 8981
TEST_TIME 77 9063
TEST_TIME 78 9083
TEST_TIME 79 9118
TEST_TIME 80 9190
TEST_TIME 81 9172
TEST_TIME 82 9208
TEST_TIME 83 9176
TEST_TIME 84 9092
TEST_TIME 85 9075
TEST_TIME 86 9072
TEST_TIME 87 9036
TEST_TIME 88 8976
TEST_TIME 89 8963
TEST_TIME 90 8947
TEST_TIME 91 8916
TEST_TIME 92 8853
TEST_TIME 93 8875
TEST_TIME 94 8844
TEST_TIME 95 8832
TEST_TIME 96 8832
TEST_TIME 97 8870
TEST_TIME 98 8849
TEST_TIME 99 8900
TEST_TIME 100 8899
TEST_TIME 101 8928
TEST_TIME 102 8916
TEST_TIME 103 8932
TEST_TIME 104 8947
TEST_TIME 105 8956
TEST_TIME 106 8949
TEST_TIME 107 8938
TEST_TIME 108 8893
TEST_TIME 109 8895
TEST_TIME 110 8850
TEST_TIME 111 8821
TEST_TIME 112 8803
TEST_TIME 113 8783
TEST_TIME 114 8775
TEST_TIME 115 8769
TEST_TIME 116 8794
TEST_TIME 117 8793
TEST_TIME 118 8833
TEST_TIME 119 8855
TEST_TIME 120 8786

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2021 07:38:00 to 14-10-2021 07:38:03.

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 1453526 1453526 0 0.00 0 23252 7 0 0
doc_list_biud 1453526 1453526 0 0.00 0 42021 33 0 0
sp_add_invoice_to_stock 4744 4631 113 2.38 33 215203 19572 0 113
sp_cancel_adding_invoice 5545 5545 0 0.00 4 81423 930 0 0
sp_cancel_client_order 1700 1700 0 0.00 5 19938 330 0 0
sp_cancel_customer_reserve 540240 540240 0 0.00 1 42024 71 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 13790 113 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 2801 68 0 0
sp_cancel_supplier_invoice 6101 6089 12 0.19 2 14154 983 0 12
sp_cancel_supplier_order 392 392 0 0.00 7 976832 123415 0 0
sp_cancel_write_off 10891 10891 0 0.00 1 23254 119 0 0
sp_client_order 19575 19575 0 0.00 4 42978 458 0 0
sp_customer_reserve 430616 430616 0 0.00 0 36729 191 0 0
sp_fill_shopping_cart 29925 29925 0 0.00 1 34756 49 0 0
sp_fill_shopping_cart_clo_res 430614 430614 0 0.00 0 18104 18 0 0
sp_get_clo_for_invoice 4628 4628 0 0.00 0 25956 425 0 0
sp_kill_cost_storno 17468 17468 0 0.00 0 23250 69 0 0
sp_kill_qstorno_ret_qs2qd 1097852 1097852 0 0.00 0 34387 38 0 0
sp_kill_qty_storno 578989 578989 0 0.00 0 42019 82 0 0
sp_lock_selected_doc 19610 19610 0 0.00 0 5567 3 0 0
sp_make_cost_storno 26460 26460 0 0.00 0 19066 60 0 0
sp_multiply_rows_for_pdistr 26460 26460 0 0.00 0 11242 39 0 0
sp_multiply_rows_for_qdistr 19575 19575 0 0.00 0 42926 261 0 0
sp_pay_from_customer 6684 6684 0 0.00 3 13418 119 0 0
sp_pay_to_supplier 5644 5644 0 0.00 5 15677 133 0 0
sp_payment_common 12328 12328 0 0.00 1 7270 22 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 19922 305 0 0
sp_qd_handle_on_invoice_upd_sts 10173 10173 0 0.00 0 25906 382 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 10743 57 0 0
sp_reserve_write_off 9504 9504 0 0.00 8 19039 192 0 0
sp_supplier_invoice 5115 5115 0 0.00 7 92078 3785 0 0
sp_supplier_order 5244 5244 0 0.00 6 76161 1465 0 0
srv_aggregate_perf_data 101 101 0 0.00 2827 291881 18565 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2049 133723 47038 0 0
srv_find_qd_qs_mism 1009582 1009582 0 0.00 0 19125 6 0 0
srv_make_invnt_saldo 440 440 0 0.00 72 120215 3635 0 0
srv_make_money_saldo 707 707 0 0.00 1 14985 611 0 0
srv_recalc_idx_stat 4 4 0 0.00 166844 390883 306636 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4631 4631 0 0.00 0 11599 168 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 4855 106 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 3797 16 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 2148 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 8568 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 392 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 831 13 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 26193 1758 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 7408 20 0 0
t$perf-norm:sp_client_order 19575 19575 0 0.00 0 6541 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1663 7 0 0
t$perf-norm:sp_pay_from_customer 6684 6684 0 0.00 0 5476 10 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 7406 15 0 0
t$perf-norm:sp_reserve_write_off 9504 9504 0 0.00 0 13007 10 0 0
t$perf-norm:sp_supplier_invoice 5115 5115 0 0.00 0 5427 17 0 0
t$perf-norm:sp_supplier_order 5244 5244 0 0.00 0 4848 14 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 4542 65 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2862 47 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 1196 13 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 544 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 4 3 0 0
v_add_invoice_to_stock 9476 9250 226 2.38 0 7800 21 0 226
v_all_customers 36054 36054 0 0.00 0 22 1 0 0
v_all_suppliers 29152 29152 0 0.00 0 39 1 0 0
v_all_wares 39150 39150 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 3090 13 0 0
v_cancel_client_order 3400 3400 0 0.00 0 2530 6 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 6527 11 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 2932 10 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 724 5 0 0
v_cancel_supplier_invoice 786 762 24 3.05 0 373 13 0 24
v_cancel_supplier_order 784 784 0 0.00 0 4895 13 0 0
v_cancel_write_off 1884 1884 0 0.00 0 1056 14 0 0
v_max_id_clo_ord 5242 5242 0 0.00 0 337 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 4404 16 0 0
v_max_id_ord_sup 5106 5106 0 0.00 0 5927 7 0 0
v_max_non_paid_invoice 1416 1416 0 0.00 0 4804 5 0 0
v_max_non_paid_realizn 3377 3377 0 0.00 0 5230 7 0 0
v_min_id_clo_ord 5242 5242 0 0.00 0 4174 10 0 0
v_min_id_clo_res 974 974 0 0.00 0 7042 39 0 0
v_min_id_ord_sup 5106 5106 0 0.00 0 5909 11 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 5123 11 0 0
v_min_non_paid_realizn 6684 6684 0 0.00 0 5135 14 0 0
v_our_firm 9710 9710 0 0.00 0 18 1 0 0
v_reserve_write_off 19008 19008 0 0.00 0 5124 7 0 0
x_lock_depdocs_on_canc_invoice 11253 11253 0 0.00 0 29110 119 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 28034 397 0 0
x_make_qstorno_1000_1200 5244 5244 0 0.00 2 76152 1377 0 0
x_make_qstorno_1200_2000 5115 5115 0 0.00 3 91728 3599 0 0
x_make_qstorno_2100_3300 430616 430616 0 0.00 0 36133 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2021 07:38:04 to 14-10-2021 07:38:08.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:12 37022 8192000000 9717362576 10014695424 795139200 8.847 100 91 182 0 825050032 7657472 22253360
05:09:51 17610 8192000000 9747537664 10049822720 818851280 9.087 100 88 176 0 853669296 13484224 21333792
05:11:15 13524 8192000000 9794374384 10111942656 852643040 9.427 100 78 155 1 902023264 30757552 18622672
05:12:32 9069 8192000000 9868930512 10203807744 919424768 10.090 100 73 146 0 979697024 44595072 15677184
05:13:47 9231 8192000000 9926416880 10271657984 956625120 10.456 100 75 150 0 1041680560 69132912 15922528
05:14:59 4352 8192000000 9964988432 10319675392 998532816 10.864 100 87 170 1 1079980192 63978704 17468672
05:16:05 2328 8192000000 9979862832 10340175872 1025531264 11.125 100 87 172 1 1104725856 61730848 17463744
05:17:09 2234 8192000000 10032763744 10400468992 1052270736 11.382 100 91 183 3 1147987312 77151792 18564784
05:18:12 11521 8192000000 10032985280 10409607168 1065835808 11.512 100 80 160 0 1148190352 66434864 15919680
05:19:24 4775 8192000000 9987758576 10381463552 1071036976 11.562 100 30 60 0 1109156128 31912736 6206416
05:20:29 3852 8192000000 9979664416 10376200192 1079301472 11.641 100 50 99 2 1099138352 8213232 11623648
05:21:34 18726 8192000000 9989819472 10399514624 1083959312 11.685 100 31 62 1 1109425872 17874992 7591568
05:22:53 4975 8192000000 10010232336 10405384192 1085565600 11.700 100 57 114 1 1116474320 17892128 13016592
05:23:59 5533 8192000000 10010715536 10416549888 1091729504 11.759 100 51 103 0 1126263072 23441792 11091776
05:25:08 3378 8192000000 10018572672 10416209920 1094567248 11.786 100 51 100 2 1125819120 20024528 11227344
05:26:12 57736 8192000000 9989552496 10402856960 1055190928 11.410 97 48 95 2 1085057296 17989168 11877200
05:28:10 3681 8192000000 9981792896 10367180800 1062678272 11.482 100 40 81 0 1097563936 26339312 8546352
05:29:18 7650 8192000000 9956298448 10347876352 1031203408 11.180 99 35 70 0 1065358368 26620464 7534496
05:30:26 4769 8192000000 9939346240 10322952192 1025937680 11.129 100 34 65 0 1046741920 13152496 7651744
05:31:31 14043 8192000000 9944243568 10338004992 998507120 10.864 97 22 44 0 1023830656 19936912 5386624
05:32:49 7434 8192000000 9938833632 10336686080 1018436656 11.057 98 30 59 2 1044699760 19012944 7250160
05:33:58 7666 8192000000 9973590096 10369654784 1057848768 11.436 100 45 90 2 1087477952 19726240 9902944
05:35:09 75554 8192000000 9965148544 10379034624 1065152848 11.506 100 47 94 0 1089410432 13567376 10690208
05:37:27 2091 8192000000 10016294304 10400243712 1086216608 11.707 100 73 147 1 1118018144 14907840 16893696
05:38:37 6897 8192000000 10037163488 10427600896 1099813920 11.836 100 57 112 0 1143672224 32364112 11494192
05:39:46 33673 8192000000 10084021744 10484260864 1117247456 12.001 100 54 106 4 1191727344 62613680 11866208
05:41:24 49809 8192000000 10140763328 10554351616 1128170528 12.104 100 42 84 1 1222819936 85357984 9291424
05:43:18 18334 8192000000 10136149008 10549301248 1139443984 12.210 100 35 70 0 1253382544 105683840 8254720
05:44:44 19409 8192000000 10180870368 10609627136 1151837184 12.327 100 40 79 1 1295173056 134504768 8831104
05:46:11 10485 8192000000 10207383120 10617360384 1155883840 12.365 100 59 118 2 1312996464 144560992 12551632
05:47:27 8521 8192000000 10225946032 10637316096 1155562112 12.362 100 63 126 2 1330580336 161429216 13589008
05:48:38 3093 8192000000 10271414176 10679283712 1168209600 12.480 100 75 148 0 1376923200 192670272 16043328
05:49:44 4550 8192000000 10300073408 10700169216 1165822640 12.458 100 84 168 1 1396816944 211020128 19974176
05:50:58 3997 8192000000 10323722944 10728857600 1174381888 12.538 100 69 138 0 1423804464 233099968 16322608
05:52:07 2583 8192000000 10350901808 10757857280 1173363680 12.528 99 77 154 2 1453829072 262783184 17682208
05:53:15 5803 8192000000 10365758528 10788306944 1164436800 12.445 100 73 145 0 1472364496 290806624 17121072
05:54:30 2046 8192000000 10402783184 10803556352 1157820656 12.383 100 87 175 0 1504100320 325817776 20461888
05:55:39 2346 8192000000 10409861424 10810802176 1155355712 12.360 100 81 162 0 1503032768 328985296 18691760
05:56:45 13569 8192000000 10390713776 10818949120 1153789104 12.345 100 40 80 0 1489194544 324717728 10687712
05:58:04 6129 8192000000 10363010448 10795032576 1145791744 12.270 99 58 117 0 1479823168 319133232 14898192
05:59:15 5064 8192000000 10367658304 10808918016 1145972080 12.272 99 41 81 0 1461334016 305611808 9750128
06:00:31 10960 8192000000 10368946112 10806951936 1144829488 12.261 100 38 75 0 1461280864 306910816 9540560
06:02:07 19966 8192000000 10313366400 10775519232 1088883904 11.732 97 17 34 1 1424054576 330064000 5106672
06:03:33 14243 8192000000 10229143216 10675572736 1107571184 11.909 99 35 68 2 1335939696 219563984 8804528
06:04:48 55979 8192000000 10110408400 10527727616 1094253152 11.783 98 47 95 2 1186995056 81160416 11581488
06:06:50 12845 8192000000 10031654976 10479013888 1105887968 11.893 100 15 30 0 1152732592 43990016 2854608
06:08:24 76778 8192000000 10107797520 10569441280 1116007184 11.989 100 12 24 0 1160191952 42392272 1792496
06:10:41 117431 8192000000 10061078864 10499805184 1117058832 11.999 99 24 49 1 1210063536 87591360 5413344
06:13:41 121084 8192000000 10094040448 10541993984 1099259536 11.831 97 11 22 0 1143818480 42545664 2013280
06:17:21 74213 8192000000 10135126384 10620891136 1132902496 12.149 99 45 89 0 1223481104 81360064 9218544
06:19:48 58386 8192000000 10093541264 10572615680 1141361648 12.228 100 14 28 0 1209425680 64927472 3136560
06:21:50 56553 8192000000 10110696800 10557992960 1135201776 12.170 99 9 18 0 1210884320 73868384 1814160
06:23:52 58832 8192000000 10096052768 10592890880 1135874800 12.177 99 16 32 0 1205179632 65910176 3394656
06:25:57 48138 8192000000 10126969632 10611228672 1158829040 12.392 100 11 22 0 1222223216 61328912 2065264
06:27:50 62655 8192000000 10145099024 10603323392 1159875696 12.402 100 35 68 2 1264084848 97254096 6955056
06:30:02 118495 8192000000 10127164128 10608615424 1172710320 12.522 100 10 20 0 1276305712 101579296 2016096
06:33:03 73072 8192000000 10091188064 10529935360 1158628912 12.390 99 22 44 1 1194388928 31503776 4256240
06:35:20 98744 8192000000 10117034576 10599493632 1141407808 12.229 98 15 30 0 1214321008 70030464 2882736
06:37:59 91455 8192000000 10131654992 10581749760 1155050128 12.357 99 40 77 1 1232379920 69363136 7966656
06:40:44 43924 8192000000 10118781024 10552303616 1108414080 11.917 96 29 58 0 1193084416 78946176 5724160
06:42:29 73900 8192000000 10069619888 10508091392 1110533200 11.937 97 12 24 1 1163787856 49842464 3412192
06:44:50 31215 8192000000 10067390544 10544074752 1115152512 11.981 98 6 12 0 1176739712 59752400 1834800
06:46:21 29535 8192000000 10077720784 10547417088 1123299328 12.058 99 7 14 0 1174869664 50002992 1567344
06:47:51 58269 8192000000 10134234864 10573185024 1130561456 12.127 100 30 60 1 1235488416 98680032 6246928
06:50:08 56603 8192000000 10042770624 10519359488 1044889888 11.312 93 6 12 0 1148756320 102389456 1476976
06:52:10 47547 8192000000 10145430896 10573770752 1110278112 11.935 98 36 72 0 1205384864 87536048 7570704
06:53:58 97422 8192000000 10108189280 10573426688 1081303696 11.660 96 14 28 0 1171919904 87499152 3117056
06:56:35 50627 8192000000 10074989072 10557612032 1072537312 11.576 95 32 64 1 1124530944 45813072 6180560
06:58:26 31912 8192000000 10081795760 10517843968 1126736784 12.091 100 26 52 1 1170001808 38354736 4910288
07:00:18 23273 8192000000 10096515808 10520596480 1107972288 11.913 98 44 88 1 1173755104 56884064 8898752
07:01:45 70690 8192000000 10069646064 10516357120 1093663120 11.777 98 27 53 2 1188548736 89178400 5707216
07:03:56 44382 8192000000 10033417872 10471714816 1104431856 11.880 99 11 22 0 1129787888 23363792 1992240
07:05:50 91407 8192000000 10080261968 10547392512 1097759136 11.816 98 16 32 2 1170925248 69041696 4124416
07:08:26 94642 8192000000 10072142480 10499747840 1095762688 11.797 97 21 42 1 1155305712 55588736 3954288
07:11:00 46370 8192000000 10055307536 10492825600 1116806736 11.997 99 19 38 1 1152865728 32292176 3766816
07:12:48 36336 8192000000 10067990000 10512322560 1114080192 11.971 99 27 54 1 1186004912 65845168 6079552
07:14:28 31988 8192000000 10043825760 10479554560 1116625376 11.995 99 17 33 2 1142270720 22148800 3496544
07:16:00 69162 8192000000 10084616096 10546855936 1135874464 12.177 100 26 52 2 1200828960 59248304 5706192
07:18:10 60491 8192000000 10058920976 10497445888 1121368720 12.040 98 21 40 2 1145046048 19935120 3742208
07:20:16 37035 8192000000 10128297264 10567700480 1138027024 12.197 99 41 81 0 1213112688 67156208 7929456
07:21:55 46540 8192000000 10079645664 10514944000 1121063344 12.037 98 27 54 0 1169928112 43844944 5019824
07:23:46 133722 8192000000 10099838112 10532257792 1143280368 12.246 99 17 34 0 1206232512 59399920 3552224
07:27:08 50722 8192000000 10083665664 10532839424 1133392080 12.153 98 7 14 1 1174236144 39459328 1384736
07:29:12 69621 8192000000 10079556240 10539073536 1132168736 12.142 98 10 20 1 1167248016 32874624 2204656
07:31:23 50884 8192000000 10121666624 10558291968 1136133728 12.179 98 18 36 0 1199834048 59582704 4117616
07:33:18 58045 8192000000 10068578352 10539290624 1070840448 11.560 94 16 32 0 1142519952 67871008 3808496
07:35:25 105836 8192000000 9479436688 9838698496 515382400 5.918 46 7 14 0 571658832 54836256 1440176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2021 07:38:10 to 14-10-2021 07:38:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2021 07:38:26 to 14-10-2021 07:38:28.

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          231244
MON$OLDEST_ACTIVE               232071
MON$OLDEST_SNAPSHOT             232071
MON$NEXT_TRANSACTION            232071
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                       2036128
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.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 ---
:74: 
:75: mscs-test-161.budzdorov.ru    Thu Oct 14 07:37:18 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Thu Oct 14 07:37:18 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
--- end of diff output ---

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

14.10.21 07:38:29. 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-14 07:38:31 No severe PSQL-related problems occured
14.10.21 07:38:33. End of report.