Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.02.2021 10:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16409372 kB
MemAvailable 31159788 kB
Buffers 0 kB
Cached 15303736 kB
SwapCached 3892 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.0
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-02-21 10:26:57
DTS_END                         2021-02-21 10:26:57.3830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44374
TRN_ID                          228786
ATT_ID                          606
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: 8654.49 23111 1038539
customer order: creation 163.36 392 19603
customer order: refuse 14.24 363 1709
order to supplier: creation 43.42 1468 5210
order to supplier: removal 3.20 130370 384
invoice (draft): creation 42.62 3885 5114
invoice (draft): removal 49.31 1082 5917
invoice accept: apply 39.00 20332 4680
invoice accept: cancel 44.47 1054 5336
customer reserve: creation 3574.55 200 428946
customer reserve: removal 4409.84 75 529181
realization accept: apply 77.22 200 9267
realization accept: cancel 71.98 135 8638
payment from customer: creation 55.75 130 6690
payment from customer: removal 5.56 85 667
payment to supplier: creation 46.41 142 5569
payment to supplier: removal 3.31 105 397
service: total inventory turnovers 3.33 4011 400
service: total monetary turnovers 6.03 704 723
service: aggregate perf. data 0.86 17007 103
service: refresh index statistics 0.04 280486 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-02-2021 10:29:08 to 21-02-2021 10:29:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2308
TEST_TIME 2 3760
TEST_TIME 3 3809
TEST_TIME 4 4789
TEST_TIME 5 4794
TEST_TIME 6 5344
TEST_TIME 7 5375
TEST_TIME 8 5384
TEST_TIME 9 5716
TEST_TIME 10 5657
TEST_TIME 11 5696
TEST_TIME 12 5781
TEST_TIME 13 6139
TEST_TIME 14 6222
TEST_TIME 15 6480
TEST_TIME 16 6609
TEST_TIME 17 6870
TEST_TIME 18 7142
TEST_TIME 19 7303
TEST_TIME 20 7365
TEST_TIME 21 7379
TEST_TIME 22 8327
TEST_TIME 23 8304
TEST_TIME 24 8448
TEST_TIME 25 8593
TEST_TIME 26 8502
TEST_TIME 27 8812
TEST_TIME 28 8880
TEST_TIME 29 8810
TEST_TIME 30 8921
TEST_TIME 31 9158
TEST_TIME 32 9620
TEST_TIME 33 10707
TEST_TIME 34 10534
TEST_TIME 35 10484
TEST_TIME 36 10316
TEST_TIME 37 10068
TEST_TIME 38 9991
TEST_TIME 39 9907
TEST_TIME 40 9821
TEST_TIME 41 9713
TEST_TIME 42 9680
TEST_TIME 43 9565
TEST_TIME 44 9484
TEST_TIME 45 9431
TEST_TIME 46 9503
TEST_TIME 47 9399
TEST_TIME 48 9311
TEST_TIME 49 9277
TEST_TIME 50 9266
TEST_TIME 51 9248
TEST_TIME 52 9379
TEST_TIME 53 9352
TEST_TIME 54 9329
TEST_TIME 55 9333
TEST_TIME 56 9325
TEST_TIME 57 9292
TEST_TIME 58 9252
TEST_TIME 59 9216
TEST_TIME 60 9235
TEST_TIME 61 9323
TEST_TIME 62 9332
TEST_TIME 63 9285
TEST_TIME 64 9309
TEST_TIME 65 9275
TEST_TIME 66 9147
TEST_TIME 67 9065
TEST_TIME 68 9021
TEST_TIME 69 8956
TEST_TIME 70 8944
TEST_TIME 71 8955
TEST_TIME 72 8903
TEST_TIME 73 8987
TEST_TIME 74 8962
TEST_TIME 75 8999
TEST_TIME 76 8973
TEST_TIME 77 8968
TEST_TIME 78 9106
TEST_TIME 79 9075
TEST_TIME 80 9070
TEST_TIME 81 9072
TEST_TIME 82 9062
TEST_TIME 83 8989
TEST_TIME 84 8945
TEST_TIME 85 8935
TEST_TIME 86 8853
TEST_TIME 87 8809
TEST_TIME 88 8788
TEST_TIME 89 8774
TEST_TIME 90 8885
TEST_TIME 91 8856
TEST_TIME 92 8835
TEST_TIME 93 8820
TEST_TIME 94 8849
TEST_TIME 95 8829
TEST_TIME 96 8902
TEST_TIME 97 8888
TEST_TIME 98 8889
TEST_TIME 99 8904
TEST_TIME 100 8895
TEST_TIME 101 8835
TEST_TIME 102 8789
TEST_TIME 103 8743
TEST_TIME 104 8718
TEST_TIME 105 8675
TEST_TIME 106 8653
TEST_TIME 107 8660
TEST_TIME 108 8699
TEST_TIME 109 8703
TEST_TIME 110 8694
TEST_TIME 111 8686
TEST_TIME 112 8744
TEST_TIME 113 8719
TEST_TIME 114 8721
TEST_TIME 115 8745
TEST_TIME 116 8780
TEST_TIME 117 8805
TEST_TIME 118 8740
TEST_TIME 119 8689
TEST_TIME 120 8655

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-02-2021 10:29:11 to 21-02-2021 10:29:15.

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 1435787 1435787 0 0.00 0 16824 8 0 0
doc_list_biud 1435762 1435762 0 0.00 0 76230 35 0 0
sp_add_invoice_to_stock 4869 4680 189 3.88 28 162839 20332 0 189
sp_cancel_adding_invoice 5336 5336 0 0.00 5 128065 1054 0 0
sp_cancel_client_order 1709 1709 0 0.00 5 11624 363 0 0
sp_cancel_customer_reserve 529181 529181 0 0.00 1 76232 75 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 3709 86 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 9483 106 0 0
sp_cancel_supplier_invoice 5931 5917 14 0.23 2 18285 1082 0 14
sp_cancel_supplier_order 384 384 0 0.00 7 1057609 130371 0 0
sp_cancel_write_off 8638 8638 0 0.00 1 11939 136 0 0
sp_client_order 19603 19603 0 0.00 4 29231 393 0 0
sp_customer_reserve 428946 428946 0 0.00 0 33921 200 0 0
sp_fill_shopping_cart 29924 29924 0 0.00 1 15393 47 0 0
sp_fill_shopping_cart_clo_res 428946 428946 0 0.00 0 13698 17 0 0
sp_get_clo_for_invoice 4679 4679 0 0.00 0 43109 468 0 0
sp_kill_cost_storno 15038 15038 0 0.00 0 9669 69 0 0
sp_kill_qstorno_ret_qs2qd 1073301 1073301 0 0.00 0 76202 41 0 0
sp_kill_qty_storno 565099 565099 0 0.00 0 76229 88 0 0
sp_lock_selected_doc 19461 19461 0 0.00 0 9475 5 0 0
sp_make_cost_storno 26205 26205 0 0.00 0 14485 66 0 0
sp_multiply_rows_for_pdistr 26186 26186 0 0.00 0 9533 35 0 0
sp_multiply_rows_for_qdistr 19603 19603 0 0.00 0 29223 212 0 0
sp_pay_from_customer 6690 6690 0 0.00 4 13942 130 0 0
sp_pay_to_supplier 5569 5569 0 0.00 5 13174 142 0 0
sp_payment_common 12246 12246 0 0.00 1 13131 21 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 11614 334 0 0
sp_qd_handle_on_invoice_upd_sts 10010 10010 0 0.00 0 37354 429 0 0
sp_qd_handle_on_reserve_upd_sts 9260 9260 0 0.00 0 17520 60 0 0
sp_reserve_write_off 9267 9267 0 0.00 7 17582 201 0 0
sp_supplier_invoice 5114 5114 0 0.00 8 96099 3886 0 0
sp_supplier_order 5210 5210 0 0.00 5 92172 1469 0 0
srv_aggregate_perf_data 103 103 0 0.00 3124 112346 17008 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 972 129569 41095 0 0
srv_find_qd_qs_mism 993777 993777 0 0.00 0 16824 7 0 0
srv_make_invnt_saldo 400 400 0 0.00 58 100478 4011 0 0
srv_make_money_saldo 723 723 0 0.00 1 16094 704 0 0
srv_recalc_idx_stat 5 5 0 0.00 129772 490773 280486 0 0
t$perf-abend:sp_add_invoice_to_stock 189 189 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4680 4680 0 0.00 0 29910 195 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 4548 123 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 3897 16 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 2481 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 554 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 402 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 5013 30 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 38823 1691 0 0
t$perf-norm:sp_cancel_write_off 914 914 0 0.00 0 4149 19 0 0
t$perf-norm:sp_client_order 19603 19603 0 0.00 0 29645 21 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 1601 10 0 0
t$perf-norm:sp_pay_from_customer 6690 6690 0 0.00 0 6451 9 0 0
t$perf-norm:sp_pay_to_supplier 5569 5569 0 0.00 0 17261 14 0 0
t$perf-norm:sp_reserve_write_off 9267 9267 0 0.00 0 9069 11 0 0
t$perf-norm:sp_supplier_invoice 5114 5114 0 0.00 0 5219 18 0 0
t$perf-norm:sp_supplier_order 5210 5210 0 0.00 0 23019 23 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 5799 109 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 630 11 0 0
t$perf-norm:srv_make_invnt_saldo 400 400 0 0.00 0 6249 23 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 1129 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 6 4 0 0
v_add_invoice_to_stock 9722 9344 378 3.88 0 15423 24 0 378
v_all_customers 35426 35426 0 0.00 0 16 1 0 0
v_all_suppliers 28344 28344 0 0.00 0 18 1 0 0
v_all_wares 39204 39204 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 89 5 0 0
v_cancel_client_order 3418 3418 0 0.00 0 5237 8 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 3703 10 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 535 4 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1766 7 0 0
v_cancel_supplier_invoice 808 780 28 3.46 0 244 14 0 28
v_cancel_supplier_order 768 768 0 0.00 0 438 5 0 0
v_cancel_write_off 1828 1828 0 0.00 0 964 14 0 0
v_max_id_clo_ord 5210 5210 0 0.00 0 2020 2 0 0
v_max_id_clo_res 992 992 0 0.00 0 1714 12 0 0
v_max_id_ord_sup 5111 5111 0 0.00 0 2488 3 0 0
v_max_non_paid_invoice 1711 1711 0 0.00 0 896 3 0 0
v_max_non_paid_realizn 3723 3723 0 0.00 0 1629 3 0 0
v_min_id_clo_ord 5210 5210 0 0.00 0 1014 5 0 0
v_min_id_clo_res 992 992 0 0.00 0 8266 16 0 0
v_min_id_ord_sup 5111 5111 0 0.00 0 7997 9 0 0
v_min_non_paid_invoice 5562 5562 0 0.00 0 6475 12 0 0
v_min_non_paid_realizn 6682 6682 0 0.00 0 9652 11 0 0
v_our_firm 9696 9696 0 0.00 0 20 1 0 0
v_reserve_write_off 18518 18518 0 0.00 0 5526 6 0 0
x_lock_depdocs_on_canc_invoice 10863 10863 0 0.00 0 12904 139 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 10888 345 0 0
x_make_qstorno_1000_1200 5210 5210 0 0.00 2 83088 1382 0 0
x_make_qstorno_1200_2000 5114 5114 0 0.00 3 96052 3704 0 0
x_make_qstorno_2100_3300 428946 428946 0 0.00 0 33918 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-02-2021 10:29:16 to 21-02-2021 10:29:20.

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
07:57:00 5 8192000000 8801205712 8813338624 6550576 0.079 3 1 2 0 6651776 65424 35776
07:58:01 15835 8192000000 9718305456 10013548544 796443408 8.860 100 91 182 0 826700672 7853600 22403664
07:59:21 24769 8192000000 9769741536 10073595904 839345664 9.293 100 87 174 0 871444336 11411200 20687472
08:00:51 14274 8192000000 9787774496 10103549952 862621360 9.526 100 81 162 0 898733328 17623024 18488944
08:02:15 13145 8192000000 9861205136 10189242368 908913232 9.987 100 84 168 0 971381632 44438144 18030256
08:03:31 5866 8192000000 9915083392 10253901824 949924304 10.390 100 82 165 2 1028911104 61597040 17389760
08:04:39 8045 8192000000 9962667712 10309623808 982472512 10.708 100 80 160 1 1070736320 72133904 16129904
08:05:50 2721 8192000000 9978378272 10339463168 1014758800 11.021 100 87 174 2 1100611360 68275440 17577120
08:06:55 4947 8192000000 10012143216 10374483968 1040754384 11.272 100 77 152 2 1119680624 64265904 14660336
08:08:02 1203 8192000000 10040225952 10408439808 1062882496 11.484 100 93 185 3 1150439744 68849088 18708160
08:09:04 12560 8192000000 9972552752 10361888768 1063175696 11.487 100 24 48 0 1092210432 23965776 5068960
08:10:18 5344 8192000000 9987086016 10378227712 1069985008 11.552 100 30 58 2 1111149536 34961440 6203088
08:11:24 5640 8192000000 9986838800 10384150528 1085594816 11.701 100 40 80 2 1106822000 11962576 9264608
08:12:33 63835 8192000000 10045352720 10444173312 1093713696 11.778 100 48 96 2 1128619600 24861968 10043936
08:14:38 5598 8192000000 10019320752 10413051904 1097740848 11.816 100 42 80 3 1149696432 43768848 8186736
08:15:45 9337 8192000000 10012201616 10415267840 1101558608 11.852 100 37 75 1 1135039152 25336112 8144432
08:16:55 8815 8192000000 10018937808 10421026816 1074168656 11.592 99 34 68 0 1128555680 47322688 7064336
08:18:12 33775 8192000000 9967459328 10373210112 977037264 10.655 90 21 41 1 999399200 18023120 4338816
08:19:46 8458 8192000000 9956360000 10338729984 1033237040 11.200 99 39 77 0 1058804960 17286752 8281168
08:20:54 12353 8192000000 9941502384 10338430976 1013852960 11.013 98 30 60 0 1044669840 23991424 6825456
08:22:08 61017 8192000000 9935229872 10329595904 1011784288 10.993 98 23 46 0 1039446176 23112112 4549776
08:24:12 8228 8192000000 9982501120 10371780608 1050440432 11.365 100 36 74 1 1094337248 34885216 9011600
08:25:21 9690 8192000000 9989703632 10392076288 1061491712 11.471 100 30 60 0 1090736000 22397728 6846560
08:26:34 8547 8192000000 9990016528 10396606464 1068576064 11.538 100 31 63 0 1104683632 29719104 6388464
08:27:45 34503 8192000000 9999509728 10404958208 1082433024 11.671 100 34 65 2 1117570256 27838880 7298352
08:29:39 16475 8192000000 10078368832 10483785728 1105949024 11.894 100 54 108 1 1182488928 65156384 11383520
08:31:00 17330 8192000000 10083985712 10502832128 1114009872 11.970 100 51 102 0 1189264448 64614080 10640496
08:32:22 970 8192000000 10097899520 10505056256 1125757360 12.081 100 71 139 3 1202926672 62485424 14683888
08:33:25 13072 8192000000 10160220160 10566131712 1140220480 12.218 100 51 102 3 1261452816 110362432 10869904
08:34:46 3888 8192000000 10178088912 10590040064 1146421904 12.276 100 57 114 0 1282758528 123965440 12371184
08:36:03 13895 8192000000 10170337328 10592321536 1151888224 12.327 100 43 85 1 1280873168 119857792 9127152
08:37:22 7515 8192000000 10227289232 10644787200 1164217744 12.443 100 58 116 2 1336192784 158910592 13064448
08:38:37 4845 8192000000 10271510784 10686193664 1170215712 12.499 100 74 143 3 1374645056 188294448 16134896
08:39:47 1122 8192000000 10237343456 10640338944 1145042192 12.263 99 74 147 0 1339862848 178724352 16096304
08:40:53 4556 8192000000 10239949776 10665545728 1147637536 12.287 100 60 120 0 1345748496 184500000 13610960
08:42:02 2705 8192000000 10283035776 10685661184 1135675264 12.175 99 77 154 2 1381249040 228155760 17418016
08:43:10 2208 8192000000 10324902224 10733223936 1153558224 12.343 99 71 140 2 1430360448 261290096 15512128
08:44:17 1310 8192000000 10312963328 10716295168 1151216272 12.321 100 90 181 0 1414705776 242808976 20680528
08:45:24 3522 8192000000 10325996320 10724134912 1141756912 12.232 99 83 162 1 1415760880 256177920 17826048
08:46:32 5161 8192000000 10344808960 10760011776 1145711312 12.269 100 77 151 1 1454033760 290344544 17977904
08:47:47 3507 8192000000 10392822240 10794160128 1151122560 12.320 99 82 165 1 1476412832 306145776 19144496
08:48:53 10242 8192000000 10301009536 10715238400 1143266992 12.246 99 55 109 1 1408390144 252256288 12866864
08:50:05 10323 8192000000 10319537152 10757660672 1144899744 12.262 100 35 69 0 1429085216 275303120 8882352
08:51:22 7002 8192000000 10342667056 10760409088 1157255248 12.378 100 68 136 1 1446819824 272901792 16662784
08:52:32 9060 8192000000 10361730528 10778136576 1153621776 12.343 100 57 111 0 1457825120 290414240 13789104
08:53:43 15176 8192000000 10371542720 10802307072 1168045120 12.479 100 34 67 2 1474621376 297556272 9019984
08:55:00 22509 8192000000 10400527968 10849308672 1173545056 12.530 100 34 68 1 1512718432 330795920 8377456
08:56:46 12438 8192000000 10435820368 10915360768 1150813648 12.317 100 31 63 0 1526795616 367424880 8557088
08:58:50 18950 8192000000 10306987776 10767216640 1130809152 12.129 100 20 40 1 1413987680 277754704 5423824
09:00:15 34317 8192000000 10082758832 10497802240 1123797728 12.063 99 52 101 2 1156878080 22421056 10659296
09:01:50 114602 8192000000 10098759120 10554060800 1143669936 12.250 100 24 48 0 1194094752 45487872 4936944
09:04:47 19396 8192000000 10038413408 10476744704 1090062336 11.743 96 31 62 0 1132701312 36941360 5697616
09:07:09 72341 8192000000 10105586288 10573705216 1130653120 12.128 99 22 45 0 1219010128 83712400 4644608
09:09:49 41678 8192000000 10164242304 10614243328 1151573312 12.324 100 24 47 2 1258334112 101581056 5179744
09:11:34 36438 8192000000 10162160608 10611101696 1130771440 12.129 98 24 48 0 1241529888 105057776 5700672
09:13:18 29290 8192000000 10167971792 10635177984 1149055936 12.301 100 15 30 1 1264820512 112132672 3631904
09:15:00 27506 8192000000 10141610752 10651090944 1154403872 12.351 100 13 26 1 1275369600 117984608 2981120
09:16:29 36562 8192000000 10160203408 10650447872 1160274400 12.406 100 9 18 0 1266473856 104060128 2139328
09:18:09 60751 8192000000 10084798960 10578976768 1096452720 11.804 95 10 20 1 1148315296 49877808 1984768
09:20:16 23175 8192000000 10079903632 10512494592 1144669456 12.259 100 35 69 1 1174282448 22727936 6885056
09:21:44 46227 8192000000 10088417072 10546868224 1118851520 12.016 98 21 42 0 1174123312 51253472 4018320
09:23:30 41172 8192000000 10069150944 10527739904 1089454384 11.737 96 35 68 0 1138888352 42721584 6712384
09:25:12 49741 8192000000 10125798032 10571759616 1128050496 12.103 99 41 82 1 1224658400 88157008 8450896
09:27:09 81896 8192000000 10073260752 10541535232 1106967376 11.904 97 11 22 0 1161486624 52179664 2339584
09:29:31 74811 8192000000 10078184640 10560901120 1100634768 11.844 97 14 28 1 1141030496 37427552 2968176
09:34:30 87851 8192000000 10081460928 10568355840 1128620512 12.108 100 9 18 0 1177202032 46713824 1867696
09:37:01 74510 8192000000 10106443760 10582429696 1101860048 11.855 97 8 16 0 1167872960 64418176 1594736
09:39:15 51480 8192000000 10063241680 10488791040 1099782528 11.836 98 19 38 0 1163293632 59101568 4409536
09:41:11 48885 8192000000 10076018112 10539192320 1101962368 11.856 98 21 42 0 1164569168 57747184 4859616
09:43:07 22062 8192000000 10097107696 10519871488 1109580208 11.928 98 35 68 0 1178006720 60982352 7444160
09:44:31 86972 8192000000 10064489328 10545057792 1072669488 11.578 96 10 20 0 1120596688 46191040 1736160
09:47:13 51450 8192000000 10094409168 10537922560 1116537968 11.994 99 25 50 1 1182078640 60805856 4734816
09:49:05 129568 8192000000 10052325664 10542456832 1115728480 11.987 99 17 33 1 1202751360 82655216 4367664
09:52:15 98688 8192000000 10086216912 10545176576 1123749024 12.062 99 10 21 1 1177514144 51796928 1968192
09:54:54 95100 8192000000 10081791248 10575052800 1109059664 11.924 98 17 35 0 1201763680 88741264 3962752
09:57:34 47004 8192000000 10068725632 10500009984 1121726192 12.043 99 24 48 1 1160531952 33727664 5078096
09:59:21 108859 8192000000 10062384080 10493726720 1066705472 11.521 94 19 39 1 1099458608 29416512 3336624
10:02:16 102636 8192000000 10085937936 10540064768 1135552288 12.174 100 12 24 0 1203615952 65903664 2160000
10:05:16 23391 8192000000 10111039728 10542481408 1141024480 12.225 100 25 50 2 1190315280 44270672 5020128
10:06:42 34586 8192000000 10106301376 10544783360 1144477456 12.258 100 37 75 1 1183518176 31325248 7715472
10:08:18 70296 8192000000 10078352160 10539913216 1142270384 12.237 99 13 26 0 1178677888 33986080 2421424
10:11:44 54551 8192000000 10114236096 10604388352 1153437520 12.342 100 7 15 0 1201703744 47028416 1237808
10:13:42 78486 8192000000 10108646336 10595667968 1117768896 12.006 97 20 40 0 1217731152 95525696 4436560
10:16:01 80123 8192000000 10123007024 10573258752 1128402272 12.106 98 19 38 1 1205418992 72976448 4040272
10:18:22 61652 8192000000 10074792752 10508742656 1118493344 12.013 98 16 32 1 1154142688 32350592 3298752
10:20:24 40051 8192000000 10110234160 10542657536 1119650784 12.024 98 30 60 0 1172648912 47282624 5715504
10:22:04 49291 8192000000 10117827344 10601754624 1104964128 11.885 97 17 34 0 1185055600 76566912 3524560
10:24:06 100281 8192000000 10051283040 10497871872 1115340736 11.983 99 21 42 1 1141743184 22082496 4319952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-02-2021 10:29:22 to 21-02-2021 10:29:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 189 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

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          228280
MON$OLDEST_ACTIVE               228903
MON$OLDEST_SNAPSHOT             228903
MON$NEXT_TRANSACTION            228903
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                       2110208
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:19: 
:20: mscs-test-161.budzdorov.ru    Sun Feb 21 10:27:22 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sun Feb 21 10:27:22 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Sun Feb 21 10:27:22 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

21.02.21 10:29:41. 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-02-21 10:29:43 No severe PSQL-related problems occured
21.02.21 10:29:45. End of report.