Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16481972 kB
MemAvailable 31423608 kB
Buffers 0 kB
Cached 15176828 kB
SwapCached 0 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-12-16 10:37:33
DTS_END                         2021-12-16 10:37:10.2550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60872
TRN_ID                          235455
ATT_ID                          259
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: 9170.64 22943 1100477
customer order: creation 174.32 383 20918
customer order: refuse 15.31 371 1837
order to supplier: creation 46.58 1390 5590
order to supplier: removal 3.44 126045 413
invoice (draft): creation 46.16 3729 5539
invoice (draft): removal 49.83 1097 5979
invoice accept: apply 42.56 19070 5107
invoice accept: cancel 44.84 1019 5381
customer reserve: creation 3988.31 183 478597
customer reserve: removal 4472.08 75 536650
realization accept: apply 85.19 207 10223
realization accept: cancel 69.13 135 8295
payment from customer: creation 61.53 136 7383
payment from customer: removal 5.86 151 703
payment to supplier: creation 50.28 147 6033
payment to supplier: removal 3.42 91 410
service: total inventory turnovers 4.37 3426 524
service: total monetary turnovers 6.49 552 779
service: aggregate perf. data 0.93 16739 112
service: refresh index statistics 0.03 283917 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2021 10:38:10 to 16-12-2021 10:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3584
TEST_TIME 2 5543
TEST_TIME 3 5021
TEST_TIME 4 4559
TEST_TIME 5 5401
TEST_TIME 6 5567
TEST_TIME 7 5649
TEST_TIME 8 5759
TEST_TIME 9 5638
TEST_TIME 10 6180
TEST_TIME 11 6135
TEST_TIME 12 6128
TEST_TIME 13 6200
TEST_TIME 14 6126
TEST_TIME 15 6144
TEST_TIME 16 6362
TEST_TIME 17 6490
TEST_TIME 18 6494
TEST_TIME 19 6908
TEST_TIME 20 7182
TEST_TIME 21 7354
TEST_TIME 22 7872
TEST_TIME 23 7958
TEST_TIME 24 8114
TEST_TIME 25 8304
TEST_TIME 26 8244
TEST_TIME 27 8496
TEST_TIME 28 8851
TEST_TIME 29 8995
TEST_TIME 30 9391
TEST_TIME 31 10616
TEST_TIME 32 10640
TEST_TIME 33 10575
TEST_TIME 34 10520
TEST_TIME 35 10364
TEST_TIME 36 10297
TEST_TIME 37 10286
TEST_TIME 38 10381
TEST_TIME 39 10292
TEST_TIME 40 10432
TEST_TIME 41 10196
TEST_TIME 42 10102
TEST_TIME 43 10033
TEST_TIME 44 9970
TEST_TIME 45 9956
TEST_TIME 46 9859
TEST_TIME 47 9861
TEST_TIME 48 9870
TEST_TIME 49 9890
TEST_TIME 50 9817
TEST_TIME 51 9750
TEST_TIME 52 9650
TEST_TIME 53 9518
TEST_TIME 54 9594
TEST_TIME 55 9541
TEST_TIME 56 9548
TEST_TIME 57 9563
TEST_TIME 58 9593
TEST_TIME 59 9610
TEST_TIME 60 9603
TEST_TIME 61 9605
TEST_TIME 62 9526
TEST_TIME 63 9520
TEST_TIME 64 9752
TEST_TIME 65 9737
TEST_TIME 66 9782
TEST_TIME 67 9832
TEST_TIME 68 9823
TEST_TIME 69 9740
TEST_TIME 70 9630
TEST_TIME 71 9548
TEST_TIME 72 9661
TEST_TIME 73 9590
TEST_TIME 74 9518
TEST_TIME 75 9468
TEST_TIME 76 9412
TEST_TIME 77 9398
TEST_TIME 78 9338
TEST_TIME 79 9310
TEST_TIME 80 9316
TEST_TIME 81 9288
TEST_TIME 82 9299
TEST_TIME 83 9347
TEST_TIME 84 9367
TEST_TIME 85 9322
TEST_TIME 86 9303
TEST_TIME 87 9466
TEST_TIME 88 9503
TEST_TIME 89 9534
TEST_TIME 90 9472
TEST_TIME 91 9389
TEST_TIME 92 9354
TEST_TIME 93 9327
TEST_TIME 94 9295
TEST_TIME 95 9260
TEST_TIME 96 9289
TEST_TIME 97 9258
TEST_TIME 98 9305
TEST_TIME 99 9299
TEST_TIME 100 9346
TEST_TIME 101 9344
TEST_TIME 102 9351
TEST_TIME 103 9358
TEST_TIME 104 9412
TEST_TIME 105 9431
TEST_TIME 106 9444
TEST_TIME 107 9432
TEST_TIME 108 9387
TEST_TIME 109 9328
TEST_TIME 110 9317
TEST_TIME 111 9288
TEST_TIME 112 9248
TEST_TIME 113 9199
TEST_TIME 114 9156
TEST_TIME 115 9182
TEST_TIME 116 9209
TEST_TIME 117 9188
TEST_TIME 118 9202
TEST_TIME 119 9216
TEST_TIME 120 9174

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2021 10:38:14 to 16-12-2021 10:38:17.

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 1541250 1541250 0 0.00 0 43616 7 0 0
doc_list_biud 1541250 1541250 0 0.00 0 39403 33 0 0
sp_add_invoice_to_stock 5224 5107 117 2.23 36 217218 19071 0 117
sp_cancel_adding_invoice 5381 5381 0 0.00 5 67105 1019 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 9292 371 0 0
sp_cancel_customer_reserve 536650 536650 0 0.00 1 36164 76 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 14314 152 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 1850 91 0 0
sp_cancel_supplier_invoice 5989 5979 10 0.16 2 37567 1098 0 10
sp_cancel_supplier_order 413 413 0 0.00 13 1204378 126045 0 0
sp_cancel_write_off 8295 8295 0 0.00 1 16037 135 0 0
sp_client_order 20918 20918 0 0.00 4 31028 384 0 0
sp_customer_reserve 478597 478597 0 0.00 0 84567 183 0 0
sp_fill_shopping_cart 32029 32029 0 0.00 1 21400 44 0 0
sp_fill_shopping_cart_clo_res 478589 478589 0 0.00 0 21816 18 0 0
sp_get_clo_for_invoice 5099 5099 0 0.00 0 27550 363 0 0
sp_kill_cost_storno 14789 14789 0 0.00 0 16098 77 0 0
sp_kill_qstorno_ret_qs2qd 1087987 1087987 0 0.00 0 37558 41 0 0
sp_kill_qty_storno 573877 573877 0 0.00 0 39401 88 0 0
sp_lock_selected_doc 21220 21220 0 0.00 0 9527 5 0 0
sp_make_cost_storno 28738 28738 0 0.00 0 42978 72 0 0
sp_multiply_rows_for_pdistr 28738 28738 0 0.00 0 15842 40 0 0
sp_multiply_rows_for_qdistr 20918 20918 0 0.00 0 13103 204 0 0
sp_pay_from_customer 7383 7383 0 0.00 4 42025 137 0 0
sp_pay_to_supplier 6033 6033 0 0.00 5 41256 148 0 0
sp_payment_common 13416 13416 0 0.00 1 16018 38 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 9271 334 0 0
sp_qd_handle_on_invoice_upd_sts 10480 10480 0 0.00 0 39399 407 0 0
sp_qd_handle_on_reserve_upd_sts 10223 10223 0 0.00 0 21941 52 0 0
sp_reserve_write_off 10223 10223 0 0.00 7 42017 208 0 0
sp_supplier_invoice 5539 5539 0 0.00 6 83799 3730 0 0
sp_supplier_order 5590 5590 0 0.00 5 35514 1391 0 0
srv_aggregate_perf_data 112 112 0 0.00 2482 167908 16739 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1704 151645 45533 0 0
srv_find_qd_qs_mism 1050311 1050311 0 0.00 0 22109 6 0 0
srv_make_invnt_saldo 524 524 0 0.00 33 115937 3427 0 0
srv_make_money_saldo 779 779 0 0.00 1 14867 553 0 0
srv_recalc_idx_stat 4 4 0 0.00 249184 343831 283917 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5107 5107 0 0.00 0 8855 162 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 7101 108 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 3711 10 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 956 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2917 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1558 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 832 12 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 21755 1786 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 2819 9 0 0
t$perf-norm:sp_client_order 20918 20918 0 0.00 0 17963 20 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 987 5 0 0
t$perf-norm:sp_pay_from_customer 7383 7383 0 0.00 0 5009 6 0 0
t$perf-norm:sp_pay_to_supplier 6033 6033 0 0.00 0 4804 12 0 0
t$perf-norm:sp_reserve_write_off 10223 10223 0 0.00 0 7376 11 0 0
t$perf-norm:sp_supplier_invoice 5539 5539 0 0.00 0 7125 20 0 0
t$perf-norm:sp_supplier_order 5590 5590 0 0.00 0 2218 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 463 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 3516 12 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 4689 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 615 156 0 0
v_add_invoice_to_stock 10431 10197 234 2.24 0 7006 18 0 234
v_all_customers 38196 38196 0 0.00 0 91 1 0 0
v_all_suppliers 31072 31072 0 0.00 0 61 1 0 0
v_all_wares 41836 41836 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1918 9 0 0
v_cancel_client_order 3674 3674 0 0.00 0 7397 8 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 4559 19 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 700 4 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1199 10 0 0
v_cancel_supplier_invoice 808 788 20 2.47 0 2325 15 0 20
v_cancel_supplier_order 826 826 0 0.00 0 1357 7 0 0
v_cancel_write_off 2044 2044 0 0.00 0 5828 22 0 0
v_max_id_clo_ord 5590 5590 0 0.00 0 417 1 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 4981 25 0 0
v_max_id_ord_sup 5521 5521 0 0.00 0 12313 7 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 341 2 0 0
v_max_non_paid_realizn 3914 3914 0 0.00 0 6847 8 0 0
v_min_id_clo_ord 5590 5590 0 0.00 0 12110 9 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 7860 21 0 0
v_min_id_ord_sup 5521 5521 0 0.00 0 5520 6 0 0
v_min_non_paid_invoice 6033 6033 0 0.00 0 15177 27 0 0
v_min_non_paid_realizn 7383 7383 0 0.00 0 15181 30 0 0
v_our_firm 10578 10578 0 0.00 0 14 1 0 0
v_reserve_write_off 20446 20446 0 0.00 0 4559 6 0 0
x_lock_depdocs_on_canc_invoice 10966 10966 0 0.00 0 10880 124 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 4000 378 0 0
x_make_qstorno_1000_1200 5590 5590 0 0.00 2 35504 1299 0 0
x_make_qstorno_1200_2000 5539 5539 0 0.00 3 83205 3568 0 0
x_make_qstorno_2100_3300 478597 478597 0 0.00 0 84565 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2021 10:38:18 to 16-12-2021 10:38:22.

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 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 29650 8192000000 9731155328 10027888640 800020816 8.897 100 88 176 0 828730064 7868608 20840640
08:09:49 25901 8192000000 9762801472 10064666624 834119744 9.241 100 94 188 0 870633440 13785152 22728544
08:11:19 26608 8192000000 9810594768 10125402112 855671472 9.457 100 77 154 0 904565824 30740304 18154048
08:12:47 8804 8192000000 9858820608 10187538432 910873216 10.006 100 81 162 0 967928272 38935072 18119984
08:14:03 6646 8192000000 9899141248 10241683456 961557472 10.504 100 70 139 1 1014453296 38690656 14205168
08:15:12 5284 8192000000 9965885872 10320728064 995732000 10.837 100 70 141 1 1075844288 66093664 14018624
08:16:19 10075 8192000000 9992763216 10360541184 1024145232 11.112 100 81 162 0 1111931488 71610896 16175360
08:17:33 1982 8192000000 10026356096 10393464832 1046639680 11.328 100 91 183 2 1140616416 74375760 19600976
08:18:38 1761 8192000000 10026760464 10396626944 1060308240 11.459 100 89 178 1 1138322928 60279664 17735024
08:19:45 1828 8192000000 10023772992 10402553856 1066762544 11.521 100 85 169 1 1149422688 65467504 17192640
08:20:51 3533 8192000000 10020468176 10402955264 1071973216 11.571 100 68 138 2 1123621328 37285312 14362800
08:21:55 4273 8192000000 10011480608 10412855296 1083557664 11.681 100 35 69 1 1135196080 44764816 6873600
08:23:00 63825 8192000000 9997833680 10399105024 1088617136 11.730 100 42 81 2 1112285136 14967888 8700112
08:25:05 5714 8192000000 9999620160 10394845184 1092572608 11.767 100 42 83 1 1120002368 18125008 9304752
08:26:12 8601 8192000000 10016507632 10430922752 1093506048 11.776 100 28 54 2 1119266224 19739312 6020864
08:27:21 4853 8192000000 9995592800 10397863936 1087545696 11.719 100 33 68 1 1115568608 20895872 7127040
08:28:26 26983 8192000000 10002704368 10394787840 1090422384 11.747 100 62 125 2 1120888816 16936400 13530032
08:29:53 7291 8192000000 9980360016 10382172160 1049202800 11.353 99 25 50 0 1083411360 28975520 5233040
08:31:04 9183 8192000000 9949364000 10333532160 1020588224 11.078 98 36 72 1 1053478976 25093024 7797728
08:32:15 6772 8192000000 9940600816 10318639104 1002489824 10.903 97 25 50 0 1039460800 30663488 6307488
08:33:23 29180 8192000000 9948353408 10315591680 1003136784 10.909 97 53 108 1 1025114368 10172400 11805184
08:34:53 3854 8192000000 9966675872 10345504768 1042485008 11.289 100 51 103 1 1077737696 24183072 11069616
08:35:57 5884 8192000000 9962649136 10349424640 1056475200 11.423 100 45 89 4 1076871264 9663312 10732752
08:37:04 8936 8192000000 9991396048 10391994368 1074274656 11.593 100 41 80 0 1105485200 22231088 8979456
08:38:24 114349 8192000000 10047776448 10450558976 1103119744 11.867 100 41 80 1 1167256336 54848384 9288208
08:41:39 16976 8192000000 10108920240 10514157568 1125388976 12.078 100 48 96 3 1214992496 79543648 10059872
08:43:02 7225 8192000000 10086746944 10490351616 1132090944 12.141 100 37 74 0 1184878224 44520480 8266800
08:44:14 7220 8192000000 10159208880 10560159744 1147416768 12.285 100 64 124 2 1252747776 92134608 13196400
08:45:27 13068 8192000000 10179060912 10596184064 1156245520 12.368 100 49 98 0 1287628384 120252720 11130144
08:46:48 15719 8192000000 10188741232 10618839040 1172357280 12.519 100 38 74 0 1316510816 135096896 9056640
08:48:09 7216 8192000000 10200840752 10626576384 1179695520 12.587 100 35 68 0 1303775952 115623456 8456976
08:49:25 2015 8192000000 10252169456 10658279424 1174896032 12.543 100 82 163 2 1352432208 159613120 17923056
08:50:32 16748 8192000000 10257361408 10676322304 1179191888 12.583 100 50 100 1 1366887024 176753056 10942080
08:52:03 5657 8192000000 10304158384 10715873280 1183981824 12.627 100 61 119 0 1405269408 207579552 13708032
08:53:13 4371 8192000000 10362780640 10768150528 1181064976 12.600 100 86 169 0 1454809008 254696416 19047616
08:54:24 3220 8192000000 10401436576 10807480320 1180801392 12.598 100 85 169 0 1494280432 293412048 20066992
08:55:35 3012 8192000000 10433279152 10839511040 1177518656 12.567 100 87 173 1 1525765280 327767184 20479440
08:56:45 2297 8192000000 10415912432 10848198656 1170914848 12.505 99 83 165 2 1508736160 318590192 19231120
08:57:53 2061 8192000000 10444164400 10850471936 1187867872 12.664 100 87 171 1 1538611040 330986544 19756624
08:59:00 1700 8192000000 10423021136 10857652224 1173243008 12.527 100 91 181 1 1533570432 339012032 21315392
09:00:07 9108 8192000000 10393015808 10829320192 1158361376 12.388 100 51 102 2 1503943296 333238224 12343696
09:01:23 2062 8192000000 10493703648 10897690624 1162436352 12.426 100 79 158 0 1586812592 405583376 18792864
09:02:30 5061 8192000000 10451827056 10870906880 1148808112 12.298 100 44 87 0 1546617952 386796048 11013792
09:03:40 15459 8192000000 10511913840 10940334080 1145478144 12.267 99 42 82 1 1597296736 440634960 11183632
09:05:03 12538 8192000000 10508165376 10953105408 1149919856 12.309 100 43 84 3 1604223760 443785248 10518656
09:06:19 22621 8192000000 10424685200 10953814016 1133837664 12.158 99 34 68 1 1550101056 407219872 9043520
09:07:42 13144 8192000000 10318658208 10799075328 1111538480 11.947 99 25 50 0 1422120096 304486288 6095328
09:09:00 65167 8192000000 10058090800 10490183680 1124179920 12.066 100 16 32 0 1194659408 67257520 3221968
09:11:15 38310 8192000000 10062908944 10506276864 1101791648 11.855 97 7 14 1 1128996016 25757824 1446544
09:13:04 35781 8192000000 10080951040 10499002368 1098053760 11.819 98 38 77 3 1142215888 36603472 7558656
09:14:43 44012 8192000000 10171218128 10623791104 1117699584 12.005 99 17 35 0 1264594384 142979456 3915344
09:16:28 151645 8192000000 10071757600 10532900864 1113130416 11.962 99 39 79 0 1237371120 115204912 9035792
09:20:20 61208 8192000000 10054620112 10504044544 1121077760 12.037 99 22 44 0 1159627248 33630464 4919024
09:22:24 30238 8192000000 10091495520 10523697152 1127947856 12.102 99 33 66 1 1178866128 43606384 7311888
09:24:06 68576 8192000000 10140987376 10616836096 1130597472 12.127 99 27 54 1 1213446368 76415760 6433136
09:26:15 55076 8192000000 10075857904 10558349312 1143037936 12.244 100 12 24 0 1203907616 58483184 2386496
09:28:13 21176 8192000000 10089610944 10558750720 1150167136 12.311 100 16 32 0 1189333824 35241936 3924752
09:29:35 70989 8192000000 10096306528 10550591488 1128335264 12.106 99 11 22 0 1193924192 63250256 2338672
09:32:03 48821 8192000000 10083914976 10552786944 1143771504 12.251 100 16 32 1 1208501328 61419936 3309888
09:34:01 36507 8192000000 10091966208 10543394816 1132100864 12.141 99 29 58 0 1184263776 45771760 6391152
09:35:40 40563 8192000000 10070525600 10525351936 1125374704 12.078 99 28 56 0 1177893744 46523024 5996016
09:37:21 72307 8192000000 10079337264 10521501696 1102374672 11.860 96 24 48 0 1152312192 45146672 4790848
09:39:34 63695 8192000000 10192284320 10649436160 1127214672 12.095 98 14 28 0 1226951440 96817568 2919200
09:41:38 70691 8192000000 10110203280 10585944064 1093903488 11.780 96 14 28 1 1161922096 64756352 3262256
09:43:49 110391 8192000000 10061066304 10504282112 1096294576 11.802 97 12 24 2 1136106704 37357776 2454352
09:46:44 142381 8192000000 10136228656 10597097472 1113699904 11.967 98 41 82 0 1228933888 106144272 9089712
09:50:11 34130 8192000000 10045608384 10547347456 1107238848 11.906 98 8 16 0 1151018016 42528064 1251104
09:51:46 46921 8192000000 10076249104 10533212160 1132792080 12.148 100 10 20 0 1159273264 24360736 2120448
09:53:35 24344 8192000000 10052141232 10492313600 1116233440 11.991 98 12 24 1 1148723440 29938160 2551840
09:55:01 48877 8192000000 10046880240 10487017472 1096546016 11.805 97 14 28 0 1125416528 25950592 2919920
09:56:58 41894 8192000000 10074113152 10534838272 1089949888 11.742 96 34 68 0 1155950832 58854864 7146080
09:58:45 48631 8192000000 10147736096 10588389376 1111608688 11.948 98 26 52 0 1204010816 86988752 5413376
10:00:37 66760 8192000000 10035979600 10478772224 1070909392 11.561 95 11 22 0 1096096496 22595776 2591328
10:02:50 41291 8192000000 10155453120 10582466560 1122501264 12.051 99 42 84 0 1230161888 98948240 8712384
10:04:41 51308 8192000000 10075989376 10555695104 1117708848 12.005 99 14 28 0 1184994032 63932496 3352688
10:06:38 119659 8192000000 10055782576 10492694528 1085990224 11.705 95 33 66 1 1135065296 42251056 6824016
10:10:17 60655 8192000000 10083067744 10550280192 1129256688 12.114 99 9 18 0 1171981440 41006352 1718400
10:12:24 75678 8192000000 10099562672 10581000192 1111278656 11.945 97 13 26 1 1162701968 48808672 2614640
10:14:41 104560 8192000000 10109359792 10588680192 1140901968 12.224 99 17 35 0 1213948976 69558640 3488368
10:17:29 68733 8192000000 10086100704 10529882112 1124457264 12.069 97 19 38 2 1157215408 28960832 3797312
10:19:38 91707 8192000000 10141531008 10617143296 1106424048 11.899 96 16 33 0 1225222880 115513136 3285696
10:22:10 118292 8192000000 10067110496 10573770752 1041948448 11.283 92 14 28 0 1087476560 42876224 2651888
10:25:09 89653 8192000000 10040952512 10508693504 1118892784 12.017 100 13 26 0 1161784736 40378528 2513424
10:27:40 45295 8192000000 10053141264 10528608256 1121860032 12.045 99 9 18 0 1160021984 36483856 1678096
10:31:08 76745 8192000000 10100599200 10566987776 1132245696 12.143 100 10 20 1 1213468064 78921968 2300400
10:33:26 60074 8192000000 10099477648 10581504000 1098040144 11.819 96 20 40 0 1198879808 96093856 4745808
10:35:36 59252 8192000000 10081291744 10543284224 1077704848 11.626 96 16 32 0 1155902896 74575120 3622928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2021 10:38:24 to 16-12-2021 10:38:39.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2021 10:38:40 to 16-12-2021 10:38:42.

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          252928
MON$OLDEST_ACTIVE               252929
MON$OLDEST_SNAPSHOT             252929
MON$NEXT_TRANSACTION            252929
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                       2050784
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

16.12.21 10:38:43. 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-12-16 10:38:45 No severe PSQL-related problems occured
16.12.21 10:38:46. End of report.