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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16030276 kB
MemAvailable 30804072 kB
Buffers 0 kB
Cached 15665984 kB
SwapCached 2484 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-11-05 10:37:10
DTS_END                         2021-11-05 10:37:10.2190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47190
TRN_ID                          242251
ATT_ID                          621
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: 8851.49 27590 1062179
customer order: creation 169.37 395 20324
customer order: refuse 14.13 307 1696
order to supplier: creation 46.97 1296 5636
order to supplier: removal 3.44 109799 413
invoice (draft): creation 46.78 3538 5614
invoice (draft): removal 48.10 1011 5772
invoice accept: apply 41.63 19516 4996
invoice accept: cancel 43.63 940 5235
customer reserve: creation 3851.84 192 462221
customer reserve: removal 4294.55 68 515346
realization accept: apply 84.84 198 10181
realization accept: cancel 75.68 130 9082
payment from customer: creation 59.68 176 7162
payment from customer: removal 5.53 92 663
payment to supplier: creation 50.01 191 6001
payment to supplier: removal 3.80 76 456
service: total inventory turnovers 3.85 3473 462
service: total monetary turnovers 6.55 493 786
service: aggregate perf. data 1.08 12254 129
service: refresh index statistics 0.03 397660 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-11-2021 10:37:59 to 05-11-2021 10:38:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5623
TEST_TIME 2 5295
TEST_TIME 3 4850
TEST_TIME 4 5077
TEST_TIME 5 5759
TEST_TIME 6 5460
TEST_TIME 7 5674
TEST_TIME 8 5972
TEST_TIME 9 6059
TEST_TIME 10 6032
TEST_TIME 11 6223
TEST_TIME 12 7256
TEST_TIME 13 7332
TEST_TIME 14 7631
TEST_TIME 15 7585
TEST_TIME 16 7686
TEST_TIME 17 8038
TEST_TIME 18 8463
TEST_TIME 19 8978
TEST_TIME 20 9375
TEST_TIME 21 9478
TEST_TIME 22 9716
TEST_TIME 23 9952
TEST_TIME 24 10032
TEST_TIME 25 10232
TEST_TIME 26 10837
TEST_TIME 27 11595
TEST_TIME 28 11481
TEST_TIME 29 11198
TEST_TIME 30 11059
TEST_TIME 31 10834
TEST_TIME 32 10674
TEST_TIME 33 10679
TEST_TIME 34 10548
TEST_TIME 35 10413
TEST_TIME 36 10281
TEST_TIME 37 10290
TEST_TIME 38 10309
TEST_TIME 39 10237
TEST_TIME 40 10128
TEST_TIME 41 10016
TEST_TIME 42 9846
TEST_TIME 43 9747
TEST_TIME 44 9641
TEST_TIME 45 9527
TEST_TIME 46 9467
TEST_TIME 47 9293
TEST_TIME 48 9268
TEST_TIME 49 9583
TEST_TIME 50 9724
TEST_TIME 51 9662
TEST_TIME 52 9645
TEST_TIME 53 9662
TEST_TIME 54 9645
TEST_TIME 55 9583
TEST_TIME 56 9622
TEST_TIME 57 9549
TEST_TIME 58 9580
TEST_TIME 59 9591
TEST_TIME 60 9618
TEST_TIME 61 9659
TEST_TIME 62 9518
TEST_TIME 63 9373
TEST_TIME 64 9438
TEST_TIME 65 9409
TEST_TIME 66 9374
TEST_TIME 67 9311
TEST_TIME 68 9255
TEST_TIME 69 9193
TEST_TIME 70 9137
TEST_TIME 71 9077
TEST_TIME 72 9140
TEST_TIME 73 9116
TEST_TIME 74 9127
TEST_TIME 75 9134
TEST_TIME 76 9135
TEST_TIME 77 9173
TEST_TIME 78 9139
TEST_TIME 79 9191
TEST_TIME 80 9215
TEST_TIME 81 9193
TEST_TIME 82 9283
TEST_TIME 83 9222
TEST_TIME 84 9225
TEST_TIME 85 9256
TEST_TIME 86 9156
TEST_TIME 87 9141
TEST_TIME 88 9110
TEST_TIME 89 9118
TEST_TIME 90 9077
TEST_TIME 91 9057
TEST_TIME 92 9058
TEST_TIME 93 9048
TEST_TIME 94 9084
TEST_TIME 95 9060
TEST_TIME 96 9046
TEST_TIME 97 9087
TEST_TIME 98 9072
TEST_TIME 99 9073
TEST_TIME 100 9099
TEST_TIME 101 9118
TEST_TIME 102 9129
TEST_TIME 103 9058
TEST_TIME 104 9086
TEST_TIME 105 9036
TEST_TIME 106 9009
TEST_TIME 107 8958
TEST_TIME 108 8921
TEST_TIME 109 8879
TEST_TIME 110 8904
TEST_TIME 111 8884
TEST_TIME 112 8881
TEST_TIME 113 8894
TEST_TIME 114 8930
TEST_TIME 115 8897
TEST_TIME 116 8886
TEST_TIME 117 8873
TEST_TIME 118 8934
TEST_TIME 119 8919
TEST_TIME 120 8855

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-11-2021 10:38:02 to 05-11-2021 10:38:06.

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 1488605 1488605 0 0.00 0 32577 8 0 0
doc_list_biud 1488604 1488604 0 0.00 0 37883 30 0 0
sp_add_invoice_to_stock 5105 4996 109 2.13 27 156386 19516 0 109
sp_cancel_adding_invoice 5235 5235 0 0.00 6 100185 940 0 0
sp_cancel_client_order 1696 1696 0 0.00 5 8322 307 0 0
sp_cancel_customer_reserve 515346 515346 0 0.00 1 25848 69 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 7097 93 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 3 1803 76 0 0
sp_cancel_supplier_invoice 5780 5772 8 0.13 2 37889 1011 0 8
sp_cancel_supplier_order 413 413 0 0.00 8 1062435 109800 0 0
sp_cancel_write_off 9082 9082 0 0.00 1 23465 130 0 0
sp_client_order 20324 20324 0 0.00 4 46347 395 0 0
sp_customer_reserve 462221 462221 0 0.00 0 57232 193 0 0
sp_fill_shopping_cart 31567 31567 0 0.00 1 14087 49 0 0
sp_fill_shopping_cart_clo_res 462218 462218 0 0.00 0 23821 17 0 0
sp_get_clo_for_invoice 4991 4991 0 0.00 0 27996 438 0 0
sp_kill_cost_storno 15436 15436 0 0.00 0 23436 62 0 0
sp_kill_qstorno_ret_qs2qd 1045959 1045959 0 0.00 0 37882 37 0 0
sp_kill_qty_storno 552715 552715 0 0.00 0 37883 80 0 0
sp_lock_selected_doc 21044 21044 0 0.00 0 7452 4 0 0
sp_make_cost_storno 28334 28334 0 0.00 0 32303 66 0 0
sp_multiply_rows_for_pdistr 28334 28334 0 0.00 0 32312 44 0 0
sp_multiply_rows_for_qdistr 20324 20324 0 0.00 0 23671 206 0 0
sp_pay_from_customer 7162 7162 0 0.00 4 38636 176 0 0
sp_pay_to_supplier 6001 6001 0 0.00 5 38457 192 0 0
sp_payment_common 13163 13163 0 0.00 1 31205 69 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 8313 279 0 0
sp_qd_handle_on_invoice_upd_sts 10225 10225 0 0.00 0 34065 362 0 0
sp_qd_handle_on_reserve_upd_sts 10181 10181 0 0.00 0 25997 59 0 0
sp_reserve_write_off 10181 10181 0 0.00 6 32337 198 0 0
sp_supplier_invoice 5614 5614 0 0.00 6 93423 3538 0 0
sp_supplier_order 5636 5636 0 0.00 5 36693 1296 0 0
srv_aggregate_perf_data 129 129 0 0.00 2894 134854 12255 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 862 174398 46204 0 0
srv_find_qd_qs_mism 1013523 1013523 0 0.00 0 16091 7 0 0
srv_make_invnt_saldo 462 462 0 0.00 31 81734 3474 0 0
srv_make_money_saldo 786 786 0 0.00 1 16147 493 0 0
srv_recalc_idx_stat 4 4 0 0.00 229573 616212 397661 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 0 18759 186 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 2823 81 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 1143 10 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 5586 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 4727 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 4339 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 6613 36 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 37380 1594 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 2833 8 0 0
t$perf-norm:sp_client_order 20324 20324 0 0.00 0 7456 15 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 5277 15 0 0
t$perf-norm:sp_pay_from_customer 7162 7162 0 0.00 0 8662 13 0 0
t$perf-norm:sp_pay_to_supplier 6001 6001 0 0.00 0 10520 15 0 0
t$perf-norm:sp_reserve_write_off 10181 10181 0 0.00 0 5627 11 0 0
t$perf-norm:sp_supplier_invoice 5614 5614 0 0.00 0 5138 22 0 0
t$perf-norm:sp_supplier_order 5636 5636 0 0.00 0 3327 13 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 410 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 187 4 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 11737 33 0 0
t$perf-norm:srv_make_money_saldo 786 786 0 0.00 0 2935 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10198 9980 218 2.13 0 7197 18 0 218
v_all_customers 36762 36762 0 0.00 0 20 1 0 0
v_all_suppliers 31056 31056 0 0.00 0 84 1 0 0
v_all_wares 40646 40646 0 0.00 0 96 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 120 6 0 0
v_cancel_client_order 3392 3392 0 0.00 0 1614 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 3002 9 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 77 3 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 714 3 0 0
v_cancel_supplier_invoice 888 872 16 1.80 0 3364 24 0 16
v_cancel_supplier_order 826 826 0 0.00 0 1663 8 0 0
v_cancel_write_off 2076 2076 0 0.00 0 1846 16 0 0
v_max_id_clo_ord 5636 5636 0 0.00 0 1006 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 16367 31 0 0
v_max_id_ord_sup 5608 5608 0 0.00 0 3260 3 0 0
v_max_non_paid_invoice 1717 1717 0 0.00 0 4252 5 0 0
v_max_non_paid_realizn 3934 3934 0 0.00 0 1657 3 0 0
v_min_id_clo_ord 5636 5636 0 0.00 0 7360 11 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 4838 14 0 0
v_min_id_ord_sup 5608 5608 0 0.00 0 3894 8 0 0
v_min_non_paid_invoice 6001 6001 0 0.00 0 31199 60 0 0
v_min_non_paid_realizn 7162 7162 0 0.00 0 29303 62 0 0
v_our_firm 10340 10340 0 0.00 0 84 1 0 0
v_reserve_write_off 20362 20362 0 0.00 0 3347 6 0 0
x_lock_depdocs_on_canc_invoice 10571 10571 0 0.00 0 29512 145 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 20640 403 0 0
x_make_qstorno_1000_1200 5636 5636 0 0.00 1 36683 1206 0 0
x_make_qstorno_1200_2000 5614 5614 0 0.00 3 90874 3358 0 0
x_make_qstorno_2100_3300 462221 462221 0 0.00 0 57155 173 0 0

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

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
08:07:13 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 37212 8192000000 9743680208 10043568128 816808816 9.066 100 95 190 0 848223552 8401584 23013152
08:09:59 16367 8192000000 9771311248 10082209792 843605664 9.336 100 84 168 0 877343152 13833456 19904032
08:11:19 21128 8192000000 9810858560 10132316160 881086416 9.710 100 90 180 0 926296320 24318400 20891504
08:12:50 9182 8192000000 9877475648 10214248448 927352800 10.169 100 76 152 1 985415760 41388464 16674496
08:14:11 7416 8192000000 9937189952 10287714304 972775440 10.614 100 76 150 0 1049069248 60520800 15773008
08:15:20 3443 8192000000 9965449408 10319446016 1002450624 10.902 100 94 186 1 1080766496 60262320 18053552
08:16:25 4886 8192000000 10006126720 10369658880 1026345792 11.133 100 89 178 0 1121947504 77193920 18407792
08:17:37 1495 8192000000 10025582048 10390896640 1048855248 11.350 100 92 185 1 1139049600 71096432 19097920
08:18:39 15430 8192000000 10050524752 10420936704 1061668400 11.472 100 92 183 0 1160486960 80198128 18620432
08:19:55 4921 8192000000 9992356656 10374664192 1065603408 11.510 100 40 78 2 1113568800 40112800 7852592
08:21:00 6210 8192000000 9993161424 10388815872 1075940592 11.609 100 34 66 0 1111057424 27731936 7384896
08:22:07 4590 8192000000 9987802528 10384486400 1087435936 11.718 100 29 59 0 1104032528 9734032 6862560
08:23:13 23684 8192000000 9992394832 10401280000 1089188080 11.735 100 56 112 1 1115310272 12649392 13472800
08:24:38 8369 8192000000 10012285392 10414915584 1091322384 11.755 100 36 70 2 1122283792 23306240 7655168
08:25:51 4812 8192000000 10011560288 10410070016 1098256976 11.821 100 38 79 1 1128510480 21885584 8367920
08:26:58 5531 8192000000 10017714432 10426695680 1104811680 11.883 100 38 76 1 1127252640 13175376 9265584
08:28:06 41998 8192000000 9998674656 10404118528 1031880864 11.187 94 26 52 0 1061649920 24340368 5428688
08:29:48 7132 8192000000 9977203824 10361229312 1013803696 11.012 98 44 86 0 1056113072 33019504 9289872
08:30:57 7960 8192000000 9949891312 10336776192 1015634608 11.030 98 47 90 2 1038823440 11877888 11310944
08:32:08 9622 8192000000 9939781168 10335584256 1030490864 11.173 99 35 68 4 1058127168 19870016 7766288
08:33:19 35509 8192000000 9961859520 10365440000 1039847152 11.263 99 36 73 0 1077310576 30153136 7310288
08:35:01 9223 8192000000 9999449328 10400010240 1063372592 11.489 100 44 87 2 1099494464 27135952 8985920
08:36:11 11345 8192000000 9990463232 10402070528 1075962032 11.609 100 40 80 0 1104990048 20671648 8356368
08:37:32 106852 8192000000 10038115408 10445844480 1095042928 11.791 100 40 80 0 1120805440 17611152 8151360
08:40:22 13122 8192000000 10095300400 10495561728 1115876944 11.988 100 54 107 1 1196950848 70085776 10988128
08:41:46 9205 8192000000 10149814272 10549022720 1126541232 12.089 100 69 138 0 1251366496 110551136 14274128
08:43:15 6928 8192000000 10154805120 10555957248 1139594128 12.212 100 63 126 2 1262898192 109941568 13362496
08:44:26 28464 8192000000 10215166768 10629079040 1147953072 12.290 100 61 122 0 1324550800 163195840 13401888
08:45:56 9951 8192000000 10217716560 10634678272 1160443904 12.407 100 53 106 1 1327345008 155200848 11700256
08:47:11 17148 8192000000 10286915536 10703269888 1179012640 12.581 100 59 117 0 1381484704 189651392 12820672
08:48:41 6027 8192000000 10314227120 10734579712 1171549744 12.511 100 58 115 1 1424185584 239094048 13541792
08:49:51 4590 8192000000 10298782352 10711498752 1179093120 12.582 100 79 158 1 1412494736 215673264 17728352
08:50:59 2302 8192000000 10326859104 10740445184 1167541920 12.474 100 80 156 0 1429846240 244346336 17957984
08:52:05 2818 8192000000 10340822976 10736640000 1135241808 12.171 99 94 184 1 1434045056 277469120 21334128
08:53:13 3828 8192000000 10359322528 10759700480 1134903760 12.168 98 85 170 0 1454629520 300203648 19522112
08:54:21 861 8192000000 10436923152 10851368960 1161741632 12.420 100 94 187 1 1530716880 347846752 21128496
08:55:27 4153 8192000000 10395182096 10821652480 1136836368 12.186 100 77 155 0 1490014144 335467872 17709904
08:56:34 7288 8192000000 10375495360 10787684352 1132539152 12.145 99 46 93 0 1471663856 327314816 11809888
08:57:52 5470 8192000000 10362824208 10772766720 1146810048 12.280 100 53 106 1 1456580880 296923648 12847184
08:59:02 27033 8192000000 10381135136 10804543488 1139595392 12.212 99 34 68 0 1454708512 305995088 9118032
09:00:30 21923 8192000000 10393812848 10838466560 1145495408 12.267 99 28 56 1 1490413712 337512688 7405616
09:01:54 15508 8192000000 10400524208 10843295744 1135495840 12.173 100 29 58 0 1494588768 351180384 7912544
09:03:12 23890 8192000000 10288545664 10771689472 1133743440 12.157 100 22 45 0 1431056912 291841792 5471680
09:04:39 18943 8192000000 10124204880 10556104704 1120600640 12.033 99 54 105 1 1190574128 57988336 11985152
09:06:27 34670 8192000000 10104838624 10531115008 1132393280 12.144 99 41 82 1 1189150784 48541616 8215888
09:08:04 67160 8192000000 10122792560 10570866688 1141399568 12.229 100 29 55 0 1211281776 64563760 5318448
09:10:20 48360 8192000000 10070469888 10544951296 1096763760 11.807 96 18 36 1 1160357648 59443232 4150656
09:12:08 87127 8192000000 10141930928 10582790144 1128994352 12.112 99 19 38 0 1200719344 67676368 4048624
09:15:04 50277 8192000000 10064787344 10527850496 1097058224 11.810 97 14 25 2 1177904704 76931920 3914560
09:17:12 94680 8192000000 10074465968 10521636864 1138648768 12.203 100 31 62 1 1181436352 35676896 7110688
09:19:56 6708 8192000000 10119967440 10556440576 1158038032 12.385 100 36 72 1 1224882176 59405184 7438960
09:21:07 60457 8192000000 10192933696 10653024256 1165366832 12.454 100 33 66 0 1315980592 143178720 7435040
09:23:08 56509 8192000000 10221361184 10698424320 1167860832 12.477 100 20 40 3 1325959888 153383600 4715456
09:25:13 102472 8192000000 10182929344 10767495168 1127025760 12.093 97 16 32 1 1344150688 212979024 4145904
09:28:07 75297 8192000000 10102750032 10566488064 1160582080 12.409 100 24 46 2 1204718048 39369504 4766464
09:30:23 83338 8192000000 10084563376 10550169600 1151907312 12.327 99 19 38 1 1204695424 48549568 4238544
09:32:51 35371 8192000000 10092468208 10543398912 1132475296 12.145 98 32 64 0 1168919728 28896720 7547712
09:34:30 15051 8192000000 10073108160 10504806400 1124292784 12.068 98 29 58 0 1158185744 28047952 5845008
09:35:49 49085 8192000000 10055456624 10517331968 1064879280 11.503 94 22 44 0 1136374064 67127216 4367568
09:37:39 154042 8192000000 10079151680 10509561856 1098547696 11.824 97 9 18 0 1132692224 32504304 1640224
09:41:30 65170 8192000000 10085033856 10550251520 1126031056 12.084 100 21 42 1 1170378880 39981984 4365840
09:43:48 24244 8192000000 10041240128 10509582336 1127575104 12.098 100 7 14 0 1154280672 25496816 1208752
09:45:15 67075 8192000000 10091296640 10530050048 1115134640 11.981 98 13 26 0 1172938512 54962592 2841280
09:47:22 75188 8192000000 10059469488 10500841472 1086549504 11.710 96 13 26 0 1150312080 61147280 2615296
09:49:38 56594 8192000000 10037783552 10474565632 1044828800 11.311 93 7 14 0 1071261648 25259408 1173440
09:52:09 61051 8192000000 10107542960 10545799168 1118701392 12.015 98 34 68 0 1196912112 70720000 7490720
09:54:10 73751 8192000000 10070701616 10498207744 1114833504 11.978 98 23 46 3 1138664912 19191696 4639712
09:56:27 42459 8192000000 10091103888 10514710528 1132707696 12.147 100 34 69 0 1178151104 38728112 6715296
09:58:30 18373 8192000000 10089955184 10510893056 1118732064 12.015 99 54 108 0 1165288720 35112992 11443664
09:59:56 39916 8192000000 10054458320 10475745280 1089193680 11.735 96 19 38 0 1110977488 18374192 3409616
10:01:42 55604 8192000000 10116568512 10594467840 1141885200 12.233 100 25 50 1 1213631200 66354896 5391104
10:03:38 87457 8192000000 10092923568 10541588480 1132862464 12.148 99 32 64 1 1214680176 74649024 7168688
10:06:06 49421 8192000000 10056827280 10545684480 1115254320 11.982 98 7 14 0 1160619760 43889920 1475520
10:08:00 43566 8192000000 10069178976 10540978176 1129561152 12.117 100 8 16 0 1154858800 23466336 1831312
10:09:47 71572 8192000000 10096896272 10561888256 1110390704 11.936 97 18 36 0 1181749648 67391968 3966976
10:12:00 32796 8192000000 10075846576 10500894720 1106054960 11.895 97 37 72 1 1142739488 28785184 7899344
10:13:38 174398 8192000000 10078718080 10558431232 1084137104 11.687 95 29 56 1 1166287456 76722608 5427744
10:17:37 68121 8192000000 10071593728 10552459264 1114929264 11.979 97 17 34 0 1177063632 59122784 3011584
10:19:47 115696 8192000000 10076947696 10533306368 1120486960 12.032 98 23 46 0 1157069840 31964080 4618800
10:23:09 58882 8192000000 10062847088 10505097216 1130415440 12.125 100 7 14 0 1162301872 30874064 1012368
10:25:14 46600 8192000000 10070662896 10535874560 1128139472 12.104 99 10 20 0 1174835808 44488656 2207680
10:27:43 32745 8192000000 10029472256 10463145984 1100662096 11.844 98 13 26 0 1130590912 27231360 2697456
10:29:22 57883 8192000000 10100053568 10546700288 1130907552 12.130 100 31 62 0 1194801584 56646896 7247136
10:31:21 39916 8192000000 10052774544 10503843840 1106894320 11.903 98 23 46 0 1162016512 50111344 5010848
10:33:01 45719 8192000000 10066097024 10488193024 1070781536 11.560 95 31 61 0 1143021024 65640640 6598848
10:34:47 57824 8192000000 10108935568 10557968384 1117578672 12.004 99 22 44 2 1192047488 70014480 4454336
10:36:45 38005 8192000000 9131045216 9412780032 282971872 3.338 25 4 8 0 313249680 29190976 1086832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-11-2021 10:38:12 to 05-11-2021 10:38:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2021 10:38:28 to 05-11-2021 10:38:30.

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          242902
MON$OLDEST_ACTIVE               242903
MON$OLDEST_SNAPSHOT             242903
MON$NEXT_TRANSACTION            242903
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                       2040608
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             816
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

05.11.21 10:38:31. 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-11-05 10:38:34 No severe PSQL-related problems occured
05.11.21 10:38:35. End of report.