Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2020 07:28
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 16845576 kB
MemAvailable 31630920 kB
Buffers 0 kB
Cached 14840624 kB
SwapCached 5428 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-13 07:26:48
DTS_END                         2020-10-13 07:26:48.5740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/56800
TRN_ID                          241959
ATT_ID                          626
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: 9121.81 24675 1094617
customer order: creation 169.83 403 20380
customer order: refuse 14.90 336 1788
order to supplier: creation 45.33 1420 5439
order to supplier: removal 3.54 98358 425
invoice (draft): creation 45.83 3488 5500
invoice (draft): removal 53.03 760 6364
invoice accept: apply 42.04 17088 5045
invoice accept: cancel 47.80 834 5736
customer reserve: creation 3742.79 175 449135
customer reserve: removal 4653.88 60 558465
realization accept: apply 84.53 167 10143
realization accept: cancel 90.17 110 10820
payment from customer: creation 59.12 105 7094
payment from customer: removal 5.82 90 698
payment to supplier: creation 48.56 113 5827
payment to supplier: removal 3.83 110 459
service: total inventory turnovers 3.53 3570 423
service: total monetary turnovers 6.36 502 763
service: aggregate perf. data 0.91 16329 109
service: refresh index statistics 0.03 349473 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2020 07:28:51 to 13-10-2020 07:28:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6613
TEST_TIME 2 5358
TEST_TIME 3 6294
TEST_TIME 4 6111
TEST_TIME 5 6295
TEST_TIME 6 6024
TEST_TIME 7 5889
TEST_TIME 8 5788
TEST_TIME 9 5925
TEST_TIME 10 6070
TEST_TIME 11 6218
TEST_TIME 12 6543
TEST_TIME 13 6683
TEST_TIME 14 7093
TEST_TIME 15 7208
TEST_TIME 16 7720
TEST_TIME 17 8196
TEST_TIME 18 8742
TEST_TIME 19 8687
TEST_TIME 20 8845
TEST_TIME 21 8776
TEST_TIME 22 9273
TEST_TIME 23 9361
TEST_TIME 24 9376
TEST_TIME 25 9478
TEST_TIME 26 9915
TEST_TIME 27 10630
TEST_TIME 28 10948
TEST_TIME 29 10837
TEST_TIME 30 10731
TEST_TIME 31 10667
TEST_TIME 32 10825
TEST_TIME 33 10684
TEST_TIME 34 10399
TEST_TIME 35 10135
TEST_TIME 36 10082
TEST_TIME 37 9979
TEST_TIME 38 9903
TEST_TIME 39 9970
TEST_TIME 40 9955
TEST_TIME 41 9808
TEST_TIME 42 9675
TEST_TIME 43 9670
TEST_TIME 44 9953
TEST_TIME 45 10012
TEST_TIME 46 9960
TEST_TIME 47 9993
TEST_TIME 48 10016
TEST_TIME 49 10103
TEST_TIME 50 9986
TEST_TIME 51 10138
TEST_TIME 52 10145
TEST_TIME 53 10178
TEST_TIME 54 10175
TEST_TIME 55 10114
TEST_TIME 56 10197
TEST_TIME 57 10045
TEST_TIME 58 9913
TEST_TIME 59 9833
TEST_TIME 60 9758
TEST_TIME 61 9718
TEST_TIME 62 9641
TEST_TIME 63 9556
TEST_TIME 64 9483
TEST_TIME 65 9444
TEST_TIME 66 9487
TEST_TIME 67 9442
TEST_TIME 68 9436
TEST_TIME 69 9481
TEST_TIME 70 9515
TEST_TIME 71 9561
TEST_TIME 72 9576
TEST_TIME 73 9519
TEST_TIME 74 9547
TEST_TIME 75 9673
TEST_TIME 76 9724
TEST_TIME 77 9667
TEST_TIME 78 9562
TEST_TIME 79 9502
TEST_TIME 80 9416
TEST_TIME 81 9434
TEST_TIME 82 9352
TEST_TIME 83 9314
TEST_TIME 84 9301
TEST_TIME 85 9298
TEST_TIME 86 9350
TEST_TIME 87 9330
TEST_TIME 88 9329
TEST_TIME 89 9301
TEST_TIME 90 9312
TEST_TIME 91 9281
TEST_TIME 92 9502
TEST_TIME 93 9473
TEST_TIME 94 9418
TEST_TIME 95 9366
TEST_TIME 96 9338
TEST_TIME 97 9290
TEST_TIME 98 9313
TEST_TIME 99 9297
TEST_TIME 100 9275
TEST_TIME 101 9293
TEST_TIME 102 9303
TEST_TIME 103 9302
TEST_TIME 104 9361
TEST_TIME 105 9394
TEST_TIME 106 9402
TEST_TIME 107 9396
TEST_TIME 108 9380
TEST_TIME 109 9394
TEST_TIME 110 9416
TEST_TIME 111 9433
TEST_TIME 112 9448
TEST_TIME 113 9367
TEST_TIME 114 9294
TEST_TIME 115 9277
TEST_TIME 116 9241
TEST_TIME 117 9205
TEST_TIME 118 9178
TEST_TIME 119 9165
TEST_TIME 120 9122

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-10-2020 07:28:54 to 13-10-2020 07:28:58.

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 1512476 1512476 0 0.00 0 11040 6 0 0
doc_list_biud 1512476 1512476 0 0.00 0 41066 28 0 0
sp_add_invoice_to_stock 5152 5045 107 2.07 32 181523 17088 0 107
sp_cancel_adding_invoice 5736 5736 0 0.00 4 100839 835 0 0
sp_cancel_client_order 1788 1788 0 0.00 5 10628 336 0 0
sp_cancel_customer_reserve 558465 558465 0 0.00 1 41067 61 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 7779 91 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 3 7836 110 0 0
sp_cancel_supplier_invoice 6372 6364 8 0.12 2 26779 761 0 8
sp_cancel_supplier_order 425 425 0 0.00 12 884265 98359 0 0
sp_cancel_write_off 10820 10820 0 0.00 1 7053 110 0 0
sp_client_order 20380 20380 0 0.00 4 20164 403 0 0
sp_customer_reserve 449135 449135 0 0.00 0 57324 175 0 0
sp_fill_shopping_cart 31317 31317 0 0.00 1 14425 43 0 0
sp_fill_shopping_cart_clo_res 449134 449134 0 0.00 0 14971 15 0 0
sp_get_clo_for_invoice 5044 5044 0 0.00 0 21421 289 0 0
sp_kill_cost_storno 17713 17713 0 0.00 0 10431 51 0 0
sp_kill_qstorno_ret_qs2qd 1134539 1134539 0 0.00 0 41061 33 0 0
sp_kill_qty_storno 598784 598784 0 0.00 0 41065 70 0 0
sp_lock_selected_doc 21137 21137 0 0.00 0 8122 5 0 0
sp_make_cost_storno 28107 28107 0 0.00 0 10336 48 0 0
sp_multiply_rows_for_pdistr 28107 28107 0 0.00 0 7296 34 0 0
sp_multiply_rows_for_qdistr 20380 20380 0 0.00 0 19824 233 0 0
sp_pay_from_customer 7094 7094 0 0.00 4 8979 105 0 0
sp_pay_to_supplier 5827 5827 0 0.00 5 8115 114 0 0
sp_payment_common 12921 12921 0 0.00 1 8499 19 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 10620 305 0 0
sp_qd_handle_on_invoice_upd_sts 10779 10779 0 0.00 0 14039 337 0 0
sp_qd_handle_on_reserve_upd_sts 10143 10143 0 0.00 0 12838 51 0 0
sp_reserve_write_off 10143 10143 0 0.00 7 18222 168 0 0
sp_supplier_invoice 5500 5500 0 0.00 7 105635 3489 0 0
sp_supplier_order 5439 5439 0 0.00 5 116421 1420 0 0
srv_aggregate_perf_data 109 109 0 0.00 2700 321032 16329 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1883 137222 47058 0 0
srv_find_qd_qs_mism 1048592 1048592 0 0.00 0 11021 5 0 0
srv_make_invnt_saldo 423 423 0 0.00 55 103427 3570 0 0
srv_make_money_saldo 763 763 0 0.00 1 11430 502 0 0
srv_recalc_idx_stat 4 4 0 0.00 227797 464979 349474 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5045 5045 0 0.00 0 25553 172 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 4283 90 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 12110 17 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 2123 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 3032 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 200 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 439 15 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 20636 1367 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 5559 13 0 0
t$perf-norm:sp_client_order 20380 20380 0 0.00 0 5625 14 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 1267 9 0 0
t$perf-norm:sp_pay_from_customer 7094 7094 0 0.00 0 11496 12 0 0
t$perf-norm:sp_pay_to_supplier 5827 5827 0 0.00 0 13257 16 0 0
t$perf-norm:sp_reserve_write_off 10143 10143 0 0.00 0 9152 9 0 0
t$perf-norm:sp_supplier_invoice 5500 5500 0 0.00 0 7651 22 0 0
t$perf-norm:sp_supplier_order 5439 5439 0 0.00 0 4685 17 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 498 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5470 86 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 496 9 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 6244 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 61 17 0 0
v_add_invoice_to_stock 10300 10086 214 2.07 0 4901 17 0 214
v_all_customers 36948 36948 0 0.00 0 56 1 0 0
v_all_suppliers 29986 29986 0 0.00 0 23 1 0 0
v_all_wares 40760 40760 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1234 7 0 0
v_cancel_client_order 3576 3576 0 0.00 0 5146 7 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 1808 9 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 1286 6 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 4584 14 0 0
v_cancel_supplier_invoice 832 816 16 1.92 0 270 13 0 16
v_cancel_supplier_order 850 850 0 0.00 0 1022 6 0 0
v_cancel_write_off 2048 2048 0 0.00 0 1754 14 0 0
v_max_id_clo_ord 5438 5438 0 0.00 0 431 1 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 4489 17 0 0
v_max_id_ord_sup 5499 5499 0 0.00 0 6421 6 0 0
v_max_non_paid_invoice 1771 1771 0 0.00 0 127 2 0 0
v_max_non_paid_realizn 3841 3841 0 0.00 0 5568 4 0 0
v_min_id_clo_ord 5438 5438 0 0.00 0 4636 7 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 8294 14 0 0
v_min_id_ord_sup 5499 5499 0 0.00 0 6399 9 0 0
v_min_non_paid_invoice 5827 5827 0 0.00 0 6938 11 0 0
v_min_non_paid_realizn 7094 7094 0 0.00 0 4849 12 0 0
v_our_firm 10318 10318 0 0.00 0 19 1 0 0
v_reserve_write_off 20286 20286 0 0.00 0 4327 7 0 0
x_lock_depdocs_on_canc_invoice 11692 11692 0 0.00 0 18089 92 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 2346 268 0 0
x_make_qstorno_1000_1200 5439 5439 0 0.00 2 116405 1345 0 0
x_make_qstorno_1200_2000 5500 5500 0 0.00 4 98874 3320 0 0
x_make_qstorno_2100_3300 449135 449135 0 0.00 0 57322 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2020 07:28:59 to 13-10-2020 07:29:03.

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
04:56:52 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
04:57:53 25079 8192000000 9728252288 10027053056 803639136 8.933 100 84 168 1 831507392 7514128 20354128
04:59:20 32163 8192000000 9776526752 10082594816 842376064 9.324 100 91 182 2 876926272 13165056 21385152
05:00:57 13090 8192000000 9807743344 10130014208 877532400 9.675 100 81 162 2 922035296 26334784 18168112
05:02:16 13335 8192000000 9892603232 10226139136 926323520 10.158 100 79 158 0 993442400 50005824 17113056
05:03:34 7137 8192000000 9953838336 10302222336 972889232 10.615 100 85 170 2 1073852704 83246272 17717200
05:04:44 3998 8192000000 9993558592 10351513600 1003911568 10.916 100 88 171 1 1100353344 79487328 16954448
05:05:49 3508 8192000000 9995737216 10360254464 1035781776 11.224 100 77 153 1 1108842800 58374000 14687024
05:06:54 1081 8192000000 10032546272 10399170560 1061273792 11.469 100 97 194 1 1139900768 58291520 20335456
05:07:55 6711 8192000000 9969259120 10361434112 1064552608 11.500 100 28 56 6 1088995968 18165952 6277408
05:09:05 8701 8192000000 9990876784 10395095040 1073916304 11.589 100 32 64 1 1107124144 25971152 7236688
05:10:17 8536 8192000000 9999510000 10391199744 1087027552 11.714 100 41 82 4 1124843728 28585712 9230464
05:11:28 58201 8192000000 9997747152 10399019008 1086665312 11.711 100 24 47 1 1110876288 19347264 4863712
05:13:28 6407 8192000000 10016010112 10423283712 1096575968 11.805 100 53 106 1 1122668688 14281840 11810880
05:14:37 9286 8192000000 9999555904 10399092736 1088874416 11.732 99 33 66 1 1113186608 17163920 7148272
05:15:46 10118 8192000000 10009770480 10423951360 1094007728 11.781 100 18 35 0 1120401984 22750976 3643280
05:16:57 26798 8192000000 9996482336 10399072256 1063911456 11.494 97 23 46 0 1088903216 20130064 4861696
05:18:24 7555 8192000000 9946107280 10336047104 1018837616 11.061 97 36 69 1 1040519136 13228448 8453072
05:19:33 7212 8192000000 9931905424 10308632576 991678096 10.798 97 56 112 3 1026878464 22525328 12675040
05:20:43 54090 8192000000 9939657264 10312323072 1014021792 11.014 98 48 96 2 1036918848 12177200 10719856
05:22:38 9145 8192000000 9944214112 10338263040 1039026912 11.255 100 36 72 1 1073919680 26841616 8051152
05:23:50 8147 8192000000 9974659632 10370871296 1060763024 11.464 100 39 78 1 1082129952 12773744 8593184
05:25:00 7407 8192000000 9981636032 10382041088 1070763168 11.559 100 31 62 3 1099672928 22314864 6594896
05:26:14 40412 8192000000 9988294976 10382204928 1077765648 11.626 100 30 57 1 1103273472 19139936 6367888
05:28:07 9414 8192000000 10044510320 10444480512 1100516352 11.843 100 62 123 1 1147600912 34602560 12482000
05:29:25 8508 8192000000 10065396448 10465910784 1112515408 11.956 100 56 111 2 1172824656 49298688 11010560
05:30:39 6530 8192000000 10064927440 10465071104 1125980592 12.083 100 43 86 1 1177435248 41723872 9730784
05:31:49 22440 8192000000 10106520144 10527649792 1141281136 12.228 100 39 77 0 1221262656 71664400 8317120
05:33:24 3386 8192000000 10137636128 10544406528 1149605264 12.306 100 59 116 0 1247170336 83827024 13738048
05:34:33 17573 8192000000 10177471936 10590683136 1157723600 12.382 100 39 78 3 1282360832 115853472 8783760
05:35:54 18238 8192000000 10204850960 10629464064 1158373872 12.388 100 34 68 3 1318670752 152037392 8259488
05:37:18 14535 8192000000 10237282176 10644865024 1153233120 12.340 99 46 92 0 1343003600 179958144 9812336
05:38:37 4296 8192000000 10216217344 10614222848 1149774512 12.307 100 74 145 0 1320047648 155243488 15029648
05:39:50 1882 8192000000 10255412336 10645184512 1136847856 12.186 100 82 160 1 1353576304 198953360 17775088
05:40:57 2796 8192000000 10285353536 10675404800 1144408704 12.257 100 85 167 2 1384140224 221253968 18477552
05:42:06 1994 8192000000 10308307008 10706055168 1138417488 12.201 99 83 164 0 1405351472 247243408 19690576
05:43:12 3323 8192000000 10273893696 10664902656 1137649344 12.193 100 83 165 0 1375930928 220047776 18233808
05:44:21 2929 8192000000 10274027008 10685489152 1142111280 12.235 100 80 160 1 1365294096 204628736 18554080
05:45:26 11605 8192000000 10246967472 10669539328 1132426752 12.144 99 33 64 1 1342759328 202024896 8307680
05:46:40 17699 8192000000 10299135024 10743943168 1145263920 12.265 100 32 64 0 1409651040 256832480 7554640
05:48:10 5152 8192000000 10314428576 10737156096 1147539392 12.286 100 48 94 1 1422338848 263361744 11437712
05:49:21 17916 8192000000 10310823152 10729836544 1154301072 12.350 100 26 52 0 1388303472 227184384 6818016
05:50:46 20593 8192000000 10316396048 10771685376 1149591824 12.306 100 24 48 1 1438450320 282436080 6422416
05:52:12 18705 8192000000 10287733328 10749620224 1124895456 12.073 100 18 36 0 1425319776 295034592 5389728
05:53:34 33497 8192000000 10112936016 10592231424 1097548528 11.814 98 11 22 1 1230083696 129573040 2962128
05:55:10 61725 8192000000 10066388608 10477129728 1094853248 11.789 98 42 80 1 1138673968 35632480 8188240
05:57:19 111968 8192000000 10067979088 10540081152 1114737216 11.977 99 7 14 0 1198398384 81939600 1721568
06:00:15 87633 8192000000 10038205328 10470268928 1116380448 11.993 99 44 89 0 1140040784 13435152 10225184
06:03:02 61840 8192000000 10120545904 10581303296 1138644592 12.203 100 29 58 2 1223375808 78581088 6150128
06:05:07 61034 8192000000 10123895440 10579701760 1143705920 12.250 100 15 29 1 1203953472 57089408 3158144
06:07:12 49747 8192000000 10162342640 10607296512 1160790480 12.411 100 15 30 0 1255055024 90889792 3374752
06:09:02 43093 8192000000 10211296768 10670788608 1151161520 12.320 99 15 30 0 1298126288 142928576 4036192
06:10:46 71645 8192000000 10100484160 10555764736 1140502688 12.220 99 13 24 2 1223927696 80606608 2818400
06:12:58 49812 8192000000 10107764032 10559643648 1141230224 12.227 99 23 46 1 1222136784 76319104 4587456
06:14:48 40123 8192000000 10116113248 10593738752 1123167312 12.057 98 19 38 0 1191569376 64171424 4230640
06:17:01 96844 8192000000 10112380544 10586537984 1134749072 12.166 99 21 42 1 1278212912 138664912 4798928
06:19:46 43504 8192000000 10094013104 10521796608 1088100528 11.725 95 23 46 1 1133175616 40351648 4723440
06:21:32 40872 8192000000 10093215040 10509639680 1098435968 11.823 97 36 71 0 1145303440 39989888 6877584
06:23:14 122021 8192000000 10042987408 10483511296 1121243024 12.039 100 17 34 1 1160827968 36237232 3347712
06:26:16 37156 8192000000 10051653648 10505527296 1116594944 11.995 99 10 20 0 1155884720 37434480 1855296
06:27:55 23516 8192000000 10049080320 10530807808 1125445744 12.078 100 5 10 0 1172264064 46160304 658016
06:29:19 66311 8192000000 10059542832 10539495424 1124552144 12.070 100 4 8 0 1149974592 24841760 580688
06:32:03 34823 8192000000 10066027200 10508472320 1095837488 11.798 98 14 28 0 1153339024 54128288 3373248
06:33:38 48306 8192000000 10084798144 10533515264 1102502928 11.861 97 26 52 1 1168987344 60724768 5759648
06:35:36 49200 8192000000 10067817120 10540740608 1091660384 11.758 97 15 30 0 1167080688 71934176 3486128
06:37:34 57982 8192000000 10047224096 10503606272 1086772480 11.712 96 21 42 1 1144729840 53879840 4077520
06:39:32 48170 8192000000 10128764544 10568851456 1107052608 11.905 98 25 50 3 1199080464 86623344 5404512
06:41:21 50758 8192000000 10066872304 10591916032 1108775984 11.921 99 14 28 0 1204452368 92826560 2849824
06:43:20 128348 8192000000 10086288928 10542342144 1128346112 12.106 100 18 36 0 1173487328 41021216 4120000
06:46:39 55833 8192000000 10078633712 10559827968 1136776480 12.185 100 12 24 0 1209606720 70111776 2718464
06:48:36 60189 8192000000 10080004128 10530988032 1089465040 11.738 96 11 20 0 1147639728 56085184 2089504
06:50:43 31617 8192000000 10064133232 10525229056 1124937968 12.074 99 23 46 4 1184438496 54121696 5378832
06:52:15 47530 8192000000 10089532448 10568343552 1102083552 11.857 98 24 47 1 1185368816 78126960 5158304
06:54:03 73423 8192000000 10115442912 10581196800 1119998192 12.027 98 13 26 1 1214001536 90951760 3051584
06:56:17 76898 8192000000 10205990432 10672091136 1133223056 12.152 99 25 50 0 1235688224 96920688 5544480
06:58:40 59026 8192000000 10096617728 10584190976 1125371648 12.078 99 17 34 1 1189130880 60431312 3327920
07:00:40 96852 8192000000 10085388368 10560995328 1137092256 12.188 100 28 56 1 1189928240 47246736 5589248
07:03:18 64289 8192000000 10105131840 10575306752 1135070384 12.169 99 12 24 1 1204509504 66605392 2833728
07:05:38 78520 8192000000 10126783424 10584457216 1145179456 12.264 99 17 34 1 1196771296 47867648 3724192
07:08:12 74634 8192000000 10105228096 10597076992 1164045344 12.441 100 20 40 0 1231666784 63889984 3731456
07:10:30 56809 8192000000 10101488032 10640179200 1117692400 12.005 97 11 22 0 1193252736 73467472 2092864
07:12:44 90745 8192000000 10082787184 10531696640 1074181104 11.592 93 15 30 1 1120319872 42952240 3186528
07:15:21 61745 8192000000 10120763744 10574069760 1141233568 12.227 100 15 30 1 1177528912 33551376 2743968
07:17:27 137222 8192000000 10051511024 10530033664 1053370864 11.393 92 6 12 0 1081275376 27000384 904128
07:20:52 71292 8192000000 10033995088 10496122880 1095151216 11.792 97 22 44 1 1143850896 43649632 5050048
07:23:15 39129 8192000000 10066959584 10509770752 1105857408 11.893 97 12 24 2 1146452448 38222752 2372288
07:25:15 65980 8192000000 10050466192 10502782976 1108269888 11.916 98 14 28 0 1133556832 22429200 2857744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-10-2020 07:29:05 to 13-10-2020 07:29:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 107 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2020 07:29:20 to 13-10-2020 07:29:22.

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          240472
MON$OLDEST_ACTIVE               242083
MON$OLDEST_SNAPSHOT             242083
MON$NEXT_TRANSACTION            242083
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                       2114752
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             795
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 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.2225 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 ---
:741: 
:742: mscs-test-161.budzdorov.ru    Tue Oct 13 07:26:56 2020
:743:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:744: 
--- end of diff output ---

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

13.10.20 07:29:23. 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-13 07:29:26 No severe PSQL-related problems occured
13.10.20 07:29:27. End of report.