Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16877640 kB
MemAvailable 31546280 kB
Buffers 0 kB
Cached 14752272 kB
SwapCached 5444 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 2020-10-16 10:26:50
DTS_END                         2020-10-16 10:26:50.4520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/50972
TRN_ID                          229420
ATT_ID                          632
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: 8869.95 29891 1064394
customer order: creation 154.83 381 18580
customer order: refuse 13.70 315 1644
order to supplier: creation 41.57 1394 4988
order to supplier: removal 3.13 132321 375
invoice (draft): creation 43.58 3627 5230
invoice (draft): removal 51.38 936 6166
invoice accept: apply 38.26 18857 4591
invoice accept: cancel 46.09 921 5531
customer reserve: creation 3664.01 180 439681
customer reserve: removal 4532.08 73 543849
realization accept: apply 76.16 181 9139
realization accept: cancel 87.66 114 10519
payment from customer: creation 54.81 123 6577
payment from customer: removal 5.09 107 611
payment to supplier: creation 44.43 124 5331
payment to supplier: removal 3.05 112 366
service: total inventory turnovers 3.38 3879 405
service: total monetary turnovers 5.87 535 704
service: aggregate perf. data 0.86 17683 103
service: refresh index statistics 0.03 415950 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2020 10:27:46 to 16-10-2020 10:27:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3009
TEST_TIME 2 2848
TEST_TIME 3 3540
TEST_TIME 4 4252
TEST_TIME 5 4562
TEST_TIME 6 4694
TEST_TIME 7 5246
TEST_TIME 8 5157
TEST_TIME 9 5123
TEST_TIME 10 5475
TEST_TIME 11 5540
TEST_TIME 12 5588
TEST_TIME 13 5717
TEST_TIME 14 5798
TEST_TIME 15 5897
TEST_TIME 16 6249
TEST_TIME 17 6773
TEST_TIME 18 7948
TEST_TIME 19 8017
TEST_TIME 20 8300
TEST_TIME 21 8664
TEST_TIME 22 8679
TEST_TIME 23 9701
TEST_TIME 24 9869
TEST_TIME 25 9972
TEST_TIME 26 11055
TEST_TIME 27 10858
TEST_TIME 28 10879
TEST_TIME 29 10775
TEST_TIME 30 10525
TEST_TIME 31 10463
TEST_TIME 32 10347
TEST_TIME 33 10160
TEST_TIME 34 10126
TEST_TIME 35 9978
TEST_TIME 36 9868
TEST_TIME 37 9842
TEST_TIME 38 9900
TEST_TIME 39 9740
TEST_TIME 40 9659
TEST_TIME 41 9515
TEST_TIME 42 9642
TEST_TIME 43 9820
TEST_TIME 44 9721
TEST_TIME 45 9718
TEST_TIME 46 9786
TEST_TIME 47 9821
TEST_TIME 48 9790
TEST_TIME 49 9817
TEST_TIME 50 9831
TEST_TIME 51 9720
TEST_TIME 52 9717
TEST_TIME 53 9952
TEST_TIME 54 9945
TEST_TIME 55 9882
TEST_TIME 56 9726
TEST_TIME 57 9583
TEST_TIME 58 9482
TEST_TIME 59 9359
TEST_TIME 60 9329
TEST_TIME 61 9339
TEST_TIME 62 9304
TEST_TIME 63 9350
TEST_TIME 64 9279
TEST_TIME 65 9283
TEST_TIME 66 9240
TEST_TIME 67 9434
TEST_TIME 68 9371
TEST_TIME 69 9410
TEST_TIME 70 9394
TEST_TIME 71 9395
TEST_TIME 72 9405
TEST_TIME 73 9430
TEST_TIME 74 9426
TEST_TIME 75 9492
TEST_TIME 76 9389
TEST_TIME 77 9299
TEST_TIME 78 9272
TEST_TIME 79 9181
TEST_TIME 80 9149
TEST_TIME 81 9117
TEST_TIME 82 9140
TEST_TIME 83 9166
TEST_TIME 84 9108
TEST_TIME 85 9098
TEST_TIME 86 9156
TEST_TIME 87 9179
TEST_TIME 88 9178
TEST_TIME 89 9166
TEST_TIME 90 9158
TEST_TIME 91 9134
TEST_TIME 92 9198
TEST_TIME 93 9250
TEST_TIME 94 9254
TEST_TIME 95 9172
TEST_TIME 96 9108
TEST_TIME 97 9053
TEST_TIME 98 9053
TEST_TIME 99 9100
TEST_TIME 100 9076
TEST_TIME 101 9048
TEST_TIME 102 9058
TEST_TIME 103 9048
TEST_TIME 104 9013
TEST_TIME 105 9029
TEST_TIME 106 9046
TEST_TIME 107 9076
TEST_TIME 108 9079
TEST_TIME 109 9034
TEST_TIME 110 9101
TEST_TIME 111 9025
TEST_TIME 112 8960
TEST_TIME 113 8928
TEST_TIME 114 8873
TEST_TIME 115 8887
TEST_TIME 116 8859
TEST_TIME 117 8836
TEST_TIME 118 8844
TEST_TIME 119 8870
TEST_TIME 120 8871

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2020 10:27:49 to 16-10-2020 10:27:53.

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 1471493 1471493 0 0.00 0 22942 6 0 0
doc_list_biud 1471472 1471472 0 0.00 0 89173 33 0 0
sp_add_invoice_to_stock 4685 4591 94 2.00 23 199097 18857 0 94
sp_cancel_adding_invoice 5531 5531 0 0.00 5 122052 922 0 0
sp_cancel_client_order 1644 1644 0 0.00 5 17210 315 0 0
sp_cancel_customer_reserve 543849 543849 0 0.00 1 89175 73 0 0
sp_cancel_pay_from_customer 611 611 0 0.00 3 9310 108 0 0
sp_cancel_pay_to_supplier 366 366 0 0.00 2 8860 112 0 0
sp_cancel_supplier_invoice 6181 6166 15 0.24 2 21347 936 0 15
sp_cancel_supplier_order 375 375 0 0.00 11 1192250 132321 0 0
sp_cancel_write_off 10519 10519 0 0.00 1 10713 114 0 0
sp_client_order 18580 18580 0 0.00 4 14987 382 0 0
sp_customer_reserve 439681 439681 0 0.00 0 88589 180 0 0
sp_fill_shopping_cart 28768 28768 0 0.00 1 49823 49 0 0
sp_fill_shopping_cart_clo_res 439674 439674 0 0.00 0 20547 18 0 0
sp_get_clo_for_invoice 4584 4584 0 0.00 0 37104 367 0 0
sp_kill_cost_storno 17027 17027 0 0.00 0 10709 56 0 0
sp_kill_qstorno_ret_qs2qd 1104758 1104758 0 0.00 0 89164 39 0 0
sp_kill_qty_storno 581807 581807 0 0.00 0 89173 83 0 0
sp_lock_selected_doc 19035 19035 0 0.00 0 8851 5 0 0
sp_make_cost_storno 25631 25631 0 0.00 0 13505 51 0 0
sp_multiply_rows_for_pdistr 25631 25631 0 0.00 0 9922 30 0 0
sp_multiply_rows_for_qdistr 18580 18580 0 0.00 0 12086 197 0 0
sp_pay_from_customer 6577 6577 0 0.00 4 14163 123 0 0
sp_pay_to_supplier 5331 5331 0 0.00 4 15766 125 0 0
sp_payment_common 11901 11901 0 0.00 1 7094 19 0 0
sp_qd_handle_on_cancel_clo 1644 1644 0 0.00 0 17203 283 0 0
sp_qd_handle_on_invoice_upd_sts 10115 10115 0 0.00 0 34652 376 0 0
sp_qd_handle_on_reserve_upd_sts 9139 9139 0 0.00 0 23598 55 0 0
sp_reserve_write_off 9139 9139 0 0.00 7 24015 182 0 0
sp_supplier_invoice 5230 5230 0 0.00 6 105528 3628 0 0
sp_supplier_order 4988 4988 0 0.00 6 77277 1395 0 0
srv_aggregate_perf_data 103 103 0 0.00 2882 224548 17683 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1338 129043 48370 0 0
srv_find_qd_qs_mism 1020213 1020213 0 0.00 0 22941 5 0 0
srv_make_invnt_saldo 405 405 0 0.00 62 98471 3880 0 0
srv_make_money_saldo 704 704 0 0.00 1 11872 536 0 0
srv_recalc_idx_stat 4 4 0 0.00 242185 527100 415951 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4591 4591 0 0.00 0 12984 143 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 2390 89 0 0
t$perf-norm:sp_cancel_client_order 1644 1644 0 0.00 0 2989 12 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1591 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 611 611 0 0.00 0 6914 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 366 366 0 0.00 0 619 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 4495 30 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 47383 1671 0 0
t$perf-norm:sp_cancel_write_off 876 876 0 0.00 0 2976 10 0 0
t$perf-norm:sp_client_order 18580 18580 0 0.00 0 12438 17 0 0
t$perf-norm:sp_customer_reserve 896 896 0 0.00 0 2429 7 0 0
t$perf-norm:sp_pay_from_customer 6577 6577 0 0.00 0 3477 8 0 0
t$perf-norm:sp_pay_to_supplier 5331 5331 0 0.00 0 6736 8 0 0
t$perf-norm:sp_reserve_write_off 9139 9139 0 0.00 0 7356 10 0 0
t$perf-norm:sp_supplier_invoice 5230 5230 0 0.00 0 7107 17 0 0
t$perf-norm:sp_supplier_order 4988 4988 0 0.00 0 5233 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 159 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 429 9 0 0
t$perf-norm:srv_make_invnt_saldo 405 405 0 0.00 0 1578 13 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 4262 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 73 21 0 0
v_add_invoice_to_stock 9351 9163 188 2.01 0 5493 18 0 188
v_all_customers 34192 34192 0 0.00 0 16 1 0 0
v_all_suppliers 28272 28272 0 0.00 0 18 1 0 0
v_all_wares 37148 37148 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 2943 20 0 0
v_cancel_client_order 3288 3288 0 0.00 0 2735 7 0 0
v_cancel_customer_prepayment 1222 1222 0 0.00 0 2274 10 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 5562 9 0 0
v_cancel_payment_to_supplier 732 732 0 0.00 0 2924 12 0 0
v_cancel_supplier_invoice 842 812 30 3.56 0 5523 26 0 30
v_cancel_supplier_order 750 750 0 0.00 0 4757 10 0 0
v_cancel_write_off 1752 1752 0 0.00 0 7618 24 0 0
v_max_id_clo_ord 4985 4985 0 0.00 0 4822 3 0 0
v_max_id_clo_res 896 896 0 0.00 0 1729 13 0 0
v_max_id_ord_sup 5209 5209 0 0.00 0 2262 4 0 0
v_max_non_paid_invoice 1384 1384 0 0.00 0 405 2 0 0
v_max_non_paid_realizn 3418 3418 0 0.00 0 996 3 0 0
v_min_id_clo_ord 4985 4985 0 0.00 0 6173 9 0 0
v_min_id_clo_res 896 896 0 0.00 0 6907 14 0 0
v_min_id_ord_sup 5209 5209 0 0.00 0 7147 8 0 0
v_min_non_paid_invoice 5326 5326 0 0.00 0 3439 10 0 0
v_min_non_paid_realizn 6574 6574 0 0.00 0 6607 12 0 0
v_our_firm 9268 9268 0 0.00 0 16 1 0 0
v_reserve_write_off 18278 18278 0 0.00 0 7672 7 0 0
x_lock_depdocs_on_canc_invoice 11291 11291 0 0.00 0 20214 109 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 11649 376 0 0
x_make_qstorno_1000_1200 4988 4988 0 0.00 2 77262 1313 0 0
x_make_qstorno_1200_2000 5230 5230 0 0.00 2 105330 3435 0 0
x_make_qstorno_2100_3300 439681 439681 0 0.00 0 88587 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2020 10:27:54 to 16-10-2020 10:27:58.

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:56:54 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:57:55 33149 8192000000 9709583456 10005573632 789248592 8.787 100 88 176 0 818259872 7412032 21599248
07:59:37 9697 8192000000 9745578160 10049650688 821832480 9.117 100 84 168 0 855156208 13181424 20142304
08:00:56 13650 8192000000 9792517040 10106531840 854579728 9.446 100 80 160 0 899219952 26190624 18449600
08:02:16 5139 8192000000 9856114000 10186801152 911955312 10.017 100 82 164 0 971768992 41453024 18360656
08:03:24 8189 8192000000 9916696400 10258644992 952733360 10.418 100 69 134 2 1023613920 57049280 13831280
08:04:34 4068 8192000000 9971536832 10327044096 991787600 10.799 100 80 159 3 1077897248 70492704 15616944
08:05:39 2128 8192000000 9990461792 10353287168 1027040096 11.140 100 87 172 0 1105429984 61483648 16906240
08:06:42 1331 8192000000 10015866816 10386784256 1052231936 11.382 100 84 167 0 1129680448 60480400 16968112
08:07:44 23922 8192000000 9968158752 10358513664 1059170800 11.449 100 69 139 1 1093598096 19303376 15123920
08:09:14 5901 8192000000 9990443712 10384207872 1069725728 11.549 100 44 88 0 1110336000 30857456 9752816
08:10:25 12270 8192000000 9983413504 10384859136 1072570896 11.577 100 20 41 1 1103167840 26242112 4354832
08:11:37 59939 8192000000 10031101648 10441961472 1077324272 11.622 100 43 86 1 1114854160 28151568 9378320
08:13:41 6934 8192000000 10019835152 10407124992 1089821024 11.741 100 39 80 2 1120885920 23518096 7546800
08:14:48 10564 8192000000 10001816560 10415374336 1085557728 11.700 99 44 88 2 1120239616 25164000 9517888
08:16:00 12020 8192000000 9997100736 10407182336 1072980416 11.581 99 22 43 1 1111981936 34302832 4698688
08:17:12 44033 8192000000 9952662816 10355761152 998688592 10.866 92 26 53 2 1026485840 21772672 6024576
08:18:59 8613 8192000000 9944645392 10326728704 992707696 10.808 96 41 80 2 1027363472 25396640 9259136
08:20:11 5833 8192000000 9957778768 10335408128 1020036672 11.072 99 45 90 1 1049033664 18416336 10580656
08:21:21 10265 8192000000 9949781728 10335985664 1012612528 11.001 98 37 74 2 1038646240 17649152 8384560
08:22:33 26827 8192000000 9926032032 10312097792 1000393856 10.882 97 21 42 3 1031059280 26303840 4361584
08:24:05 9886 8192000000 9976155824 10379997184 1050616832 11.367 100 49 96 1 1081683232 20270864 10795536
08:25:18 8519 8192000000 9973295216 10378989568 1063675232 11.492 100 45 89 3 1090596528 17111888 9809408
08:26:28 97966 8192000000 10016981616 10416889856 1089237680 11.735 100 42 84 3 1121945168 23527008 9180480
08:29:22 12003 8192000000 10064179024 10457219072 1112812368 11.959 100 58 114 0 1174174688 49699232 11663088
08:30:41 6338 8192000000 10106110448 10504466432 1128309136 12.105 100 54 106 1 1218866480 79346960 11210384
08:31:51 11709 8192000000 10130224320 10531880960 1138872112 12.205 100 55 107 2 1238358416 87319376 12166928
08:33:07 5907 8192000000 10216570736 10613059584 1153088384 12.338 100 68 133 0 1317213744 148903376 15221984
08:34:18 13802 8192000000 10235094688 10645544960 1163532288 12.436 100 44 89 1 1332999088 159221984 10244816
08:35:40 4090 8192000000 10277730208 10682171392 1178043088 12.572 100 70 138 1 1385013088 190764144 16205856
08:36:50 1665 8192000000 10299658288 10711953408 1178270640 12.574 100 77 152 3 1396829008 200949760 17608608
08:38:00 2471 8192000000 10361554544 10764775424 1200447168 12.780 100 76 150 1 1458135696 240543360 17145168
08:39:09 2593 8192000000 10408783584 10813399040 1198477680 12.762 100 86 170 3 1515581088 296727904 20375504
08:40:15 2451 8192000000 10432488352 10831818752 1168648880 12.484 100 79 158 0 1529801440 342875936 18276624
08:41:23 3468 8192000000 10455174208 10853924864 1149946032 12.309 100 78 155 1 1559030320 389842480 19241808
08:42:37 1336 8192000000 10479438448 10882527232 1151338000 12.322 100 74 146 1 1579463648 410059248 18066400
08:43:45 2136 8192000000 10525259664 10938048512 1170604768 12.502 100 78 155 0 1623725408 434315088 18805552
08:44:51 5080 8192000000 10403377840 10841051136 1170797168 12.504 100 43 86 1 1503768480 322165648 10805664
08:45:58 16386 8192000000 10428222288 10872504320 1149603008 12.306 99 40 80 1 1542777232 382522864 10651360
08:47:18 8583 8192000000 10415933568 10853072896 1146104448 12.273 100 39 78 0 1529922096 373552128 10265520
08:48:31 22276 8192000000 10430904224 10877775872 1134369488 12.163 98 36 72 1 1515039456 371002208 9667760
08:49:58 13355 8192000000 10308398768 10782134272 1149714352 12.307 99 24 48 0 1403304448 247619856 5970240
08:51:40 44593 8192000000 10263788032 10739412992 1135727712 12.175 100 15 30 1 1362704608 223219792 3757104
08:53:33 38058 8192000000 10093633984 10524807168 1130066608 12.122 100 26 53 0 1170249936 34139664 6043664
08:55:15 62350 8192000000 10078429072 10501750784 1083202656 11.678 96 29 58 3 1136538576 47160736 6175184
08:58:13 42279 8192000000 10071936976 10502995968 1108205472 11.915 99 31 61 1 1146313952 32280080 5828400
09:00:09 76653 8192000000 10125173824 10562166784 1129443360 12.116 99 25 50 2 1207372512 72753056 5176096
09:02:30 51733 8192000000 10143213728 10604986368 1124138288 12.066 98 38 76 1 1248915792 116243808 8533696
09:04:43 112969 8192000000 10181672304 10628456448 1143667920 12.250 99 18 36 2 1252225728 104306192 4251616
09:07:49 57346 8192000000 10137105424 10633240576 1142252320 12.237 99 11 22 1 1253795152 108796864 2745968
09:09:49 63757 8192000000 10096583328 10539212800 1130218560 12.123 98 12 24 1 1185091584 52454080 2418944
09:11:53 56977 8192000000 10101210608 10588565504 1138723664 12.204 99 21 42 0 1195425856 51722624 4979568
09:13:51 90766 8192000000 10112221216 10569703424 1145968496 12.272 99 9 18 0 1208410592 60474592 1967504
09:16:44 42334 8192000000 10088506304 10518335488 1146238448 12.274 99 29 58 1 1168750336 16277648 6234240
09:18:26 56884 8192000000 10128868816 10657333248 1158056432 12.385 100 14 28 0 1271481600 110317072 3108096
09:20:39 129043 8192000000 10105862512 10622869504 1118828944 12.016 97 11 22 0 1169710832 48610960 2270928
09:24:25 71383 8192000000 10097331152 10555236352 1127116704 12.094 98 12 24 0 1186099904 56435920 2547280
09:26:37 57828 8192000000 10112131424 10614943744 1124396784 12.069 98 6 12 0 1189511568 64035360 1079424
09:28:35 68852 8192000000 10057598160 10543857664 1089364048 11.737 96 16 32 0 1156799440 64083312 3352080
09:30:46 27704 8192000000 10111532736 10543665152 1133014640 12.150 100 22 42 1 1192747856 55578368 4154848
09:32:18 36545 8192000000 10173996096 10621571072 1155016576 12.357 100 27 54 2 1253384624 92637904 5730144
09:33:57 29183 8192000000 10076334800 10509164544 1134586896 12.165 100 35 72 0 1163111936 21536240 6988800
09:35:30 24237 8192000000 10093621856 10521317376 1099435120 11.832 98 40 80 1 1160302752 52899504 7968128
09:37:07 30262 8192000000 10077079760 10507808768 1095389648 11.794 99 47 95 1 1158549568 53943328 9216592
09:38:39 48169 8192000000 10085933936 10511818752 1089852480 11.741 98 35 70 4 1151527344 54741440 6933424
09:40:33 55826 8192000000 10084345760 10583105536 1099225872 11.830 98 7 14 0 1161942944 61364528 1352544
09:42:56 100772 8192000000 10052860432 10495135744 1063298592 11.488 95 20 40 0 1126053424 58765984 3988848
09:48:56 79138 8192000000 10050925312 10485055488 1082280192 11.669 98 12 23 0 1112193632 27670336 2243104
09:51:22 88783 8192000000 10105966576 10603565056 1121550352 12.042 100 26 52 2 1222692432 95398832 5743248
09:53:52 64881 8192000000 10071628608 10514923520 1103624640 11.872 98 14 28 2 1156861184 50374096 2862448
09:56:04 50902 8192000000 10096122336 10518618112 1120339856 12.030 99 39 77 2 1156906720 28957712 7609152
09:57:56 78398 8192000000 10151154736 10629550080 1113142144 11.962 98 33 66 1 1181117648 61358816 6616688
10:01:27 110316 8192000000 10127519696 10589089792 1133026464 12.150 99 23 46 0 1180316528 42848224 4441840
10:04:25 76956 8192000000 10090231600 10648686592 1140868592 12.224 100 5 10 0 1214329888 72440640 1020656
10:06:44 103782 8192000000 10086913024 10553184256 1114582704 11.976 98 11 22 2 1161041648 44152816 2306128
10:09:28 64896 8192000000 10100717616 10534187008 1146642416 12.278 100 12 23 1 1195789168 46788448 2358304
10:11:43 81982 8192000000 10125148320 10627252224 1132617200 12.146 99 16 32 0 1218270176 82327968 3325008
10:14:10 65000 8192000000 10083621168 10510123008 1114534688 11.975 97 25 50 0 1149647696 30001632 5111376
10:16:20 62072 8192000000 10082464512 10550996992 1122531664 12.051 98 24 46 2 1183843120 55933984 5377472
10:18:36 61622 8192000000 10105381104 10563317760 1142450640 12.239 100 22 44 0 1203596336 56452752 4692944
10:20:39 45677 8192000000 10093863264 10555641856 1134875504 12.167 100 6 12 0 1198134096 61884128 1374464
10:22:29 78189 8192000000 10079751008 10520834048 1075507280 11.605 94 18 36 1 1136130912 57259312 3364320
10:24:49 79441 8192000000 10088970992 10565074944 1089168560 11.735 96 20 41 2 1153754032 60438784 4146688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-10-2020 10:28:00 to 16-10-2020 10:28:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 94 v_add_invoice_to_stock 335544517
user_exc 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-10-2020 10:28:15 to 16-10-2020 10:28:17.

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          228239
MON$OLDEST_ACTIVE               229654
MON$OLDEST_SNAPSHOT             229654
MON$NEXT_TRANSACTION            229654
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                       2106624
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             770
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2226 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.2226 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Fri Oct 16 10:26:52 2020
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Fri Oct 16 10:26:54 2020
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Fri Oct 16 10:26:56 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
--- end of diff output ---

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

16.10.20 10:28:18. 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
2020-10-16 10:28:20 No severe PSQL-related problems occured
16.10.20 10:28:21. End of report.