Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.03.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16927276 kB
MemAvailable 31568636 kB
Buffers 0 kB
Cached 14755608 kB
SwapCached 2180 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2022-03-30 01:38:20
DTS_END                         2022-03-30 01:38:20.3970
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(57:17)
IP                              ::1/52764
TRN_ID                          246763
ATT_ID                          712
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: 8957.98 21864 1074957
customer order: creation 177.80 365 21336
customer order: refuse 14.38 402 1726
order to supplier: creation 46.48 1185 5577
order to supplier: removal 3.12 112364 374
invoice (draft): creation 47.49 3300 5699
invoice (draft): removal 48.19 799 5783
invoice accept: apply 42.44 16722 5093
invoice accept: cancel 43.77 773 5252
customer reserve: creation 3893.38 165 467205
customer reserve: removal 4335.59 64 520271
realization accept: apply 87.33 191 10479
realization accept: cancel 85.33 112 10240
payment from customer: creation 60.84 119 7301
payment from customer: removal 5.80 161 696
payment to supplier: creation 50.37 137 6044
payment to supplier: removal 3.49 70 419
service: total inventory turnovers 4.40 1422 528
service: total monetary turnovers 6.92 25 830
service: aggregate perf. data 0.83 16112 100
service: refresh index statistics 0.03 282791 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-03-2022 01:39:35 to 30-03-2022 01:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3297
TEST_TIME 2 3189
TEST_TIME 3 3231
TEST_TIME 4 3824
TEST_TIME 5 4482
TEST_TIME 6 4316
TEST_TIME 7 4327
TEST_TIME 8 4594
TEST_TIME 9 4752
TEST_TIME 10 5202
TEST_TIME 11 5480
TEST_TIME 12 5705
TEST_TIME 13 6003
TEST_TIME 14 6559
TEST_TIME 15 6609
TEST_TIME 16 7324
TEST_TIME 17 7861
TEST_TIME 18 8338
TEST_TIME 19 8533
TEST_TIME 20 9046
TEST_TIME 21 9138
TEST_TIME 22 9165
TEST_TIME 23 9547
TEST_TIME 24 9651
TEST_TIME 25 9959
TEST_TIME 26 10883
TEST_TIME 27 10610
TEST_TIME 28 10815
TEST_TIME 29 10572
TEST_TIME 30 10437
TEST_TIME 31 10207
TEST_TIME 32 10176
TEST_TIME 33 10057
TEST_TIME 34 10056
TEST_TIME 35 9943
TEST_TIME 36 9814
TEST_TIME 37 9633
TEST_TIME 38 9554
TEST_TIME 39 9459
TEST_TIME 40 9305
TEST_TIME 41 9387
TEST_TIME 42 9551
TEST_TIME 43 9381
TEST_TIME 44 9569
TEST_TIME 45 9447
TEST_TIME 46 9394
TEST_TIME 47 9273
TEST_TIME 48 9226
TEST_TIME 49 9196
TEST_TIME 50 9143
TEST_TIME 51 9048
TEST_TIME 52 9023
TEST_TIME 53 8979
TEST_TIME 54 9047
TEST_TIME 55 9004
TEST_TIME 56 8998
TEST_TIME 57 8964
TEST_TIME 58 9105
TEST_TIME 59 9209
TEST_TIME 60 9234
TEST_TIME 61 9227
TEST_TIME 62 9319
TEST_TIME 63 9393
TEST_TIME 64 9356
TEST_TIME 65 9443
TEST_TIME 66 9448
TEST_TIME 67 9367
TEST_TIME 68 9310
TEST_TIME 69 9270
TEST_TIME 70 9216
TEST_TIME 71 9210
TEST_TIME 72 9140
TEST_TIME 73 9077
TEST_TIME 74 9059
TEST_TIME 75 9024
TEST_TIME 76 9073
TEST_TIME 77 9090
TEST_TIME 78 9094
TEST_TIME 79 9065
TEST_TIME 80 9058
TEST_TIME 81 9235
TEST_TIME 82 9217
TEST_TIME 83 9220
TEST_TIME 84 9175
TEST_TIME 85 9228
TEST_TIME 86 9346
TEST_TIME 87 9342
TEST_TIME 88 9394
TEST_TIME 89 9332
TEST_TIME 90 9268
TEST_TIME 91 9242
TEST_TIME 92 9193
TEST_TIME 93 9149
TEST_TIME 94 9111
TEST_TIME 95 9073
TEST_TIME 96 9064
TEST_TIME 97 9153
TEST_TIME 98 9112
TEST_TIME 99 9151
TEST_TIME 100 9123
TEST_TIME 101 9199
TEST_TIME 102 9163
TEST_TIME 103 9175
TEST_TIME 104 9195
TEST_TIME 105 9230
TEST_TIME 106 9192
TEST_TIME 107 9187
TEST_TIME 108 9167
TEST_TIME 109 9200
TEST_TIME 110 9170
TEST_TIME 111 9142
TEST_TIME 112 9082
TEST_TIME 113 9108
TEST_TIME 114 9053
TEST_TIME 115 9007
TEST_TIME 116 8979
TEST_TIME 117 9022
TEST_TIME 118 9020
TEST_TIME 119 9008
TEST_TIME 120 8961

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-03-2022 01:39:39 to 30-03-2022 01:39:42.

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 1507731 1507731 0 0.00 0 24927 6 0 0
doc_list_biud 1507727 1507727 0 0.00 0 26147 28 0 0
sp_add_invoice_to_stock 5262 5093 169 3.21 28 201707 16723 0 169
sp_cancel_adding_invoice 5252 5252 0 0.00 4 54946 773 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 24228 402 0 0
sp_cancel_customer_reserve 520271 520271 0 0.00 1 26148 65 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 2 13339 162 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 6918 70 0 0
sp_cancel_supplier_invoice 5793 5783 10 0.17 1 24217 799 0 10
sp_cancel_supplier_order 374 374 0 0.00 8 945218 112364 0 0
sp_cancel_write_off 10240 10240 0 0.00 1 15666 112 0 0
sp_client_order 21336 21336 0 0.00 3 41298 366 0 0
sp_customer_reserve 467205 467205 0 0.00 0 102090 166 0 0
sp_fill_shopping_cart 32607 32607 0 0.00 1 21857 45 0 0
sp_fill_shopping_cart_clo_res 467199 467199 0 0.00 0 15011 15 0 0
sp_get_clo_for_invoice 5087 5087 0 0.00 0 23538 328 0 0
sp_kill_cost_storno 16607 16607 0 0.00 0 16589 61 0 0
sp_kill_qstorno_ret_qs2qd 1056939 1056939 0 0.00 0 26143 35 0 0
sp_kill_qty_storno 559210 559210 0 0.00 0 26146 75 0 0
sp_lock_selected_doc 21396 21396 0 0.00 0 9395 5 0 0
sp_make_cost_storno 28911 28911 0 0.00 0 14585 50 0 0
sp_multiply_rows_for_pdistr 28911 28911 0 0.00 0 16218 39 0 0
sp_multiply_rows_for_qdistr 21336 21336 0 0.00 0 38673 188 0 0
sp_pay_from_customer 7301 7301 0 0.00 4 28333 119 0 0
sp_pay_to_supplier 6044 6044 0 0.00 4 17111 137 0 0
sp_payment_common 13345 13345 0 0.00 1 28284 54 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 24222 367 0 0
sp_qd_handle_on_invoice_upd_sts 10339 10339 0 0.00 0 25939 330 0 0
sp_qd_handle_on_reserve_upd_sts 10477 10477 0 0.00 0 24368 61 0 0
sp_reserve_write_off 10479 10479 0 0.00 6 24472 191 0 0
sp_supplier_invoice 5699 5699 0 0.00 6 83685 3301 0 0
sp_supplier_order 5577 5577 0 0.00 5 26260 1186 0 0
srv_aggregate_perf_data 100 100 0 0.00 2776 114772 16113 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2350 141662 47612 0 0
srv_find_qd_qs_mism 1026243 1026243 0 0.00 0 24926 5 0 0
srv_make_invnt_saldo 528 528 0 0.00 25 137380 1423 0 0
srv_make_money_saldo 830 830 0 0.00 1 2458 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 219377 321675 282791 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5093 5093 0 0.00 0 11652 184 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5568 113 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 2298 13 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 2772 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 365 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 2579 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 609 8 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 34353 1632 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 5373 12 0 0
t$perf-norm:sp_client_order 21336 21336 0 0.00 0 12149 19 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 30165 32 0 0
t$perf-norm:sp_pay_from_customer 7301 7301 0 0.00 0 28951 15 0 0
t$perf-norm:sp_pay_to_supplier 6044 6044 0 0.00 0 4755 12 0 0
t$perf-norm:sp_reserve_write_off 10479 10479 0 0.00 0 7954 12 0 0
t$perf-norm:sp_supplier_invoice 5699 5699 0 0.00 0 12592 21 0 0
t$perf-norm:sp_supplier_order 5577 5577 0 0.00 0 29675 22 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 636 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 528 528 0 0.00 0 1654 33 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 934 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10512 10174 338 3.21 0 5413 17 0 338
v_all_customers 38942 38942 0 0.00 0 51 0 0 0
v_all_suppliers 31254 31254 0 0.00 0 62 1 0 0
v_all_wares 42672 42672 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 899 6 0 0
v_cancel_client_order 3452 3452 0 0.00 0 7610 8 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 5718 14 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 1032 5 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 782 5 0 0
v_cancel_supplier_invoice 796 776 20 2.51 0 559 12 0 20
v_cancel_supplier_order 748 748 0 0.00 0 464 3 0 0
v_cancel_write_off 2142 2142 0 0.00 0 3923 14 0 0
v_max_id_clo_ord 5577 5577 0 0.00 0 1716 1 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 8865 26 0 0
v_max_id_ord_sup 5694 5694 0 0.00 0 2564 3 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 760 2 0 0
v_max_non_paid_realizn 3907 3907 0 0.00 0 1492 3 0 0
v_min_id_clo_ord 5577 5577 0 0.00 0 17442 23 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 2062 5 0 0
v_min_id_ord_sup 5694 5694 0 0.00 0 4585 8 0 0
v_min_non_paid_invoice 6044 6044 0 0.00 0 17011 41 0 0
v_min_non_paid_realizn 7300 7300 0 0.00 0 28271 48 0 0
v_our_firm 10516 10516 0 0.00 0 50 1 0 0
v_reserve_write_off 20954 20954 0 0.00 0 9119 4 0 0
x_lock_depdocs_on_canc_invoice 10647 10647 0 0.00 0 15601 119 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 5895 357 0 0
x_make_qstorno_1000_1200 5577 5577 0 0.00 1 26249 1088 0 0
x_make_qstorno_1200_2000 5699 5699 0 0.00 2 74109 3143 0 0
x_make_qstorno_2100_3300 467205 467205 0 0.00 0 102087 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-03-2022 01:39:43 to 30-03-2022 01:39:47.

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
23:08:24 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:24 43393 8192000000 9913700704 10225229824 989967120 10.781 100 88 176 0 1019228560 6376320 22885120
23:11:13 10860 8192000000 9951757456 10278313984 1041026128 11.275 100 83 166 0 1070688240 8092544 21569568
23:12:26 17345 8192000000 9996627152 10328981504 1075078208 11.601 100 89 178 0 1107032272 9918416 22035648
23:13:47 12859 8192000000 10033384608 10382462976 1121080416 12.037 100 83 166 1 1152664144 12043808 19539920
23:15:03 5782 8192000000 10085518656 10443489280 1168743856 12.485 100 81 162 0 1200143152 12385472 19013824
23:16:16 4589 8192000000 10136486128 10504957952 1222760176 12.987 100 83 164 1 1254686304 13352320 18573808
23:17:23 2110 8192000000 10178506032 10554765312 1255556400 13.289 100 89 178 1 1289831408 14512928 19762080
23:18:27 1773 8192000000 10204335296 10583887872 1288351504 13.589 100 90 183 0 1321067168 12605776 20109888
23:19:30 1509 8192000000 10224312224 10607329280 1303639152 13.728 100 95 191 1 1338356672 12711456 22006064
23:20:32 18475 8192000000 10201661280 10606862336 1306432752 13.754 100 39 78 2 1323265056 8057328 8774976
23:21:51 3940 8192000000 10209328144 10623811584 1317511728 13.854 100 32 64 2 1335660368 10922784 7225856
23:22:55 8838 8192000000 10209120448 10629406720 1322840752 13.902 100 23 46 0 1338850320 10697136 5312432
23:24:07 5965 8192000000 10223951328 10636525568 1331043184 13.977 100 40 78 2 1347044320 6071984 9929152
23:25:17 43835 8192000000 10252784416 10654179328 1337340752 14.033 100 61 125 2 1358527680 6530064 14656864
23:27:01 5516 8192000000 10234469600 10654105600 1336436112 14.025 100 22 42 1 1347681280 6778512 4466656
23:28:10 7225 8192000000 10208228864 10627170304 1315700112 13.838 100 18 36 0 1325315472 5532368 4082992
23:29:21 57570 8192000000 10170091504 10585083904 1230967152 13.063 94 22 44 1 1241144784 5136496 5041136
23:31:19 7992 8192000000 10131914640 10527526912 1200390784 12.780 96 24 48 0 1211078112 5079888 5607440
23:32:29 10596 8192000000 10131735792 10531966976 1196799696 12.747 95 26 51 1 1208001280 5253088 5948496
23:33:40 18729 8192000000 10186313792 10583953408 1276669856 13.483 100 16 32 0 1285878704 5609280 3599568
23:34:59 15035 8192000000 10186697664 10594992128 1292528576 13.627 100 29 57 0 1304648704 5703456 6416672
23:36:16 8365 8192000000 10196339792 10610089984 1304394400 13.735 100 32 63 0 1317327632 5426208 7507024
23:37:26 10097 8192000000 10195270320 10615455744 1310384320 13.790 100 17 34 1 1319805616 5506432 3914864
23:38:41 61326 8192000000 10253463088 10664411136 1337498384 14.035 100 27 54 0 1349101200 5438016 6164800
23:40:48 131678 8192000000 10279590336 10708635648 1366591088 14.296 100 32 64 2 1391077824 15988896 8497840
23:44:05 23710 8192000000 10302415904 10725744640 1387464560 14.483 100 49 98 0 1419967120 20451520 12051040
23:45:40 26784 8192000000 10307536544 10732773376 1372729104 14.351 98 25 50 2 1408687360 29209296 6748960
23:47:10 6412 8192000000 10343266816 10757746688 1404525312 14.635 100 69 138 1 1451784768 30487456 16772000
23:48:27 7697 8192000000 10343455360 10766274560 1409181760 14.677 100 61 122 2 1457143568 32645008 15316800
23:49:42 3237 8192000000 10352670960 10772291584 1415024752 14.729 100 70 138 1 1465258704 32565152 17668800
23:50:56 7215 8192000000 10341707632 10753609728 1386508592 14.475 98 72 143 1 1437855216 34098144 17248480
23:52:09 3490 8192000000 10337829504 10742390784 1369284176 14.321 99 82 164 0 1430215216 40945344 19985696
23:53:17 11530 8192000000 10339874288 10761633792 1393221744 14.535 100 49 97 1 1450808144 44908112 12678288
23:54:35 2349 8192000000 10321905632 10729603072 1331177264 13.978 96 68 134 1 1396652816 48014576 17460976
23:55:44 10032 8192000000 10292484320 10709053440 1326793312 13.938 97 46 92 1 1377516032 38615040 12107680
23:57:04 4410 8192000000 10316104112 10728886272 1355483376 14.197 99 52 104 1 1405360048 36406720 13469952
23:58:16 18288 8192000000 10301159888 10734161920 1362861648 14.263 99 31 62 2 1408983456 37059744 9062064
23:59:38 15761 8192000000 10306170704 10738343936 1357138992 14.212 99 32 64 0 1399751808 34007168 8605648
00:00:57 16586 8192000000 10274927808 10700148736 1310355600 13.789 97 26 52 0 1357995152 40352272 7287280
00:02:19 17171 8192000000 10282028368 10716258304 1356006544 14.201 100 26 52 1 1392877264 30225664 6645056
00:03:40 29401 8192000000 10272395248 10707972096 1332799920 13.992 100 20 40 0 1382143504 43546640 5796944
00:05:13 23686 8192000000 10280205696 10699186176 1344007616 14.094 99 44 86 2 1365839904 11061280 10771008
00:06:43 81522 8192000000 10253661824 10690928640 1323576016 13.909 98 9 18 0 1337429632 11978208 1875408
00:09:11 85913 8192000000 10260932848 10699202560 1342318240 14.078 99 33 66 0 1359894080 10054736 7521104
00:11:38 45752 8192000000 10266807680 10701684736 1329904992 13.966 97 34 68 2 1345704624 8355376 7444256
00:13:24 57267 8192000000 10264577488 10709938176 1352957296 14.174 99 24 48 1 1366284912 7672368 5655248
00:15:25 51957 8192000000 10294172848 10732658688 1371441952 14.340 99 23 46 0 1391929488 14822928 5664608
00:17:26 37321 8192000000 10316333696 10760417280 1388085744 14.489 99 29 59 2 1412452624 17195424 7171456
00:19:22 53990 8192000000 10318094048 10771243008 1405164032 14.641 100 21 44 2 1436950384 26207760 5578592
00:21:17 35671 8192000000 10305176560 10753191936 1395146608 14.552 100 20 40 2 1418159920 18475760 4537552
00:22:55 45558 8192000000 10292705136 10750164992 1392761376 14.530 100 9 18 0 1409136176 13869360 2505440
00:24:47 49982 8192000000 10313283632 10757029888 1399977344 14.595 100 54 110 1 1420058288 8049040 12031904
00:26:38 30572 8192000000 10297152096 10755424256 1400682992 14.601 100 15 30 0 1413452176 9533872 3235312
00:28:08 48187 8192000000 10284262128 10735280128 1373793552 14.361 98 13 26 2 1385027984 8373056 2861376
00:30:09 73126 8192000000 10294966704 10755387392 1380557136 14.422 99 17 34 0 1395280048 10861872 3861040
00:32:58 51694 8192000000 10304009680 10758250496 1390507984 14.510 99 10 20 0 1400646912 7871936 2266992
00:34:52 54663 8192000000 10325978496 10775334912 1406340688 14.651 100 26 52 1 1431381680 18829712 6211280
00:36:49 99350 8192000000 10258876672 10700599296 1300569664 13.700 94 11 22 1 1315966864 12913296 2483904
00:40:14 88667 8192000000 10277335376 10722250752 1270832032 13.429 92 11 21 0 1284252400 11543872 1876496
00:42:43 64423 8192000000 10263159440 10720972800 1319075840 13.868 97 19 38 0 1335379184 12085184 4218160
00:44:47 20562 8192000000 10277461312 10708434944 1346599328 14.117 99 43 86 1 1364279728 7579904 10100496
00:46:12 68623 8192000000 10284034080 10713948160 1336655440 14.027 98 48 94 1 1359082672 11581040 10846192
00:48:21 29828 8192000000 10250422000 10694328320 1352669328 14.171 100 20 40 0 1364942816 6331888 5941600
00:50:02 62965 8192000000 10240968560 10690060288 1314966656 13.831 97 15 30 1 1325217568 6211888 4039024
00:52:12 44358 8192000000 10258917600 10711351296 1360130688 14.239 100 8 16 1 1373176736 10875584 2170464
00:53:59 73995 8192000000 10251962368 10724990976 1338959728 14.048 98 13 26 0 1351298800 9505472 2833600
00:56:18 82922 8192000000 10266158688 10713427968 1310975088 13.795 96 26 52 2 1331415792 14290288 6150416
00:58:42 50242 8192000000 10260640528 10715418624 1319310464 13.870 97 14 28 0 1341916752 19111568 3494720
01:00:47 37556 8192000000 10268126080 10704510976 1330015664 13.967 98 26 52 0 1343579344 7860752 5702928
01:02:33 50422 8192000000 10289707456 10734235648 1372035008 14.345 100 29 58 1 1393708096 14821184 6851904
01:04:23 57854 8192000000 10274520880 10710028288 1347596848 14.126 99 11 22 0 1361864816 11525504 2742464
01:06:22 141662 8192000000 10273741120 10708533248 1315394784 13.835 96 16 32 0 1334454864 15477696 3582384
01:09:45 50702 8192000000 10278440384 10715652096 1365207232 14.284 100 41 80 1 1384290336 10095568 8987536
01:11:38 68160 8192000000 10274570272 10726948864 1374864560 14.371 100 10 20 0 1389958064 12948688 2144816
01:13:48 92341 8192000000 10282416224 10750296064 1361200960 14.248 98 17 35 2 1379987792 14715296 4071536
01:17:36 68193 8192000000 10297317328 10752995328 1391303472 14.517 100 11 22 0 1406441792 12608784 2529536
01:19:44 37045 8192000000 10310717040 10745450496 1373666320 14.360 99 39 78 1 1390546960 8279856 8600784
01:21:27 56002 8192000000 10282938192 10717536256 1353361024 14.178 99 33 66 0 1375411840 13982784 8068032
01:23:26 91518 8192000000 10279667248 10718638080 1315861248 13.839 96 32 64 1 1333916192 11429536 6625408
01:26:00 24888 8192000000 10283156688 10710073344 1326468880 13.935 97 47 94 2 1348343232 11887088 9987264
01:27:28 21469 8192000000 10288384960 10727182336 1366047952 14.292 100 38 76 0 1385710736 11460400 8202384
01:28:51 123814 8192000000 10275196464 10717102080 1323774176 13.911 96 26 50 1 1345386112 15292272 6319664
01:32:15 46509 8192000000 10260373920 10715492352 1353806304 14.182 99 8 16 0 1363984496 8619136 1559056
01:34:18 72308 8192000000 10271097712 10742435840 1379625792 14.413 100 13 26 0 1392580112 9982560 2971760
01:36:30 22182 8192000000 10265971168 10721439744 1370950176 14.336 100 24 48 2 1387927856 11567520 5410160
01:37:59 27035 8192000000 9084359360 9316933632 254550992 3.013 19 4 6 1 260030224 4552640 926592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-03-2022 01:39:49 to 30-03-2022 01:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-03-2022 01:40:05 to 30-03-2022 01:40:07.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247077
MON$OLDEST_ACTIVE               247078
MON$OLDEST_SNAPSHOT             247078
MON$NEXT_TRANSACTION            247078
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-03 16:41:55.3470
MON$PAGES                       2005376
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
--- end of diff output ---

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

30.03.22 01:40:08. There are 3 logs in /var/tmp/logs-oltp30 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
2022-03-30 01:40:11 No severe PSQL-related problems occured
30.03.22 01:40:12. End of report.