Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.09.2021 10:48
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 16081760 kB
MemAvailable 30852548 kB
Buffers 0 kB
Cached 15629108 kB
SwapCached 416 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-09-15 10:47:09
DTS_END                         2021-09-15 10:47:09.7620
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/41048
TRN_ID                          232210
ATT_ID                          645
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: 9197.63 26178 1103715
customer order: creation 164.38 454 19726
customer order: refuse 14.04 318 1685
order to supplier: creation 44.77 1436 5372
order to supplier: removal 3.53 106167 424
invoice (draft): creation 44.68 3383 5361
invoice (draft): removal 52.95 885 6354
invoice accept: apply 40.39 20185 4847
invoice accept: cancel 48.10 835 5772
customer reserve: creation 3773.49 198 452819
customer reserve: removal 4723.28 63 566794
realization accept: apply 79.64 188 9557
realization accept: cancel 82.76 124 9931
payment from customer: creation 59.10 111 7092
payment from customer: removal 5.34 77 641
payment to supplier: creation 47.09 139 5651
payment to supplier: removal 3.49 106 419
service: total inventory turnovers 3.86 3260 463
service: total monetary turnovers 5.91 511 709
service: aggregate perf. data 0.78 20060 93
service: refresh index statistics 0.04 365057 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-09-2021 10:48:41 to 15-09-2021 10:48:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8858
TEST_TIME 2 6408
TEST_TIME 3 6748
TEST_TIME 4 7025
TEST_TIME 5 6775
TEST_TIME 6 6161
TEST_TIME 7 6316
TEST_TIME 8 6119
TEST_TIME 9 6197
TEST_TIME 10 6877
TEST_TIME 11 7008
TEST_TIME 12 7031
TEST_TIME 13 7329
TEST_TIME 14 7462
TEST_TIME 15 7934
TEST_TIME 16 8427
TEST_TIME 17 8715
TEST_TIME 18 9169
TEST_TIME 19 9077
TEST_TIME 20 9023
TEST_TIME 21 9296
TEST_TIME 22 9166
TEST_TIME 23 9241
TEST_TIME 24 9456
TEST_TIME 25 9489
TEST_TIME 26 10981
TEST_TIME 27 10831
TEST_TIME 28 10676
TEST_TIME 29 10763
TEST_TIME 30 10688
TEST_TIME 31 10468
TEST_TIME 32 10302
TEST_TIME 33 10168
TEST_TIME 34 10124
TEST_TIME 35 10055
TEST_TIME 36 9938
TEST_TIME 37 9995
TEST_TIME 38 10047
TEST_TIME 39 9936
TEST_TIME 40 9871
TEST_TIME 41 9840
TEST_TIME 42 9728
TEST_TIME 43 9686
TEST_TIME 44 9719
TEST_TIME 45 9612
TEST_TIME 46 9511
TEST_TIME 47 9691
TEST_TIME 48 9693
TEST_TIME 49 9842
TEST_TIME 50 9923
TEST_TIME 51 9929
TEST_TIME 52 9884
TEST_TIME 53 9979
TEST_TIME 54 9886
TEST_TIME 55 10036
TEST_TIME 56 9983
TEST_TIME 57 9880
TEST_TIME 58 9760
TEST_TIME 59 9693
TEST_TIME 60 9643
TEST_TIME 61 9563
TEST_TIME 62 9491
TEST_TIME 63 9462
TEST_TIME 64 9418
TEST_TIME 65 9357
TEST_TIME 66 9368
TEST_TIME 67 9356
TEST_TIME 68 9315
TEST_TIME 69 9263
TEST_TIME 70 9297
TEST_TIME 71 9598
TEST_TIME 72 9533
TEST_TIME 73 9526
TEST_TIME 74 9607
TEST_TIME 75 9635
TEST_TIME 76 9558
TEST_TIME 77 9535
TEST_TIME 78 9441
TEST_TIME 79 9427
TEST_TIME 80 9416
TEST_TIME 81 9355
TEST_TIME 82 9308
TEST_TIME 83 9296
TEST_TIME 84 9201
TEST_TIME 85 9167
TEST_TIME 86 9204
TEST_TIME 87 9201
TEST_TIME 88 9166
TEST_TIME 89 9160
TEST_TIME 90 9191
TEST_TIME 91 9221
TEST_TIME 92 9259
TEST_TIME 93 9212
TEST_TIME 94 9202
TEST_TIME 95 9317
TEST_TIME 96 9318
TEST_TIME 97 9322
TEST_TIME 98 9232
TEST_TIME 99 9214
TEST_TIME 100 9226
TEST_TIME 101 9178
TEST_TIME 102 9156
TEST_TIME 103 9164
TEST_TIME 104 9140
TEST_TIME 105 9134
TEST_TIME 106 9125
TEST_TIME 107 9184
TEST_TIME 108 9159
TEST_TIME 109 9213
TEST_TIME 110 9247
TEST_TIME 111 9243
TEST_TIME 112 9272
TEST_TIME 113 9288
TEST_TIME 114 9324
TEST_TIME 115 9341
TEST_TIME 116 9361
TEST_TIME 117 9338
TEST_TIME 118 9270
TEST_TIME 119 9275
TEST_TIME 120 9203

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-09-2021 10:48:44 to 15-09-2021 10:48:48.

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 1522485 1522485 0 0.00 0 36760 7 0 0
doc_list_biud 1522485 1522485 0 0.00 0 64930 30 0 0
sp_add_invoice_to_stock 4963 4847 116 2.33 28 236390 20186 0 116
sp_cancel_adding_invoice 5772 5772 0 0.00 4 91508 836 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 9782 318 0 0
sp_cancel_customer_reserve 566794 566794 0 0.00 1 31159 63 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 2416 77 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 10014 106 0 0
sp_cancel_supplier_invoice 6357 6354 3 0.04 2 64937 885 0 3
sp_cancel_supplier_order 424 424 0 0.00 11 893321 106167 0 0
sp_cancel_write_off 9931 9931 0 0.00 1 24203 125 0 0
sp_client_order 19726 19726 0 0.00 4 62895 454 0 0
sp_customer_reserve 452819 452819 0 0.00 0 88641 199 0 0
sp_fill_shopping_cart 30447 30447 0 0.00 1 20993 38 0 0
sp_fill_shopping_cart_clo_res 452808 452808 0 0.00 0 13762 17 0 0
sp_get_clo_for_invoice 4836 4836 0 0.00 0 27363 381 0 0
sp_kill_cost_storno 16763 16763 0 0.00 0 24197 57 0 0
sp_kill_qstorno_ret_qs2qd 1150297 1150297 0 0.00 0 64928 35 0 0
sp_kill_qty_storno 605352 605352 0 0.00 0 64929 74 0 0
sp_lock_selected_doc 20101 20101 0 0.00 0 9467 6 0 0
sp_make_cost_storno 27135 27135 0 0.00 0 36746 54 0 0
sp_multiply_rows_for_pdistr 27135 27135 0 0.00 0 13039 36 0 0
sp_multiply_rows_for_qdistr 19726 19726 0 0.00 0 59869 263 0 0
sp_pay_from_customer 7092 7092 0 0.00 4 12807 112 0 0
sp_pay_to_supplier 5651 5651 0 0.00 5 13055 140 0 0
sp_payment_common 12742 12742 0 0.00 1 9757 25 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 9773 287 0 0
sp_qd_handle_on_invoice_upd_sts 10607 10607 0 0.00 0 18328 321 0 0
sp_qd_handle_on_reserve_upd_sts 9557 9557 0 0.00 0 19651 56 0 0
sp_reserve_write_off 9557 9557 0 0.00 7 26984 188 0 0
sp_supplier_invoice 5361 5361 0 0.00 8 99922 3384 0 0
sp_supplier_order 5372 5372 0 0.00 7 33240 1436 0 0
srv_aggregate_perf_data 93 93 0 0.00 2899 185341 20060 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2000 148795 43751 0 0
srv_find_qd_qs_mism 1057023 1057023 0 0.00 0 26319 6 0 0
srv_make_invnt_saldo 463 463 0 0.00 46 105647 3261 0 0
srv_make_money_saldo 709 709 0 0.00 1 13492 511 0 0
srv_recalc_idx_stat 5 5 0 0.00 214043 519619 365058 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4847 4847 0 0.00 0 11391 163 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 1 12054 117 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 777 7 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 390 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 1762 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 641 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 982 22 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 19673 1396 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 4328 12 0 0
t$perf-norm:sp_client_order 19726 19726 0 0.00 0 10098 16 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 1016 5 0 0
t$perf-norm:sp_pay_from_customer 7092 7092 0 0.00 0 16941 11 0 0
t$perf-norm:sp_pay_to_supplier 5651 5651 0 0.00 0 4913 9 0 0
t$perf-norm:sp_reserve_write_off 9557 9557 0 0.00 0 3922 8 0 0
t$perf-norm:sp_supplier_invoice 5361 5361 0 0.00 0 8544 18 0 0
t$perf-norm:sp_supplier_order 5372 5372 0 0.00 0 4914 15 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 394 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 39 1 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 4324 24 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 3470 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 9902 9670 232 2.34 0 6729 16 0 232
v_all_customers 36648 36648 0 0.00 0 17 1 0 0
v_all_suppliers 29686 29686 0 0.00 0 26 1 0 0
v_all_wares 39448 39448 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 4388 11 0 0
v_cancel_client_order 3370 3370 0 0.00 0 3336 6 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 2398 10 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 4507 11 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1705 8 0 0
v_cancel_supplier_invoice 810 804 6 0.74 0 2372 16 0 6
v_cancel_supplier_order 848 848 0 0.00 0 826 5 0 0
v_cancel_write_off 2000 2000 0 0.00 0 4542 22 0 0
v_max_id_clo_ord 5372 5372 0 0.00 0 1503 2 0 0
v_max_id_clo_res 961 961 0 0.00 0 4183 12 0 0
v_max_id_ord_sup 5351 5351 0 0.00 0 6833 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 434 3 0 0
v_max_non_paid_realizn 3590 3590 0 0.00 0 7077 6 0 0
v_min_id_clo_ord 5372 5372 0 0.00 0 2870 6 0 0
v_min_id_clo_res 961 961 0 0.00 0 2719 10 0 0
v_min_id_ord_sup 5351 5351 0 0.00 0 2193 3 0 0
v_min_non_paid_invoice 5651 5651 0 0.00 0 9751 20 0 0
v_min_non_paid_realizn 7091 7091 0 0.00 0 7676 15 0 0
v_our_firm 9802 9802 0 0.00 0 19 1 0 0
v_reserve_write_off 19114 19114 0 0.00 0 4423 6 0 0
x_lock_depdocs_on_canc_invoice 11724 11724 0 0.00 0 11980 107 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 17004 332 0 0
x_make_qstorno_1000_1200 5372 5372 0 0.00 2 33231 1357 0 0
x_make_qstorno_1200_2000 5361 5361 0 0.00 3 99887 3247 0 0
x_make_qstorno_2100_3300 452818 452818 0 0.00 0 88640 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-09-2021 10:48:49 to 15-09-2021 10:48:53.

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:17:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:18:14 28956 8192000000 9727491216 10027945984 809110368 8.989 100 85 170 0 837677856 8328016 20239472
08:20:01 22314 8192000000 9772956592 10086125568 849177232 9.392 100 87 174 0 885014176 15163152 20673792
08:21:35 17873 8192000000 9854310768 10179915776 898772656 9.886 100 83 165 0 951656224 34782496 18101072
08:22:58 11518 8192000000 9906876992 10244071424 942724624 10.320 100 84 169 0 1011684272 50605456 18354192
08:24:12 9248 8192000000 9938636464 10286325760 972548976 10.612 100 80 159 0 1047616128 58686576 16380576
08:25:25 4626 8192000000 10002216512 10355777536 1006627056 10.943 100 88 174 2 1097130080 72760256 17742768
08:26:31 7885 8192000000 9999264304 10368434176 1029689648 11.165 100 83 162 0 1113459232 67000544 16769040
08:27:41 2980 8192000000 10016155552 10386604032 1054009760 11.399 100 91 182 0 1134632304 62436096 18186448
08:28:46 5827 8192000000 10013490336 10393128960 1068710528 11.540 100 80 156 2 1128086704 43587632 15788544
08:29:53 7423 8192000000 9990689440 10384744448 1076557360 11.615 100 33 64 4 1116075536 32389296 7128880
08:31:02 5257 8192000000 10021873616 10415493120 1083323024 11.679 100 40 80 0 1144933984 53453280 8157680
08:32:07 6732 8192000000 9989945072 10397847552 1085606944 11.701 100 45 89 2 1111991984 15707424 10677616
08:33:16 13012 8192000000 10003989104 10417664000 1094317168 11.784 100 25 50 0 1119840928 19825328 5698432
08:34:31 8371 8192000000 10018702848 10431647744 1099983712 11.837 100 44 89 0 1129333632 19325712 10024208
08:35:44 39026 8192000000 10025583616 10415177728 1099868272 11.836 100 52 104 2 1135147776 23172464 12107040
08:37:23 6138 8192000000 10011824848 10415706112 1073753600 11.588 99 48 94 0 1112210912 27392336 11064976
08:38:32 4488 8192000000 9988238656 10384875520 1075363904 11.603 100 40 81 1 1112557712 28324640 8869168
08:39:37 6196 8192000000 9966815488 10357878784 1025367008 11.124 97 31 60 1 1056088400 23980560 6740832
08:40:44 56129 8192000000 9914638912 10299019264 975356144 10.639 96 33 66 1 992266016 9642752 7267120
08:42:43 8667 8192000000 9944670144 10342875136 1021619264 11.088 99 34 70 1 1068073344 39431920 7022160
08:43:52 7806 8192000000 9973478176 10365792256 1047183280 11.334 100 44 85 1 1076963920 20235632 9545008
08:45:02 11187 8192000000 9982941296 10390253568 1062253408 11.478 100 25 48 3 1095927472 28288736 5385328
08:46:15 39062 8192000000 9988585696 10401714176 1064389936 11.498 100 40 80 4 1101270128 27692096 9188096
08:48:02 4670 8192000000 10011287696 10407653376 1074495872 11.595 100 45 90 0 1121431072 37963600 8971600
08:49:12 3295 8192000000 10094017696 10485567488 1093245840 11.774 100 66 133 0 1200039072 92444384 14348848
08:50:29 11325 8192000000 10079191088 10475532288 1106761056 11.902 100 56 112 2 1195341456 76627888 11952512
08:51:42 16762 8192000000 10127875440 10545541120 1117674272 12.005 100 55 109 0 1235819488 106323712 11821504
08:53:02 29248 8192000000 10169554048 10572132352 1132579056 12.146 100 51 101 0 1256351648 112826384 10946208
08:54:35 16307 8192000000 10179282224 10587488256 1129772368 12.119 100 45 89 3 1272641376 132401328 10467680
08:55:56 13760 8192000000 10204532416 10619338752 1139172192 12.208 100 54 105 0 1310631536 159231504 12227840
08:57:14 19044 8192000000 10225098640 10653892608 1164687856 12.447 100 48 96 0 1343297344 168086176 10523312
08:58:36 1993 8192000000 10244984608 10668855296 1174742016 12.541 100 82 158 3 1343760160 150600576 18417568
08:59:43 2455 8192000000 10307078096 10713612288 1177148192 12.564 100 91 181 0 1405161216 208562976 19450048
09:00:51 2910 8192000000 10306681232 10722963456 1174843856 12.542 100 76 150 1 1409512032 218374400 16293776
09:02:01 3483 8192000000 10337628720 10752233472 1178395536 12.575 100 79 158 0 1439206560 242981872 17829152
09:03:07 2498 8192000000 10312871648 10722775040 1162188048 12.424 100 72 144 1 1418488000 241029344 15270608
09:04:13 16767 8192000000 10256827584 10707283968 1144486480 12.258 99 31 62 1 1372457056 219370912 8599664
09:05:32 7664 8192000000 10240267824 10658607104 1156328160 12.369 100 31 62 0 1343712560 180258144 7126256
09:06:50 11657 8192000000 10296345168 10724425728 1155137920 12.358 100 41 82 1 1398165120 233295376 9731824
09:08:08 16022 8192000000 10297857632 10717798400 1142348416 12.238 98 33 66 0 1391504672 240973984 8182272
09:10:00 13893 8192000000 10329949632 10774138880 1147363184 12.285 100 31 62 4 1437046400 281963488 7719728
09:11:16 19891 8192000000 10376946384 10836127744 1136870880 12.186 100 22 44 1 1481274320 338297264 6106176
09:13:02 43526 8192000000 10096963200 10694922240 1093933744 11.780 98 25 49 1 1380272320 279821200 6517376
09:14:50 42617 8192000000 10084828480 10531586048 1114675024 11.977 100 13 26 0 1173211232 55573344 2962864
09:16:44 93701 8192000000 10085840064 10534440960 1114315776 11.973 99 24 48 0 1198835488 79264720 5254992
09:19:30 148795 8192000000 10066206576 10498203648 1072065488 11.572 94 25 50 1 1124966656 47871840 5029328
09:23:04 49427 8192000000 10119052160 10567798784 1104142096 11.877 98 35 70 0 1199613248 87378704 8092448
09:24:54 32162 8192000000 10063730080 10554175488 1110036416 11.933 99 19 38 0 1177328240 62567648 4724176
09:26:32 31876 8192000000 10067099680 10513285120 1104536592 11.881 99 10 20 0 1169392656 62675504 2180560
09:28:07 72352 8192000000 10063004352 10510508032 1121369920 12.040 100 13 26 0 1195588192 71307712 2910560
09:30:25 63611 8192000000 10086193168 10543116288 1131966128 12.140 100 17 34 0 1190585632 55222720 3396784
09:32:34 28852 8192000000 10113917712 10555310080 1146973424 12.281 100 31 60 0 1211189664 57188816 7027424
09:34:03 42303 8192000000 10229703888 10732380160 1157246352 12.377 100 33 66 0 1315349136 150422224 7680560
09:36:29 41141 8192000000 10121506704 10590392320 1128242976 12.105 98 18 36 1 1239279632 106749952 4286704
09:38:11 49486 8192000000 10113080944 10582667264 1147500432 12.286 100 33 66 2 1208984592 54772304 6711856
09:40:07 50222 8192000000 10094490064 10572869632 1147033664 12.282 100 16 32 0 1217455168 67241728 3179776
09:41:57 23114 8192000000 10147026176 10584989696 1143049328 12.244 99 50 100 3 1228392624 74861504 10481792
09:43:29 37231 8192000000 10120118208 10561044480 1152902864 12.337 100 43 85 0 1197231392 35799904 8528624
09:45:12 71140 8192000000 10081358000 10512896000 1095713744 11.797 96 47 95 3 1146374832 40731344 9929744
09:47:36 62727 8192000000 10070768176 10541895680 1111746192 11.949 98 7 14 0 1153304816 40043856 1514768
09:49:39 34597 8192000000 10064415536 10566565888 1128624944 12.108 100 6 12 0 1181142240 51217536 1299760
09:51:13 46984 8192000000 10080881456 10559741952 1112030272 11.952 99 12 24 2 1172156032 57175184 2950576
09:53:01 33076 8192000000 10102055520 10565181440 1110752656 11.940 99 31 62 1 1195848272 78500016 6595600
09:54:34 61858 8192000000 10150219408 10588991488 1091830208 11.760 96 29 57 1 1195286160 96823872 6632080
09:56:52 35701 8192000000 10178513744 10618155008 1078472592 11.633 96 26 52 0 1234241184 149932000 5836592
09:58:34 71627 8192000000 10057522624 10485338112 1085540656 11.700 97 17 32 0 1124187040 35500640 3145744
10:00:52 62507 8192000000 10037450224 10468433920 1109257744 11.925 99 26 52 0 1160262400 45746528 5258128
10:02:58 125380 8192000000 10033931888 10507825152 1097272272 11.812 98 17 32 4 1146339232 45771968 3294992
10:06:04 48546 8192000000 10066302976 10531069952 1105872208 11.893 98 28 54 3 1160330544 48742752 5715584
10:08:10 30958 8192000000 10035793840 10489704448 1125190496 12.076 100 13 26 1 1152681296 24976976 2513824
10:09:45 68668 8192000000 10063473344 10552692736 1095410256 11.794 97 17 35 1 1153133120 53920944 3801920
10:11:56 64490 8192000000 10098965680 10565447680 1132978112 12.149 100 16 33 0 1191748992 55422400 3348480
10:14:12 37265 8192000000 10051666432 10494025728 1084336192 11.689 96 17 34 0 1113258608 25136032 3786384
10:15:53 32392 8192000000 10085818368 10538287104 1130629648 12.127 100 22 42 0 1186945520 52040208 4275664
10:17:27 81461 8192000000 10089097584 10580733952 1091341776 11.755 96 16 32 0 1168574624 73897984 3334864
10:19:52 21060 8192000000 10085343344 10515079168 1130172736 12.123 100 37 71 2 1165096160 28152688 6770736
10:21:20 66406 8192000000 10106290496 10593284096 1092931104 11.771 96 29 58 1 1208901184 110104352 5865728
10:23:27 41309 8192000000 10128132224 10595721216 1134184448 12.161 100 31 61 0 1216052576 75897520 5970608
10:25:51 63544 8192000000 10090088768 10529906688 1128014128 12.103 99 26 52 0 1186637456 53294544 5328784
10:27:54 32003 8192000000 10061196304 10548015104 1129072144 12.113 99 6 12 0 1169017776 38914256 1031376
10:29:27 60976 8192000000 10076847392 10567061504 1131899664 12.139 99 13 26 0 1190525840 55820448 2805728
10:31:51 79000 8192000000 10127903584 10585993216 1155204144 12.358 100 26 52 1 1224614960 64083024 5327792
10:34:20 62788 8192000000 10124221120 10568482816 1137640160 12.193 98 22 44 1 1201216880 58649824 4926896
10:36:23 72140 8192000000 10077317936 10600292352 1112923408 11.960 97 13 26 0 1199149280 83221600 3004272
10:39:07 103091 8192000000 10127578416 10631720960 1126137344 12.085 98 8 16 0 1195212672 67373840 1701488
10:41:56 85040 8192000000 10089777536 10539180032 1072359760 11.575 94 10 20 0 1118351376 44128864 1862752
10:44:22 106211 8192000000 10071549120 10526347264 1085908880 11.704 96 20 40 0 1155878336 65483088 4486368
10:47:09 16720 8192000000 9365504304 9686257664 504810112 5.804 45 3 3 1 514725472 9482480 432880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-09-2021 10:48:54 to 15-09-2021 10:49:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-09-2021 10:49:10 to 15-09-2021 10:49:12.

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          232503
MON$OLDEST_ACTIVE               232504
MON$OLDEST_SNAPSHOT             232504
MON$NEXT_TRANSACTION            232504
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                       2038688
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

15.09.21 10:49:13. 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-09-15 10:49:16 No severe PSQL-related problems occured
15.09.21 10:49:17. End of report.