Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.07.2022 13:38
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 16004792 kB
MemAvailable 31161240 kB
Buffers 0 kB
Cached 15734484 kB
SwapCached 2524 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.2
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 2022-07-08 13:37:18
DTS_END                         2022-07-08 13:37:18.2000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35946
TRN_ID                          223767
ATT_ID                          628
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: 9088.30 23184 1090596
customer order: creation 170.28 462 20434
customer order: refuse 15.50 419 1860
order to supplier: creation 46.23 1372 5548
order to supplier: removal 3.62 139066 434
invoice (draft): creation 46.12 3176 5534
invoice (draft): removal 51.18 908 6141
invoice accept: apply 42.32 28403 5078
invoice accept: cancel 46.16 1144 5539
customer reserve: creation 3856.41 277 462769
customer reserve: removal 4513.26 87 541591
realization accept: apply 82.01 295 9841
realization accept: cancel 83.18 160 9982
payment from customer: creation 59.33 190 7119
payment from customer: removal 5.81 140 697
payment to supplier: creation 50.73 210 6087
payment to supplier: removal 3.53 146 423
service: total inventory turnovers 3.61 3987 433
service: total monetary turnovers 7.95 330 954
service: aggregate perf. data 1.05 14036 126
service: refresh index statistics 0.05 268867 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-07-2022 13:39:07 to 08-07-2022 13:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11172
TEST_TIME 2 8448
TEST_TIME 3 7324
TEST_TIME 4 6891
TEST_TIME 5 6865
TEST_TIME 6 6616
TEST_TIME 7 6781
TEST_TIME 8 7246
TEST_TIME 9 7361
TEST_TIME 10 7386
TEST_TIME 11 8027
TEST_TIME 12 7883
TEST_TIME 13 7925
TEST_TIME 14 7893
TEST_TIME 15 7849
TEST_TIME 16 7798
TEST_TIME 17 7872
TEST_TIME 18 8010
TEST_TIME 19 7997
TEST_TIME 20 8214
TEST_TIME 21 8153
TEST_TIME 22 8348
TEST_TIME 23 8493
TEST_TIME 24 8553
TEST_TIME 25 8974
TEST_TIME 26 8887
TEST_TIME 27 8890
TEST_TIME 28 9062
TEST_TIME 29 9217
TEST_TIME 30 9499
TEST_TIME 31 9912
TEST_TIME 32 9685
TEST_TIME 33 9899
TEST_TIME 34 10077
TEST_TIME 35 10259
TEST_TIME 36 10747
TEST_TIME 37 10535
TEST_TIME 38 10537
TEST_TIME 39 10434
TEST_TIME 40 10262
TEST_TIME 41 10268
TEST_TIME 42 10143
TEST_TIME 43 10036
TEST_TIME 44 9883
TEST_TIME 45 9716
TEST_TIME 46 9942
TEST_TIME 47 9783
TEST_TIME 48 9738
TEST_TIME 49 9657
TEST_TIME 50 9685
TEST_TIME 51 9663
TEST_TIME 52 9618
TEST_TIME 53 9548
TEST_TIME 54 9539
TEST_TIME 55 9407
TEST_TIME 56 9370
TEST_TIME 57 9423
TEST_TIME 58 9442
TEST_TIME 59 9438
TEST_TIME 60 9396
TEST_TIME 61 9368
TEST_TIME 62 9382
TEST_TIME 63 9504
TEST_TIME 64 9515
TEST_TIME 65 9496
TEST_TIME 66 9489
TEST_TIME 67 9517
TEST_TIME 68 9532
TEST_TIME 69 9456
TEST_TIME 70 9407
TEST_TIME 71 9393
TEST_TIME 72 9341
TEST_TIME 73 9283
TEST_TIME 74 9233
TEST_TIME 75 9217
TEST_TIME 76 9134
TEST_TIME 77 9114
TEST_TIME 78 9139
TEST_TIME 79 9101
TEST_TIME 80 9104
TEST_TIME 81 9139
TEST_TIME 82 9155
TEST_TIME 83 9204
TEST_TIME 84 9214
TEST_TIME 85 9235
TEST_TIME 86 9276
TEST_TIME 87 9292
TEST_TIME 88 9302
TEST_TIME 89 9327
TEST_TIME 90 9386
TEST_TIME 91 9420
TEST_TIME 92 9369
TEST_TIME 93 9398
TEST_TIME 94 9330
TEST_TIME 95 9275
TEST_TIME 96 9252
TEST_TIME 97 9274
TEST_TIME 98 9207
TEST_TIME 99 9211
TEST_TIME 100 9179
TEST_TIME 101 9147
TEST_TIME 102 9072
TEST_TIME 103 9091
TEST_TIME 104 9132
TEST_TIME 105 9124
TEST_TIME 106 9143
TEST_TIME 107 9100
TEST_TIME 108 9102
TEST_TIME 109 9090
TEST_TIME 110 9122
TEST_TIME 111 9148
TEST_TIME 112 9129
TEST_TIME 113 9135
TEST_TIME 114 9139
TEST_TIME 115 9227
TEST_TIME 116 9191
TEST_TIME 117 9200
TEST_TIME 118 9187
TEST_TIME 119 9186
TEST_TIME 120 9112

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-07-2022 13:39:10 to 08-07-2022 13:39:14.

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 1518340 1518340 0 0.00 0 20371 15 0 0
doc_list_biud 1518334 1518334 0 0.00 0 37537 37 0 0
sp_add_invoice_to_stock 5236 5078 158 3.01 68 215646 28404 0 158
sp_cancel_adding_invoice 5539 5539 0 0.00 5 80817 1144 0 0
sp_cancel_client_order 1860 1860 0 0.00 6 11433 420 0 0
sp_cancel_customer_reserve 541591 541591 0 0.00 1 38340 87 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 6112 140 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 3681 146 0 0
sp_cancel_supplier_invoice 6152 6141 11 0.17 2 19458 909 0 11
sp_cancel_supplier_order 434 434 0 0.00 12 1208367 139066 0 0
sp_cancel_write_off 9982 9982 0 0.00 2 14873 161 0 0
sp_client_order 20434 20434 0 0.00 5 92643 462 0 0
sp_customer_reserve 462769 462769 0 0.00 0 100336 277 0 0
sp_fill_shopping_cart 31509 31509 0 0.00 1 11796 82 0 0
sp_fill_shopping_cart_clo_res 462739 462739 0 0.00 0 23081 18 0 0
sp_get_clo_for_invoice 5043 5043 0 0.00 0 30277 250 0 0
sp_kill_cost_storno 16641 16641 0 0.00 0 8063 75 0 0
sp_kill_qstorno_ret_qs2qd 1099739 1099739 0 0.00 0 37531 38 0 0
sp_kill_qty_storno 580429 580429 0 0.00 0 37535 89 0 0
sp_lock_selected_doc 20857 20857 0 0.00 0 10312 8 0 0
sp_make_cost_storno 28090 28090 0 0.00 0 20274 85 0 0
sp_multiply_rows_for_pdistr 28089 28089 0 0.00 0 14913 42 0 0
sp_multiply_rows_for_qdistr 20434 20434 0 0.00 0 12786 151 0 0
sp_pay_from_customer 7119 7119 0 0.00 5 15236 190 0 0
sp_pay_to_supplier 6087 6087 0 0.00 6 18226 211 0 0
sp_payment_common 13206 13206 0 0.00 2 12216 51 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 11422 341 0 0
sp_qd_handle_on_invoice_upd_sts 10582 10582 0 0.00 0 28754 508 0 0
sp_qd_handle_on_reserve_upd_sts 9839 9839 0 0.00 0 14563 62 0 0
sp_reserve_write_off 9841 9841 0 0.00 8 18413 295 0 0
sp_supplier_invoice 5534 5534 0 0.00 8 117053 3176 0 0
sp_supplier_order 5548 5548 0 0.00 7 93077 1373 0 0
srv_aggregate_perf_data 126 126 0 0.00 2251 120738 14036 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 755 164299 34673 0 0
srv_find_qd_qs_mism 1042232 1042232 0 0.00 0 16476 12 0 0
srv_make_invnt_saldo 433 433 0 0.00 73 49383 3987 0 0
srv_make_money_saldo 954 954 0 0.00 1 9952 331 0 0
srv_recalc_idx_stat 6 6 0 0.00 203675 412251 268867 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5083 5083 0 0.00 1 19421 896 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 2 18605 470 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 4245 36 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 953 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2740 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 3272 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 768 31 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 1 68005 5869 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 1760 22 0 0
t$perf-norm:sp_client_order 20435 20435 0 0.00 0 14170 35 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 847 16 0 0
t$perf-norm:sp_pay_from_customer 7119 7119 0 0.00 0 7590 20 0 0
t$perf-norm:sp_pay_to_supplier 6087 6087 0 0.00 0 12969 20 0 0
t$perf-norm:sp_reserve_write_off 9842 9842 0 0.00 0 12505 24 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 10975 31 0 0
t$perf-norm:sp_supplier_order 5548 5548 0 0.00 0 5194 32 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 1409 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 155 9 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 388 12 0 0
t$perf-norm:srv_make_money_saldo 954 954 0 0.00 0 922 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 6 188 73 0 0
v_add_invoice_to_stock 10398 10082 316 3.03 0 10210 30 0 316
v_all_customers 37446 37446 0 0.00 0 86 4 0 0
v_all_suppliers 30851 30851 0 0.00 0 124 4 0 0
v_all_wares 40858 40858 0 0.00 0 136 4 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 8492 28 0 0
v_cancel_client_order 3720 3720 0 0.00 0 5626 12 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1262 14 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 10418 24 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 528 12 0 0
v_cancel_supplier_invoice 886 864 22 2.48 0 12163 52 0 22
v_cancel_supplier_order 868 868 0 0.00 0 1668 13 0 0
v_cancel_write_off 2060 2060 0 0.00 0 7173 34 0 0
v_max_id_clo_ord 5547 5547 0 0.00 0 4671 6 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 2060 11 0 0
v_max_id_ord_sup 5530 5530 0 0.00 0 10648 10 0 0
v_max_non_paid_invoice 1738 1738 0 0.00 0 3469 10 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 1164 9 0 0
v_min_id_clo_ord 5547 5547 0 0.00 0 4386 13 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 1941 10 0 0
v_min_id_ord_sup 5530 5530 0 0.00 0 8062 10 0 0
v_min_non_paid_invoice 6087 6087 0 0.00 0 12209 25 0 0
v_min_non_paid_realizn 7119 7119 0 0.00 0 11782 20 0 0
v_our_firm 10222 10222 0 0.00 0 54 5 0 0
v_reserve_write_off 19678 19678 0 0.00 0 18126 14 0 0
x_lock_depdocs_on_canc_invoice 11248 11248 0 0.00 0 23645 119 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 2701 309 0 0
x_make_qstorno_1000_1200 5548 5548 0 0.00 3 93030 1194 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 4 117032 2962 0 0
x_make_qstorno_2100_3300 462769 462769 0 0.00 0 100333 247 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-07-2022 13:39:15 to 08-07-2022 13:39:19.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:22 22602 8192000000 9733708624 10034102272 809790656 8.995 100 88 176 1 838477232 8003360 20683216
11:09:47 17573 8192000000 9767785120 10076495872 842624304 9.326 100 95 188 0 877757504 12853440 22279760
11:11:06 13388 8192000000 9792645536 10109259776 860602512 9.506 100 83 166 0 901815584 21967072 19246000
11:12:23 13630 8192000000 9854381504 10182688768 901221760 9.910 100 81 162 0 962007568 42878320 17907488
11:13:39 3695 8192000000 9905160560 10243268608 939986224 10.293 100 89 173 1 1021563776 62973424 18604128
11:14:47 2630 8192000000 9938185824 10291572736 981770848 10.701 100 93 179 0 1063875344 63140816 18963680
11:15:51 1048 8192000000 9992328752 10350022656 1001019184 10.888 100 96 186 1 1109448832 89890560 18539088
11:16:54 1028 8192000000 9995394704 10359746560 1026270544 11.133 100 92 175 6 1112639792 68322192 18047056
11:17:58 1023 8192000000 10025679696 10399215616 1051212480 11.372 100 96 182 3 1147108880 78095776 17800624
11:19:00 991 8192000000 10048768784 10425352192 1059588160 11.453 100 97 186 2 1160851936 82877200 18386576
11:20:06 922 8192000000 10021625984 10399588352 1059992784 11.456 100 91 176 1 1142492208 64438048 18061376
11:21:08 1299 8192000000 10038129808 10420391936 1065610800 11.510 100 90 172 2 1161252672 78504368 17137504
11:22:10 1058 8192000000 10054160192 10442256384 1074533472 11.595 100 93 175 1 1176288032 83542432 18212128
11:23:12 1181 8192000000 10069612896 10456809472 1076808320 11.617 100 93 178 0 1192608848 97754160 18046368
11:24:14 1364 8192000000 10048526400 10439733248 1077924080 11.628 100 95 185 0 1166935280 70118496 18892704
11:25:17 1192 8192000000 10051702928 10438746112 1088512560 11.729 100 97 186 1 1174190256 66220240 19457456
11:26:19 980 8192000000 10059320480 10447335424 1086235024 11.707 100 96 181 1 1181996688 77727712 18033952
11:27:21 1109 8192000000 10026735456 10413031424 1076672576 11.616 100 94 179 1 1150555952 53669472 20213904
11:28:23 5462 8192000000 10027050576 10425905152 1074958448 11.599 100 63 124 2 1157495568 69542352 12994768
11:29:29 2279 8192000000 10025664304 10412130304 1074795888 11.598 100 81 153 5 1146689200 57147936 14745376
11:30:33 903 8192000000 10005410560 10390192128 1070139968 11.553 99 82 151 1 1126665008 38658880 17866160
11:31:35 1911 8192000000 10009344848 10397245440 1044309632 11.306 100 71 141 0 1126050576 67502080 14238864
11:32:38 961 8192000000 9993549616 10370670592 1044341696 11.306 100 96 182 2 1113943920 50862448 18739776
11:33:40 1041 8192000000 10002681008 10377510912 1048692240 11.348 100 95 182 1 1119618128 53445152 17480736
11:34:42 3725 8192000000 9994035520 10379149312 1040949296 11.274 100 62 117 1 1124912608 72715328 11247984
11:35:47 896 8192000000 9970316368 10333949952 1008073696 10.957 99 94 175 1 1092954576 66450864 18430016
11:36:48 1025 8192000000 10001221056 10375696384 1041693920 11.281 100 89 167 1 1123728880 63789600 18245360
11:37:54 21202 8192000000 10006212768 10397028352 1052130800 11.381 100 63 124 0 1133020336 68403632 12485904
11:39:23 13590 8192000000 10025925024 10417111040 1072249520 11.574 100 58 113 1 1146947360 63678672 11019168
11:40:44 3954 8192000000 10041858688 10423414784 1084188368 11.687 100 76 141 1 1157892784 58774416 14930000
11:42:02 1685 8192000000 10064079216 10457341952 1096432496 11.804 100 78 152 0 1189593024 76318432 16842096
11:43:10 14444 8192000000 10107436944 10500841472 1108195808 11.915 100 69 124 4 1231755056 111106960 12452288
11:44:28 857 8192000000 10155994432 10547687424 1122451584 12.050 100 95 180 0 1270584064 128011296 20121184
11:45:31 940 8192000000 10141307520 10539950080 1126624464 12.090 100 97 192 2 1260861552 112997584 21239504
11:46:36 4995 8192000000 10162887328 10564165632 1133105040 12.151 100 70 135 1 1284575104 137146064 14324000
11:47:45 893 8192000000 10190991040 10599698432 1144396096 12.257 100 92 177 1 1304035600 140387264 19252240
11:48:51 1772 8192000000 10146756480 10553061376 1132985824 12.150 100 85 166 1 1258634320 108125680 17522816
11:49:58 2555 8192000000 10204526144 10611027968 1159566256 12.399 100 85 163 1 1309747600 132773440 17407904
11:51:05 1287 8192000000 10208807136 10617442304 1173267520 12.527 100 99 188 3 1317800736 124537216 19996000
11:52:11 929 8192000000 10256258912 10655481856 1177799696 12.570 100 97 189 2 1367204016 167679088 21725232
11:53:15 2738 8192000000 10299525360 10709037056 1184032320 12.628 100 85 162 2 1403301984 200796496 18473168
11:54:19 2424 8192000000 10326078144 10734751744 1187119104 12.657 100 85 162 1 1430835216 225631808 18084304
11:55:25 1603 8192000000 10354129168 10759598080 1185464416 12.641 100 84 163 1 1464479280 259884000 19130864
11:56:29 784 8192000000 10402806880 10807894016 1194016800 12.721 100 95 183 0 1510042368 295278112 20747456
11:57:34 1206 8192000000 10392367088 10813472768 1196547168 12.744 100 94 188 2 1503634224 285400096 21686960
11:58:37 1799 8192000000 10394749920 10815594496 1188888160 12.673 100 93 179 2 1499440864 289265824 21286880
11:59:40 850 8192000000 10390774464 10836180992 1195421008 12.734 100 94 184 0 1497652064 281082704 21148352
12:00:45 1691 8192000000 10439536176 10843553792 1192061792 12.703 100 100 196 0 1542658752 327850992 22745968
12:01:51 4134 8192000000 10426045744 10857926656 1189806272 12.682 100 73 142 0 1534079376 326863440 17409664
12:02:57 7015 8192000000 10383240880 10816065536 1182260448 12.611 100 43 80 0 1489393424 296601040 10531936
12:04:07 13208 8192000000 10425612000 10875387904 1178776736 12.579 100 37 73 0 1532324496 343467680 10080080
12:05:26 11512 8192000000 10415606656 10866401280 1177337328 12.565 100 41 82 0 1517953392 330209712 10406352
12:06:40 11982 8192000000 10367640688 10819432448 1162575472 12.427 99 38 67 2 1457149120 285571616 9002032
12:08:08 8541 8192000000 10273151088 10756034560 1144713600 12.260 98 39 77 0 1360574448 206886272 8974576
12:09:17 9763 8192000000 10275074128 10717257728 1154706896 12.354 99 40 78 2 1375021120 210647280 9666944
12:10:28 45616 8192000000 10277216560 10823827456 1170801168 12.504 100 17 34 0 1382494416 207115280 4577968
12:13:11 34679 8192000000 10108254848 10646130688 1138594752 12.202 99 14 28 1 1229143280 87316816 3231712
12:14:47 164299 8192000000 10049776800 10489671680 1095403520 11.794 96 22 36 1 1129624320 29957008 4263792
12:18:40 63543 8192000000 10074829680 10521006080 1110903472 11.941 98 23 44 1 1163086640 47909936 4273232
12:20:45 136377 8192000000 10151821008 10601807872 1130683728 12.128 99 38 74 0 1221022912 81915184 8424000
12:24:16 42593 8192000000 10076074608 10535038976 1135043968 12.169 100 11 22 0 1177346176 40411536 1890672
12:26:00 8182 8192000000 10081221760 10513543168 1135373104 12.172 100 46 90 0 1183660288 39358368 8928816
12:27:10 9506 8192000000 10131179344 10566127616 1138988960 12.206 100 68 115 2 1224612816 74109952 11513904
12:28:21 147457 8192000000 10082678464 10544123904 1079701920 11.645 94 20 40 0 1118031648 34499920 3829808
12:32:13 52861 8192000000 10084047584 10521870336 1107798672 11.912 97 19 37 0 1167867536 56439088 3629776
12:34:14 53548 8192000000 10104968864 10598129664 1139095568 12.207 100 8 16 0 1185234224 44699024 1439632
12:36:27 13547 8192000000 10072045824 10500239360 1110410272 11.936 97 59 111 3 1152847024 30779328 11657424
12:37:47 26105 8192000000 10123085296 10552832000 1130295264 12.124 99 41 80 0 1197797280 59206448 8295568
12:39:13 34348 8192000000 10157855584 10620030976 1142008800 12.234 100 25 50 0 1250286608 102992480 5285328
12:40:56 24844 8192000000 10099311792 10530361344 1116955712 11.998 99 34 65 2 1176478592 53029856 6493024
12:42:25 13298 8192000000 10093259184 10517520384 1125896848 12.083 100 52 102 0 1189505168 52780608 10827712
12:43:39 14064 8192000000 10132086352 10566107136 1123571664 12.061 100 54 108 0 1219067072 84680512 10814896
12:44:55 30349 8192000000 10122553280 10559381504 1133188816 12.151 100 48 93 0 1221521504 79024608 9308080
12:46:27 120394 8192000000 10070296496 10546065408 1115273440 11.982 98 36 70 0 1194003968 70834784 7895744
12:49:48 38102 8192000000 10086719936 10551664640 1139720272 12.213 100 30 56 0 1206480112 61327584 5432256
12:52:01 76524 8192000000 10096859776 10588012544 1117071968 11.999 98 12 24 0 1191822048 71703488 3046592
12:54:18 101662 8192000000 10103608816 10592710656 1094507088 11.785 96 16 32 0 1187379376 88931200 3941088
12:57:01 46971 8192000000 10148843424 10596331520 1152578464 12.334 100 22 44 0 1254934848 97542848 4813536
12:58:56 38579 8192000000 10145033312 10621345792 1143553952 12.249 100 19 38 0 1258797792 111128976 4114864
13:00:51 51959 8192000000 10120271600 10570670080 1135458880 12.173 99 14 28 0 1222037200 83563616 3014704
13:02:45 23867 8192000000 10107286576 10558418944 1121841744 12.044 99 41 82 1 1189911760 59476688 8593328
13:04:32 50596 8192000000 10113313424 10579103744 1110981296 11.942 98 26 52 0 1207483904 90985248 5517360
13:06:24 102589 8192000000 10123942032 10602266624 1102082912 11.857 97 19 38 0 1207645456 101436240 4126304
13:09:12 61194 8192000000 10116194720 10548453376 1097876480 11.817 97 40 80 0 1186424704 80508208 8040016
13:13:02 15723 8192000000 10064023664 10509471744 1123894240 12.064 99 57 110 1 1166758096 31537680 11326176
13:14:19 93999 8192000000 10103378336 10564022272 1130407760 12.125 99 12 24 0 1185795600 52855552 2532288
13:16:53 63876 8192000000 10087266112 10555265024 1138441104 12.201 100 12 23 0 1189961248 48551552 2968592
13:18:57 53540 8192000000 10098590448 10564808704 1124514192 12.070 99 12 24 0 1188348720 61270896 2563632
13:21:36 94869 8192000000 10047452544 10527141888 1099089664 11.829 97 12 24 0 1140736032 39260496 2385872
13:24:13 754 8192000000 10069073408 10497429504 1127559360 12.098 100 76 141 2 1163320656 20494896 15266400
13:25:14 22312 8192000000 10108280832 10556215296 1133165328 12.151 100 31 62 2 1203635904 63649872 6820704
13:26:37 45333 8192000000 10115775248 10549297152 1097465968 11.814 96 40 70 0 1183837360 78349888 8021504
13:28:24 55760 8192000000 10066120752 10499870720 1120737600 12.034 99 33 64 1 1178136800 51222464 6176736
13:30:23 34520 8192000000 10133755424 10586779648 1129804880 12.120 99 44 86 0 1199217984 60853824 8559280
13:32:01 57063 8192000000 10075151744 10582933504 1099887568 11.837 97 19 38 0 1216057200 112184192 3985440
13:34:01 10889 8192000000 10106352400 10548408320 1140628608 12.221 100 52 103 1 1199015040 47928320 10458112
13:35:15 102254 8192000000 10098918656 10580172800 1139178624 12.208 100 41 82 0 1233841648 86089920 8573104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-07-2022 13:39:21 to 08-07-2022 13:39:37.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-07-2022 13:39:38 to 08-07-2022 13:39:40.

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          221589
MON$OLDEST_ACTIVE               223907
MON$OLDEST_SNAPSHOT             223907
MON$NEXT_TRANSACTION            223907
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                       2051232
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
--- end of diff output ---

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

08.07.22 13:39:41. 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
2022-07-08 13:39:43 No severe PSQL-related problems occured
08.07.22 13:39:44. End of report.