Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2021 04:27
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 16708572 kB
MemAvailable 31398156 kB
Buffers 20 kB
Cached 14887036 kB
SwapCached 60 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-06-11 04:27:00
DTS_END                         2021-06-11 04:27:00.9600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51636
TRN_ID                          230667
ATT_ID                          581
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: 8848.83 24912 1061860
customer order: creation 162.51 430 19501
customer order: refuse 14.00 345 1680
order to supplier: creation 42.45 1380 5094
order to supplier: removal 3.48 112768 418
invoice (draft): creation 43.52 3609 5222
invoice (draft): removal 51.16 832 6139
invoice accept: apply 39.44 18140 4733
invoice accept: cancel 46.61 892 5593
customer reserve: creation 3677.58 176 441310
customer reserve: removal 4484.61 71 538153
realization accept: apply 78.24 194 9389
realization accept: cancel 82.91 119 9949
payment from customer: creation 55.58 110 6669
payment from customer: removal 5.64 81 677
payment to supplier: creation 47.28 134 5674
payment to supplier: removal 3.20 88 384
service: total inventory turnovers 3.70 3588 444
service: total monetary turnovers 5.93 532 712
service: aggregate perf. data 0.96 12307 115
service: refresh index statistics 0.03 342443 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-06-2021 04:28:21 to 11-06-2021 04:28:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5333
TEST_TIME 2 6197
TEST_TIME 3 5261
TEST_TIME 4 5248
TEST_TIME 5 4816
TEST_TIME 6 5248
TEST_TIME 7 5865
TEST_TIME 8 5790
TEST_TIME 9 5641
TEST_TIME 10 5695
TEST_TIME 11 5972
TEST_TIME 12 6200
TEST_TIME 13 6498
TEST_TIME 14 6892
TEST_TIME 15 6976
TEST_TIME 16 7254
TEST_TIME 17 7596
TEST_TIME 18 7775
TEST_TIME 19 8067
TEST_TIME 20 8311
TEST_TIME 21 8449
TEST_TIME 22 8869
TEST_TIME 23 9022
TEST_TIME 24 9421
TEST_TIME 25 9838
TEST_TIME 26 10019
TEST_TIME 27 10738
TEST_TIME 28 10600
TEST_TIME 29 10444
TEST_TIME 30 10568
TEST_TIME 31 10229
TEST_TIME 32 10045
TEST_TIME 33 9942
TEST_TIME 34 9856
TEST_TIME 35 9654
TEST_TIME 36 9773
TEST_TIME 37 9787
TEST_TIME 38 9751
TEST_TIME 39 9724
TEST_TIME 40 9777
TEST_TIME 41 9703
TEST_TIME 42 9608
TEST_TIME 43 9824
TEST_TIME 44 9686
TEST_TIME 45 9646
TEST_TIME 46 9668
TEST_TIME 47 9635
TEST_TIME 48 9683
TEST_TIME 49 9669
TEST_TIME 50 9564
TEST_TIME 51 9743
TEST_TIME 52 9711
TEST_TIME 53 9739
TEST_TIME 54 9838
TEST_TIME 55 9816
TEST_TIME 56 9671
TEST_TIME 57 9511
TEST_TIME 58 9459
TEST_TIME 59 9380
TEST_TIME 60 9285
TEST_TIME 61 9239
TEST_TIME 62 9151
TEST_TIME 63 9130
TEST_TIME 64 9210
TEST_TIME 65 9301
TEST_TIME 66 9298
TEST_TIME 67 9264
TEST_TIME 68 9324
TEST_TIME 69 9361
TEST_TIME 70 9341
TEST_TIME 71 9432
TEST_TIME 72 9461
TEST_TIME 73 9490
TEST_TIME 74 9461
TEST_TIME 75 9564
TEST_TIME 76 9452
TEST_TIME 77 9347
TEST_TIME 78 9304
TEST_TIME 79 9245
TEST_TIME 80 9183
TEST_TIME 81 9144
TEST_TIME 82 9177
TEST_TIME 83 9169
TEST_TIME 84 9163
TEST_TIME 85 9116
TEST_TIME 86 9154
TEST_TIME 87 9200
TEST_TIME 88 9181
TEST_TIME 89 9179
TEST_TIME 90 9187
TEST_TIME 91 9187
TEST_TIME 92 9258
TEST_TIME 93 9175
TEST_TIME 94 9147
TEST_TIME 95 9127
TEST_TIME 96 9160
TEST_TIME 97 9105
TEST_TIME 98 9090
TEST_TIME 99 9072
TEST_TIME 100 9063
TEST_TIME 101 9062
TEST_TIME 102 9017
TEST_TIME 103 9019
TEST_TIME 104 8979
TEST_TIME 105 9100
TEST_TIME 106 9067
TEST_TIME 107 9145
TEST_TIME 108 9119
TEST_TIME 109 9138
TEST_TIME 110 9076
TEST_TIME 111 9047
TEST_TIME 112 9020
TEST_TIME 113 8973
TEST_TIME 114 8951
TEST_TIME 115 8932
TEST_TIME 116 8917
TEST_TIME 117 8904
TEST_TIME 118 8913
TEST_TIME 119 8918
TEST_TIME 120 8849

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-06-2021 04:28:25 to 11-06-2021 04:28:28.

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 1469505 1469505 0 0.00 0 13409 6 0 0
doc_list_biud 1469504 1469504 0 0.00 0 95462 32 0 0
sp_add_invoice_to_stock 4848 4733 115 2.37 15 169583 18140 0 115
sp_cancel_adding_invoice 5593 5593 0 0.00 5 91893 893 0 0
sp_cancel_client_order 1680 1680 0 0.00 4 28294 346 0 0
sp_cancel_customer_reserve 538153 538153 0 0.00 1 35864 72 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 6265 81 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 2772 88 0 0
sp_cancel_supplier_invoice 6150 6139 11 0.17 2 95464 833 0 11
sp_cancel_supplier_order 418 418 0 0.00 5 1036161 112769 0 0
sp_cancel_write_off 9949 9949 0 0.00 1 7089 119 0 0
sp_client_order 19501 19501 0 0.00 4 17079 431 0 0
sp_customer_reserve 441310 441310 0 0.00 0 60315 176 0 0
sp_fill_shopping_cart 29801 29801 0 0.00 1 11795 41 0 0
sp_fill_shopping_cart_clo_res 441310 441310 0 0.00 0 20986 18 0 0
sp_get_clo_for_invoice 4733 4733 0 0.00 0 36378 463 0 0
sp_kill_cost_storno 16603 16603 0 0.00 0 11870 57 0 0
sp_kill_qstorno_ret_qs2qd 1092812 1092812 0 0.00 0 95461 38 0 0
sp_kill_qty_storno 576054 576054 0 0.00 0 95461 82 0 0
sp_lock_selected_doc 19681 19681 0 0.00 0 9789 5 0 0
sp_make_cost_storno 26465 26465 0 0.00 0 13406 55 0 0
sp_multiply_rows_for_pdistr 26465 26465 0 0.00 0 9183 30 0 0
sp_multiply_rows_for_qdistr 19501 19501 0 0.00 0 17066 239 0 0
sp_pay_from_customer 6669 6669 0 0.00 4 10178 111 0 0
sp_pay_to_supplier 5674 5674 0 0.00 5 11131 135 0 0
sp_payment_common 12343 12343 0 0.00 1 6008 21 0 0
sp_qd_handle_on_cancel_clo 1680 1680 0 0.00 0 28289 311 0 0
sp_qd_handle_on_invoice_upd_sts 10326 10326 0 0.00 0 37967 371 0 0
sp_qd_handle_on_reserve_upd_sts 9389 9389 0 0.00 0 28656 65 0 0
sp_reserve_write_off 9389 9389 0 0.00 7 28725 195 0 0
sp_supplier_invoice 5222 5222 0 0.00 5 102398 3609 0 0
sp_supplier_order 5094 5094 0 0.00 5 29236 1381 0 0
srv_aggregate_perf_data 115 115 0 0.00 2688 149501 12308 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1549 133864 47839 0 0
srv_find_qd_qs_mism 1016085 1016085 0 0.00 0 11380 5 0 0
srv_make_invnt_saldo 444 444 0 0.00 42 113733 3588 0 0
srv_make_money_saldo 712 712 0 0.00 1 13071 532 0 0
srv_recalc_idx_stat 4 4 0 0.00 112423 596361 342444 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 4733 4733 0 0.00 0 9710 166 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 4155 103 0 0
t$perf-norm:sp_cancel_client_order 1680 1680 0 0.00 0 2012 11 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 370 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 3863 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 331 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 2632 16 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 15796 1193 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 2552 7 0 0
t$perf-norm:sp_client_order 19501 19501 0 0.00 0 6360 16 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 2998 9 0 0
t$perf-norm:sp_pay_from_customer 6669 6669 0 0.00 0 3848 8 0 0
t$perf-norm:sp_pay_to_supplier 5674 5674 0 0.00 0 7818 10 0 0
t$perf-norm:sp_reserve_write_off 9389 9389 0 0.00 0 7178 10 0 0
t$perf-norm:sp_supplier_invoice 5222 5222 0 0.00 0 7321 20 0 0
t$perf-norm:sp_supplier_order 5094 5094 0 0.00 0 4850 15 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 65 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 1412 13 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 7464 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9696 9466 230 2.37 0 4831 18 0 230
v_all_customers 35674 35674 0 0.00 0 19 1 0 0
v_all_suppliers 28990 28990 0 0.00 0 93 1 0 0
v_all_wares 39002 39002 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1871 12 0 0
v_cancel_client_order 3360 3360 0 0.00 0 2160 5 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 5084 13 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 3162 12 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 1897 12 0 0
v_cancel_supplier_invoice 884 862 22 2.48 0 506 11 0 22
v_cancel_supplier_order 836 836 0 0.00 0 3592 21 0 0
v_cancel_write_off 1846 1846 0 0.00 0 2469 15 0 0
v_max_id_clo_ord 5090 5090 0 0.00 0 2058 2 0 0
v_max_id_clo_res 968 968 0 0.00 0 6433 23 0 0
v_max_id_ord_sup 5210 5210 0 0.00 0 5583 5 0 0
v_max_non_paid_invoice 1479 1479 0 0.00 0 444 2 0 0
v_max_non_paid_realizn 3549 3549 0 0.00 0 2871 5 0 0
v_min_id_clo_ord 5090 5090 0 0.00 0 11754 8 0 0
v_min_id_clo_res 968 968 0 0.00 0 3022 9 0 0
v_min_id_ord_sup 5210 5210 0 0.00 0 4200 5 0 0
v_min_non_paid_invoice 5674 5674 0 0.00 0 4227 12 0 0
v_min_non_paid_realizn 6669 6669 0 0.00 0 6003 14 0 0
v_our_firm 9566 9566 0 0.00 0 14 1 0 0
v_reserve_write_off 18776 18776 0 0.00 0 4336 8 0 0
x_lock_depdocs_on_canc_invoice 11301 11301 0 0.00 0 14172 116 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 10182 357 0 0
x_make_qstorno_1000_1200 5094 5094 0 0.00 2 29165 1310 0 0
x_make_qstorno_1200_2000 5222 5222 0 0.00 2 102382 3450 0 0
x_make_qstorno_2100_3300 441310 441310 0 0.00 0 60296 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2021 04:28:29 to 11-06-2021 04:28:33.

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
01:57:04 8 8192000000 8796668768 8807174144 3189024 0.038 1 1 2 0 3286640 61840 35776
01:58:06 45774 8192000000 9729989120 10028232704 800766176 8.904 100 89 178 0 830373840 7739824 21867840
02:00:01 29402 8192000000 9773457984 10081460224 839805104 9.298 100 95 190 0 879325872 16324400 23196368
02:01:35 18726 8192000000 9824857408 10143129600 874204912 9.642 100 88 176 0 930851152 36603808 20042432
02:02:59 8911 8192000000 9878251440 10216534016 921006864 10.106 100 74 148 1 991380416 54011488 16362064
02:04:10 8014 8192000000 9936975280 10286374912 969051040 10.577 100 78 156 1 1054119712 69116176 15952496
02:05:20 4646 8192000000 9971996592 10330968064 1011395056 10.989 100 81 161 1 1081043792 53095216 16553520
02:06:26 7144 8192000000 10004230592 10368000000 1031436512 11.182 100 76 151 2 1113457440 66887664 15133264
02:07:34 1361 8192000000 10053147168 10422157312 1056065488 11.419 100 94 188 0 1162946048 87306800 19573760
02:08:37 38236 8192000000 10000408576 10407174144 1069259840 11.545 100 43 84 0 1109274016 31232832 8781344
02:10:16 7426 8192000000 10005556528 10409750528 1083139200 11.677 100 29 58 0 1126512448 37471728 5901520
02:11:24 9289 8192000000 10014331968 10413346816 1085587296 11.701 100 31 62 2 1119820032 27640448 6592288
02:12:35 91109 8192000000 10006012720 10421387264 1093151632 11.773 100 25 48 0 1115991616 17349840 5490144
02:15:11 8180 8192000000 10014257552 10424561664 1096866496 11.808 100 34 67 0 1125571152 20810880 7893776
02:16:21 5650 8192000000 10014233440 10417455104 1096300992 11.803 100 34 68 0 1125034768 21690528 7043248
02:17:29 16226 8192000000 9967918016 10368806912 1003396240 10.911 93 27 53 2 1030952944 21267888 6288816
02:18:48 18831 8192000000 9966110032 10372874240 996993264 10.849 93 23 46 1 1023928672 21941760 4993648
02:20:07 7251 8192000000 9956642368 10338041856 1025866608 11.129 99 36 72 0 1069873616 35681376 8325632
02:21:18 6569 8192000000 9944899952 10333503488 1015883200 11.032 99 31 62 1 1045210256 22440944 6886112
02:22:26 9603 8192000000 9956460480 10364252160 1027375088 11.143 99 31 62 0 1061362480 26946976 7040416
02:23:38 21757 8192000000 9951173392 10358116352 1034823248 11.215 99 34 68 0 1069607792 27707440 7077104
02:25:00 10364 8192000000 9969769696 10372308992 1059678320 11.453 100 43 85 4 1094996352 26380912 8937120
02:26:11 7654 8192000000 9978320080 10373349376 1066359152 11.517 100 30 60 2 1088350208 15181632 6809424
02:27:21 4045 8192000000 10014229536 10409410560 1093142976 11.773 100 48 97 1 1121847104 18614192 10089936
02:28:31 44824 8192000000 10066678032 10479861760 1104138624 11.877 100 47 92 0 1180649616 67246112 9264880
02:30:22 35180 8192000000 10066669840 10475175936 1121814016 12.044 100 31 62 0 1167831360 39690896 6326448
02:32:00 28726 8192000000 10131445744 10539626496 1135527840 12.173 100 61 121 0 1230407232 82052880 12826512
02:33:43 2358 8192000000 10138499840 10537738240 1138018992 12.197 99 69 136 1 1237536896 84527552 14990352
02:34:49 13257 8192000000 10150055152 10576130048 1160179792 12.405 100 56 112 0 1261639568 88961696 12498080
02:36:14 18734 8192000000 10199296320 10620309504 1170117120 12.498 100 45 90 3 1302955824 122569872 10268832
02:37:37 10294 8192000000 10237332944 10649841664 1156578160 12.371 99 58 116 1 1325942880 156674480 12690240
02:38:51 3389 8192000000 10278599904 10692145152 1181244608 12.602 100 83 164 1 1386796576 186010544 19541424
02:40:00 1881 8192000000 10309218304 10715992064 1170916512 12.505 100 91 182 2 1408718192 217032592 20769088
02:41:07 3854 8192000000 10314722160 10740318208 1168089440 12.479 99 74 148 0 1417256096 232010112 17156544
02:42:18 5590 8192000000 10379654048 10784567296 1172801152 12.523 100 81 161 2 1475862000 284590688 18470160
02:43:27 1546 8192000000 10381746960 10791649280 1164503088 12.445 100 83 166 0 1480357088 296129440 19724560
02:44:31 9767 8192000000 10383477872 10799026176 1153041488 12.338 100 63 125 1 1489463664 321302928 15119248
02:45:48 8679 8192000000 10374676592 10789318656 1154883568 12.355 100 44 88 1 1484578000 318403648 11290784
02:47:11 1991 8192000000 10395184480 10795200512 1145714016 12.269 99 76 151 2 1478064832 314699040 17651776
02:48:16 19144 8192000000 10349130464 10776354816 1135671344 12.175 99 34 69 0 1445837648 301145056 9021248
02:49:44 14392 8192000000 10354104288 10807803904 1146655392 12.278 100 36 72 1 1473006416 317335664 9015360
02:51:03 18556 8192000000 10297180336 10776645632 1133584064 12.155 100 26 50 3 1416599632 275594864 7420704
02:52:42 37579 8192000000 10219034032 10667569152 1100881648 11.846 98 15 30 0 1322369856 217603088 3885120
02:54:20 40202 8192000000 10041888528 10452336640 1079924304 11.647 96 26 54 1 1111833696 26904048 5005344
02:56:07 88991 8192000000 10120572272 10580000768 1104391360 11.879 98 18 36 0 1186035840 77641632 4002848
02:58:37 133864 8192000000 10096679888 10526212096 1133378480 12.153 100 46 92 0 1200725856 57081280 10266096
03:01:53 69424 8192000000 10222131840 10651881472 1126156256 12.085 97 30 59 1 1276475696 143148656 7170784
03:04:08 46935 8192000000 10195185344 10653536256 1145271088 12.265 100 22 45 0 1313136736 162589072 5276576
03:06:19 63553 8192000000 10165975344 10661670912 1145004400 12.263 100 13 26 0 1275247360 127132080 3110880
03:08:31 68104 8192000000 10159487456 10615123968 1148758128 12.298 100 15 30 2 1248103952 95952720 3393104
03:10:48 39479 8192000000 10076466048 10502029312 1114490432 11.975 97 30 58 4 1149445136 28525776 6428928
03:12:28 87980 8192000000 10113234368 10595594240 1138328160 12.200 100 21 42 0 1217310144 74149376 4832608
03:14:56 85680 8192000000 10044347024 10489884672 1084717904 11.692 95 13 26 0 1134579056 47025648 2835504
03:17:26 85245 8192000000 10107694256 10633494528 1149536624 12.305 100 27 54 0 1243536480 88241104 5758752
03:19:53 52647 8192000000 10144352480 10616213504 1152210144 12.330 100 17 34 2 1218090608 62311216 3569248
03:21:47 65672 8192000000 10084483744 10537025536 1122017472 12.046 97 12 24 1 1156551760 32655088 1879200
03:24:02 88962 8192000000 10085907440 10546270208 1154817984 12.355 100 22 44 1 1188714768 28562480 5334304
03:26:43 48511 8192000000 10115012496 10573189120 1163528720 12.436 100 18 36 1 1201962208 34931440 3502048
03:28:44 82512 8192000000 10183546032 10652954624 1146096240 12.273 97 12 24 1 1236174736 87343120 2735376
03:31:21 55893 8192000000 10152946288 10638909440 1162065808 12.423 100 30 60 0 1289808832 121161728 6581296
03:33:35 34937 8192000000 10111618672 10571710464 1163176128 12.433 100 18 36 1 1203511632 36794048 3541456
03:35:16 71360 8192000000 10103813472 10591784960 1114712384 11.977 96 15 30 0 1197302528 79315184 3274960
03:37:29 93028 8192000000 10081261968 10595020800 1082671088 11.673 94 18 36 0 1161412240 75042864 3698288
03:40:03 89259 8192000000 10077443328 10518740992 998447712 10.863 88 23 45 1 1026818352 23793808 4576832
03:42:37 80166 8192000000 10034671088 10516451328 1088376736 11.727 99 11 22 0 1134565680 43886752 2302192
03:45:25 84903 8192000000 10053170880 10512560128 1097102496 11.810 98 8 16 0 1147814608 48834784 1877328
03:49:25 43911 8192000000 10067133360 10520625152 1119509088 12.022 100 18 36 1 1160071024 36544880 4017056
03:51:23 54414 8192000000 10086009632 10525134848 1082623104 11.672 97 39 78 1 1130110656 39215360 8272192
03:53:24 77753 8192000000 10051873232 10539958272 1077997008 11.628 97 16 32 1 1165706016 84326352 3382656
03:55:44 20950 8192000000 10055773392 10481299456 1104289248 11.878 99 44 88 4 1136147248 22721968 9136032
03:57:08 37926 8192000000 10077637616 10517516288 1117411168 12.003 100 36 73 0 1169273376 44934544 6927664
03:58:46 40410 8192000000 10132797088 10610274304 1120675792 12.033 99 26 52 1 1213790688 87144384 5970512
04:00:35 73400 8192000000 10119840640 10567331840 1138989888 12.206 100 13 26 0 1200776272 58984448 2801936
04:03:06 52526 8192000000 10092477232 10664562688 1137708576 12.194 100 6 12 0 1217563168 78392208 1462384
04:05:02 69995 8192000000 10063759360 10535702528 1110560944 11.938 98 18 36 0 1152802944 38170064 4071936
04:07:19 72401 8192000000 10106915312 10554908672 1105758368 11.892 96 21 43 1 1162232464 51889264 4584832
04:09:33 30774 8192000000 10154278352 10599927808 1148529984 12.296 100 55 105 2 1231846864 71914912 11401968
04:11:14 29715 8192000000 10150383680 10592395264 1126939680 12.093 98 22 44 0 1211936432 80426816 4569936
04:12:45 37481 8192000000 10065730864 10511937536 1118276128 12.011 98 10 20 1 1151945072 32052480 1616464
04:14:22 76687 8192000000 10068911920 10527776768 1107261792 11.906 97 7 14 1 1167652944 58835632 1555520
04:16:47 125102 8192000000 10086848480 10563452928 1127554336 12.098 99 32 64 0 1172145104 37685936 6904832
04:20:02 56594 8192000000 10077934256 10562830336 1117039488 11.999 98 5 10 0 1151806976 33932752 834736
04:22:14 40875 8192000000 10073888848 10530217984 1121213536 12.038 98 19 38 1 1163866896 38587056 4066304
04:23:56 21232 8192000000 10125686240 10573262848 1148223056 12.293 100 22 43 0 1214172208 61264544 4684608
04:25:21 62730 8192000000 10153400336 10613506048 1123826112 12.063 99 26 52 1 1238136880 108013824 6296944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-06-2021 04:28:35 to 11-06-2021 04:28:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 11-06-2021 04:28:50 to 11-06-2021 04:28:52.

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          225191
MON$OLDEST_ACTIVE               230774
MON$OLDEST_SNAPSHOT             230774
MON$NEXT_TRANSACTION            230774
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                       2095360
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2514 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2514 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Fri Jun 11 04:27:14 2021
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:100: 
--- end of diff output ---

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

11.06.21 04:28:53. 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-06-11 04:28:56 No severe PSQL-related problems occured
11.06.21 04:28:57. End of report.