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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15711888 kB
MemAvailable 30735184 kB
Buffers 0 kB
Cached 15961772 kB
SwapCached 2920 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-29 22:37:52
DTS_END                         2021-09-29 22:37:52.6250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36382
TRN_ID                          246328
ATT_ID                          677
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: 9338.33 21320 1120600
customer order: creation 175.81 307 21097
customer order: refuse 14.88 370 1786
order to supplier: creation 46.93 1271 5632
order to supplier: removal 3.42 109273 410
invoice (draft): creation 47.35 3303 5682
invoice (draft): removal 52.75 918 6330
invoice accept: apply 43.21 17096 5185
invoice accept: cancel 47.27 841 5672
customer reserve: creation 3973.34 168 476801
customer reserve: removal 4636.59 62 556391
realization accept: apply 85.18 179 10221
realization accept: cancel 80.31 119 9637
payment from customer: creation 59.19 126 7103
payment from customer: removal 6.20 98 744
payment to supplier: creation 49.60 128 5952
payment to supplier: removal 3.62 52 434
service: total inventory turnovers 5.21 941 625
service: total monetary turnovers 6.58 31 790
service: aggregate perf. data 0.87 22405 104
service: refresh index statistics 0.03 268713 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2021 22:38:38 to 29-09-2021 22:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1932
TEST_TIME 2 1685
TEST_TIME 3 2405
TEST_TIME 4 3043
TEST_TIME 5 3574
TEST_TIME 6 3567
TEST_TIME 7 4098
TEST_TIME 8 4505
TEST_TIME 9 4507
TEST_TIME 10 4570
TEST_TIME 11 5673
TEST_TIME 12 5943
TEST_TIME 13 5975
TEST_TIME 14 6433
TEST_TIME 15 6956
TEST_TIME 16 7130
TEST_TIME 17 7402
TEST_TIME 18 7550
TEST_TIME 19 7708
TEST_TIME 20 8136
TEST_TIME 21 8269
TEST_TIME 22 8251
TEST_TIME 23 8299
TEST_TIME 24 8370
TEST_TIME 25 8472
TEST_TIME 26 8602
TEST_TIME 27 9492
TEST_TIME 28 9884
TEST_TIME 29 10394
TEST_TIME 30 11237
TEST_TIME 31 11404
TEST_TIME 32 11286
TEST_TIME 33 11153
TEST_TIME 34 10985
TEST_TIME 35 10819
TEST_TIME 36 10739
TEST_TIME 37 10623
TEST_TIME 38 10638
TEST_TIME 39 10447
TEST_TIME 40 10406
TEST_TIME 41 10297
TEST_TIME 42 10227
TEST_TIME 43 10162
TEST_TIME 44 10062
TEST_TIME 45 9952
TEST_TIME 46 9846
TEST_TIME 47 9726
TEST_TIME 48 9767
TEST_TIME 49 9784
TEST_TIME 50 9704
TEST_TIME 51 9742
TEST_TIME 52 9635
TEST_TIME 53 9561
TEST_TIME 54 9564
TEST_TIME 55 9570
TEST_TIME 56 9478
TEST_TIME 57 9372
TEST_TIME 58 9278
TEST_TIME 59 9276
TEST_TIME 60 9311
TEST_TIME 61 9285
TEST_TIME 62 9283
TEST_TIME 63 9239
TEST_TIME 64 9299
TEST_TIME 65 9262
TEST_TIME 66 9382
TEST_TIME 67 9382
TEST_TIME 68 9397
TEST_TIME 69 9513
TEST_TIME 70 9518
TEST_TIME 71 9481
TEST_TIME 72 9543
TEST_TIME 73 9553
TEST_TIME 74 9485
TEST_TIME 75 9548
TEST_TIME 76 9503
TEST_TIME 77 9444
TEST_TIME 78 9391
TEST_TIME 79 9417
TEST_TIME 80 9394
TEST_TIME 81 9352
TEST_TIME 82 9315
TEST_TIME 83 9266
TEST_TIME 84 9239
TEST_TIME 85 9152
TEST_TIME 86 9150
TEST_TIME 87 9215
TEST_TIME 88 9243
TEST_TIME 89 9269
TEST_TIME 90 9265
TEST_TIME 91 9267
TEST_TIME 92 9353
TEST_TIME 93 9364
TEST_TIME 94 9386
TEST_TIME 95 9415
TEST_TIME 96 9433
TEST_TIME 97 9446
TEST_TIME 98 9461
TEST_TIME 99 9452
TEST_TIME 100 9590
TEST_TIME 101 9553
TEST_TIME 102 9475
TEST_TIME 103 9466
TEST_TIME 104 9432
TEST_TIME 105 9393
TEST_TIME 106 9394
TEST_TIME 107 9363
TEST_TIME 108 9376
TEST_TIME 109 9336
TEST_TIME 110 9302
TEST_TIME 111 9288
TEST_TIME 112 9290
TEST_TIME 113 9282
TEST_TIME 114 9311
TEST_TIME 115 9325
TEST_TIME 116 9330
TEST_TIME 117 9339
TEST_TIME 118 9329
TEST_TIME 119 9327
TEST_TIME 120 9342

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-09-2021 22:38:41 to 29-09-2021 22:38:45.

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 1560749 1560749 0 0.00 0 20644 6 0 0
doc_list_biud 1560741 1560741 0 0.00 0 52515 29 0 0
sp_add_invoice_to_stock 5266 5185 81 1.53 36 185984 17096 0 81
sp_cancel_adding_invoice 5672 5672 0 0.00 6 70594 842 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 12272 371 0 0
sp_cancel_customer_reserve 556391 556391 0 0.00 1 52519 63 0 0
sp_cancel_pay_from_customer 744 744 0 0.00 3 5806 98 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 2132 52 0 0
sp_cancel_supplier_invoice 6339 6330 9 0.14 2 15761 919 0 9
sp_cancel_supplier_order 410 410 0 0.00 6 1239570 109274 0 0
sp_cancel_write_off 9637 9637 0 0.00 1 20101 120 0 0
sp_client_order 21097 21097 0 0.00 4 17007 308 0 0
sp_customer_reserve 476801 476801 0 0.00 0 76390 168 0 0
sp_fill_shopping_cart 32406 32406 0 0.00 1 29828 42 0 0
sp_fill_shopping_cart_clo_res 476797 476797 0 0.00 0 15324 13 0 0
sp_get_clo_for_invoice 5179 5179 0 0.00 0 25128 353 0 0
sp_kill_cost_storno 16487 16487 0 0.00 0 9328 61 0 0
sp_kill_qstorno_ret_qs2qd 1129159 1129159 0 0.00 0 46771 35 0 0
sp_kill_qty_storno 595620 595620 0 0.00 0 52515 75 0 0
sp_lock_selected_doc 21240 21240 0 0.00 0 8616 7 0 0
sp_make_cost_storno 28455 28455 0 0.00 0 12549 52 0 0
sp_multiply_rows_for_pdistr 28447 28447 0 0.00 0 14705 29 0 0
sp_multiply_rows_for_qdistr 21097 21097 0 0.00 0 15364 138 0 0
sp_pay_from_customer 7103 7103 0 0.00 4 17797 126 0 0
sp_pay_to_supplier 5952 5952 0 0.00 5 18099 128 0 0
sp_payment_common 13053 13053 0 0.00 1 17708 33 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 11127 334 0 0
sp_qd_handle_on_invoice_upd_sts 10847 10847 0 0.00 0 14545 350 0 0
sp_qd_handle_on_reserve_upd_sts 10219 10219 0 0.00 0 14641 66 0 0
sp_reserve_write_off 10221 10221 0 0.00 6 14656 180 0 0
sp_supplier_invoice 5682 5682 0 0.00 7 88872 3304 0 0
sp_supplier_order 5632 5632 0 0.00 5 33214 1271 0 0
srv_aggregate_perf_data 104 104 0 0.00 2861 211869 22405 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1252 198212 49136 0 0
srv_find_qd_qs_mism 1071068 1071068 0 0.00 0 20111 5 0 0
srv_make_invnt_saldo 625 625 0 0.00 37 20856 941 0 0
srv_make_money_saldo 790 790 0 0.00 1 2987 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 107252 493773 268714 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5185 5185 0 0.00 0 19422 178 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 6121 159 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 5497 18 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 10313 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 744 744 0 0.00 0 10751 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 449 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1203 21 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 26567 1673 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 10201 25 0 0
t$perf-norm:sp_client_order 21097 21097 0 0.00 0 14966 22 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 8052 16 0 0
t$perf-norm:sp_pay_from_customer 7103 7103 0 0.00 0 10748 13 0 0
t$perf-norm:sp_pay_to_supplier 5952 5952 0 0.00 0 10397 14 0 0
t$perf-norm:sp_reserve_write_off 10221 10221 0 0.00 0 7361 12 0 0
t$perf-norm:sp_supplier_invoice 5682 5682 0 0.00 0 4828 16 0 0
t$perf-norm:sp_supplier_order 5632 5632 0 0.00 0 4214 15 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 258 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 488 8 0 0
t$perf-norm:srv_make_invnt_saldo 625 625 0 0.00 0 2537 29 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 3694 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 24 8 0 0
v_add_invoice_to_stock 10512 10350 162 1.54 0 4313 15 0 162
v_all_customers 37940 37940 0 0.00 0 26 1 0 0
v_all_suppliers 31254 31254 0 0.00 0 32 1 0 0
v_all_wares 42194 42194 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 642 7 0 0
v_cancel_client_order 3572 3572 0 0.00 0 4601 5 0 0
v_cancel_customer_prepayment 1488 1488 0 0.00 0 3903 7 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1066 5 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 60 2 0 0
v_cancel_supplier_invoice 824 806 18 2.18 0 461 15 0 18
v_cancel_supplier_order 820 820 0 0.00 0 314 3 0 0
v_cancel_write_off 2024 2024 0 0.00 0 697 12 0 0
v_max_id_clo_ord 5630 5630 0 0.00 0 169 1 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 2315 12 0 0
v_max_id_ord_sup 5679 5679 0 0.00 0 5932 5 0 0
v_max_non_paid_invoice 1629 1629 0 0.00 0 465 3 0 0
v_max_non_paid_realizn 3910 3910 0 0.00 0 3301 5 0 0
v_min_id_clo_ord 5630 5630 0 0.00 0 2761 5 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 7684 28 0 0
v_min_id_ord_sup 5679 5679 0 0.00 0 5461 10 0 0
v_min_non_paid_invoice 5950 5950 0 0.00 0 8829 21 0 0
v_min_non_paid_realizn 7102 7102 0 0.00 0 7884 19 0 0
v_our_firm 10638 10638 0 0.00 0 15 1 0 0
v_reserve_write_off 20436 20436 0 0.00 0 6486 6 0 0
x_lock_depdocs_on_canc_invoice 11599 11599 0 0.00 0 23585 117 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 18855 392 0 0
x_make_qstorno_1000_1200 5632 5632 0 0.00 1 33065 1188 0 0
x_make_qstorno_1200_2000 5682 5682 0 0.00 3 83064 3152 0 0
x_make_qstorno_2100_3300 476801 476801 0 0.00 0 76390 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2021 22:38:46 to 29-09-2021 22:38:50.

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
20:07:56 19 8192000000 8813108016 8827736064 16570400 0.201 3 3 4 0 16810672 78240 162032
20:08:58 22214 8192000000 10361842608 10675486720 1395319360 14.553 100 87 174 0 1429322704 5526624 28476720
20:10:25 29554 8192000000 10414857536 10740342784 1461244384 15.137 100 83 166 0 1496752352 8182128 27325840
20:11:59 14529 8192000000 10476605120 10816851968 1531482368 15.750 100 82 164 0 1567285584 9777408 26025808
20:13:17 10068 8192000000 10574155456 10933194752 1632772320 16.618 100 82 164 0 1668567760 11833280 23962160
20:14:31 8312 8192000000 10635044592 11003879424 1693166864 17.128 100 80 161 1 1730102032 13113152 23822016
20:15:45 6222 8192000000 10702829344 11078959104 1757162992 17.661 100 73 147 1 1790727968 12652208 20912768
20:16:52 3359 8192000000 10745537792 11130757120 1806707216 18.069 100 71 142 3 1839157536 12036960 20413360
20:17:56 2011 8192000000 10793884800 11184951296 1858055968 18.488 100 84 167 1 1893218320 11865616 23296736
20:19:00 27806 8192000000 10813303280 11210584064 1877155744 18.642 100 68 136 1 1904225952 7712880 19357328
20:20:29 5057 8192000000 10804517776 11222106112 1884099408 18.698 100 43 84 3 1905882960 10412416 11371136
20:21:37 9931 8192000000 10803606800 11233058816 1894785264 18.784 100 14 28 0 1904268720 5691600 3791856
20:22:47 9913 8192000000 10809706192 11243261952 1901535968 18.839 100 18 37 0 1913501920 6547360 5418592
20:24:03 19059 8192000000 10817646704 11254427648 1905046240 18.867 100 23 46 0 1917506992 6116432 6344320
20:25:23 6343 8192000000 10834904400 11269931008 1918989616 18.979 100 24 48 3 1931830496 5980000 6860880
20:26:34 8745 8192000000 10831468288 11263901696 1901384656 18.837 99 26 51 2 1915054736 6003712 7666368
20:27:45 20472 8192000000 10746445152 11163258880 1733335568 17.463 91 31 62 1 1747440048 4840256 9264224
20:29:07 13542 8192000000 10730388384 11150807040 1739547824 17.515 94 14 28 0 1748988864 5646784 3794256
20:30:21 6070 8192000000 10726027696 11138224128 1770936320 17.775 98 24 47 1 1781917888 4808320 6173248
20:31:30 6989 8192000000 10713591408 11131215872 1798246208 18.000 100 28 56 1 1812434960 5886896 8301856
20:32:37 33204 8192000000 10732939536 11156889600 1791576048 17.945 98 19 38 0 1802457200 5075344 5805808
20:34:33 6055 8192000000 10771177008 11197374464 1855072832 18.463 100 33 66 0 1870778544 5523568 10182144
20:35:42 9911 8192000000 10785338000 11216486400 1869049392 18.577 100 21 40 2 1880751104 5786320 5915392
20:36:56 10972 8192000000 10803986272 11237371904 1890464480 18.750 100 20 39 1 1901158048 4568048 6125520
20:38:09 58917 8192000000 10852328000 11281616896 1921576944 18.999 100 25 50 0 1934317872 5177328 7563600
20:40:14 9397 8192000000 10887295904 11313942528 1959387856 19.301 100 51 102 3 1985755600 11840128 14527616
20:41:35 15183 8192000000 10915838704 11343286272 1972078336 19.402 100 44 88 0 1998666064 13887184 12700544
20:42:54 13118 8192000000 10929733104 11357528064 1985075936 19.505 100 49 99 1 2016278096 17194080 14008080
20:44:12 15255 8192000000 10933332464 11371470848 1979150864 19.458 99 38 76 1 2011955312 21182480 11621968
20:45:39 9318 8192000000 10934963968 11372171264 1979747456 19.463 99 40 78 0 2012996800 20639664 12609680
20:46:55 6828 8192000000 10950722560 11387736064 1984580064 19.501 99 46 92 1 2027722544 29456880 13685600
20:48:06 4059 8192000000 10952571664 11380555776 1985414112 19.508 99 65 130 0 2041605520 37188464 19002944
20:49:15 3560 8192000000 10961472656 11370745856 1982650512 19.486 100 75 147 0 2036257184 32044848 21561824
20:50:23 3508 8192000000 10946744272 11361280000 1964869168 19.345 99 80 157 0 2026631296 38206448 23555680
20:51:30 4056 8192000000 10987161216 11405938688 2002165648 19.640 100 84 166 3 2071508816 44062752 25280416
20:52:40 5474 8192000000 10934941168 11355361280 1947718704 19.208 99 73 147 1 2014007888 44186624 22102560
20:53:57 2871 8192000000 10966649696 11385470976 1977249088 19.443 100 82 163 0 2049494912 47600880 24644944
20:55:05 7213 8192000000 10940268912 11373154304 1950974048 19.234 99 45 90 3 2013764784 47728160 15062576
20:56:24 1252 8192000000 10981095072 11387203584 1975703680 19.431 100 96 193 3 2058161376 53215440 29242256
20:57:29 6054 8192000000 10961071936 11400847360 1985276304 19.506 100 42 84 0 2048985184 49453040 14255840
20:58:44 4536 8192000000 10948602832 11375616000 1962562080 19.326 100 52 106 2 2030622320 51075856 16984384
20:59:53 5564 8192000000 10951681344 11375255552 1966173520 19.355 100 56 106 2 2037402528 53931216 17297792
21:01:03 12483 8192000000 10969009472 11407495168 1978119904 19.450 100 40 80 1 2052447744 60082656 14245184
21:02:20 9269 8192000000 10963534192 11399757824 1953044976 19.251 99 40 80 1 2035321648 67279888 14996784
21:03:33 10448 8192000000 10964630176 11406028800 1960386768 19.309 100 34 68 2 2045165856 71232352 13546736
21:04:44 34320 8192000000 10949924176 11401441280 1923976272 19.019 98 31 61 0 1993397104 58356928 11063904
21:07:21 90477 8192000000 10906544912 11379294208 1930593264 19.072 99 15 30 1 1976714640 41964160 4157216
21:09:52 102217 8192000000 10895934768 11359084544 1973337712 19.412 100 20 39 1 1986468304 7980272 5150320
21:12:38 16477 8192000000 10913373472 11366252544 1978756112 19.455 100 32 64 1 1999921296 12173280 8991904
21:14:00 87295 8192000000 10884136592 11352621056 1888853200 18.737 95 13 26 1 1906234288 13620176 3760912
21:16:29 47192 8192000000 10906126624 11364634624 1975090992 19.426 100 32 63 1 1996902176 13540432 8270752
21:18:29 103950 8192000000 10892201280 11354607616 1936732912 19.121 98 13 27 1 1950347248 9774560 3839776
21:21:31 49826 8192000000 10904115520 11369373696 1981485856 19.476 100 20 39 3 1996535728 9338672 5711200
21:23:23 103072 8192000000 10907660176 11377856512 1970268976 19.388 99 15 30 0 1989614960 14884976 4461008
21:26:48 139579 8192000000 10905293280 11385548800 1953746480 19.256 98 21 42 0 1976950032 17248304 5955248
21:30:13 31843 8192000000 10926391184 11368890368 1967471040 19.365 99 34 68 1 1986465504 8642592 10351872
21:31:47 43085 8192000000 10863537008 11333173248 1905456736 18.870 97 16 32 0 1917508496 7362080 4689680
21:33:30 106703 8192000000 10864457600 11331665920 1867218896 18.562 95 13 26 1 1881992832 11304832 3469104
21:36:17 63229 8192000000 10837375568 11310288896 1878465104 18.653 96 15 30 0 1902824048 19841152 4517792
21:39:36 41058 8192000000 10865327408 11331670016 1893383088 18.773 97 13 26 1 1902541248 6034016 3124144
21:41:22 55443 8192000000 10837160336 11308556288 1845410816 18.385 95 15 30 0 1861133616 11456304 4266496
21:43:18 57475 8192000000 10865118464 11347947520 1905333792 18.869 98 16 32 0 1934764672 24104448 5326432
21:45:16 30472 8192000000 10882079680 11331284992 1908440752 18.894 98 33 66 0 1928440864 10765680 9234432
21:46:47 86977 8192000000 10871675936 11344199680 1911645808 18.920 98 15 30 0 1926867696 11164576 4057312
21:49:18 85221 8192000000 10878867968 11352293376 1916547728 18.959 98 18 36 1 1932558224 11056608 4953888
21:51:44 12162 8192000000 10845556848 11280207872 1880450128 18.669 98 40 80 0 1904198416 12637120 11111168
21:53:06 40882 8192000000 10852922800 11307610112 1887490672 18.726 98 29 58 1 1905755072 10184336 8080064
21:54:50 98535 8192000000 10858642000 11326365696 1903123440 18.851 98 25 48 1 1921508080 11801184 6583456
21:57:47 39954 8192000000 10838086080 11309486080 1920496928 18.991 99 23 46 0 1935590256 7804128 7289200
21:59:32 64676 8192000000 10879249360 11352137728 1954369872 19.261 100 14 27 0 1965146064 7094496 3681696
22:01:47 80723 8192000000 10884266512 11365277696 1966795424 19.360 100 16 31 0 1983918672 12473552 4649696
22:04:24 54505 8192000000 10895117824 11371331584 1973278592 19.411 100 24 48 0 1989006528 8887456 6840480
22:06:19 73959 8192000000 10890009712 11359846400 1930853984 19.074 98 16 32 1 1946110720 10692144 4564592
22:08:33 46655 8192000000 10886759696 11368714240 1952329888 19.245 99 29 58 0 1976738496 16431104 7977504
22:10:23 73885 8192000000 10922018896 11398660096 1991179712 19.553 100 22 44 0 2010766592 13058832 6528048
22:12:58 198212 8192000000 10925151632 11404746752 1874757456 18.623 93 14 28 0 1899286112 20565168 3963488
22:17:16 141131 8192000000 10914291648 11389693952 1921507872 18.999 96 10 20 0 1941886528 17917568 2461088
22:20:37 92690 8192000000 10902998784 11390119936 1935379296 19.110 97 17 33 2 1954427184 13514000 5533888
22:23:10 60671 8192000000 10881126160 11363258368 1936730800 19.121 98 9 18 0 1951059232 11790720 2537712
22:25:11 59735 8192000000 10871443808 11354521600 1905515488 18.871 96 9 18 0 1915707424 7692976 2498960
22:27:22 66451 8192000000 10850866160 11332636672 1893958192 18.778 97 16 32 3 1905755488 7418560 4378736
22:29:29 63985 8192000000 10897936048 11358072832 1922066928 19.003 98 24 48 1 1940079424 10734048 7278448
22:31:39 52182 8192000000 10872145232 11342098432 1905397232 18.870 97 14 28 0 1916746192 7417888 3931072
22:33:42 84033 8192000000 10877392080 11350839296 1863983488 18.536 95 18 36 0 1879968000 11392160 4592352
22:36:07 25529 8192000000 10840213616 11295526912 1834812224 18.299 95 30 60 1 1854001824 10898096 8291504
22:37:33 31526 8192000000 9050429120 9267073024 216345504 2.572 12 2 4 0 218742288 2063664 333120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-09-2021 22:38:52 to 29-09-2021 22:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-09-2021 22:39:07 to 29-09-2021 22:39:09.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          246637
MON$OLDEST_ACTIVE               246638
MON$OLDEST_SNAPSHOT             246638
MON$NEXT_TRANSACTION            246638
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-03 16:41:55.3470
MON$PAGES                       2069216
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33504 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33504 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33504 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33504 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

29.09.21 22:39:10. There are 3 logs in /var/tmp/logs-oltp30 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-29 22:39:12 No severe PSQL-related problems occured
29.09.21 22:39:14. End of report.