Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2020 16: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 17395980 kB
MemAvailable 31482996 kB
Buffers 0 kB
Cached 14274768 kB
SwapCached 3064 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.7
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-31 16:26:58
DTS_END                         2020-10-31 16:26:58.2450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/57074
TRN_ID                          239931
ATT_ID                          620
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: 9288.24 19711 1114589
customer order: creation 174.17 333 20900
customer order: refuse 15.26 351 1831
order to supplier: creation 45.90 1277 5508
order to supplier: removal 3.46 103349 415
invoice (draft): creation 46.04 3236 5525
invoice (draft): removal 54.08 843 6490
invoice accept: apply 41.22 17574 4946
invoice accept: cancel 48.53 776 5824
customer reserve: creation 3794.99 174 455399
customer reserve: removal 4762.17 58 571460
realization accept: apply 84.05 191 10086
realization accept: cancel 87.68 105 10522
payment from customer: creation 59.99 126 7199
payment from customer: removal 6.26 79 751
payment to supplier: creation 48.22 151 5786
payment to supplier: removal 3.86 85 463
service: total inventory turnovers 4.71 1269 565
service: total monetary turnovers 6.84 63 821
service: aggregate perf. data 0.78 24680 94
service: refresh index statistics 0.03 239492 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-10-2020 16:27:51 to 31-10-2020 16:27:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7954
TEST_TIME 2 5790
TEST_TIME 3 4789
TEST_TIME 4 4505
TEST_TIME 5 4666
TEST_TIME 6 4594
TEST_TIME 7 4713
TEST_TIME 8 4966
TEST_TIME 9 5447
TEST_TIME 10 5528
TEST_TIME 11 6167
TEST_TIME 12 6286
TEST_TIME 13 6633
TEST_TIME 14 6795
TEST_TIME 15 7137
TEST_TIME 16 7332
TEST_TIME 17 7679
TEST_TIME 18 7724
TEST_TIME 19 8290
TEST_TIME 20 8243
TEST_TIME 21 8498
TEST_TIME 22 8471
TEST_TIME 23 8540
TEST_TIME 24 8682
TEST_TIME 25 9125
TEST_TIME 26 9066
TEST_TIME 27 9507
TEST_TIME 28 10174
TEST_TIME 29 10857
TEST_TIME 30 10541
TEST_TIME 31 10529
TEST_TIME 32 10486
TEST_TIME 33 10539
TEST_TIME 34 10471
TEST_TIME 35 10282
TEST_TIME 36 10222
TEST_TIME 37 10114
TEST_TIME 38 10008
TEST_TIME 39 9881
TEST_TIME 40 9826
TEST_TIME 41 9786
TEST_TIME 42 9769
TEST_TIME 43 9669
TEST_TIME 44 9583
TEST_TIME 45 9545
TEST_TIME 46 9412
TEST_TIME 47 9342
TEST_TIME 48 9390
TEST_TIME 49 9397
TEST_TIME 50 9451
TEST_TIME 51 9450
TEST_TIME 52 9326
TEST_TIME 53 9323
TEST_TIME 54 9251
TEST_TIME 55 9318
TEST_TIME 56 9257
TEST_TIME 57 9358
TEST_TIME 58 9351
TEST_TIME 59 9299
TEST_TIME 60 9325
TEST_TIME 61 9363
TEST_TIME 62 9337
TEST_TIME 63 9390
TEST_TIME 64 9430
TEST_TIME 65 9475
TEST_TIME 66 9439
TEST_TIME 67 9521
TEST_TIME 68 9544
TEST_TIME 69 9588
TEST_TIME 70 9597
TEST_TIME 71 9631
TEST_TIME 72 9596
TEST_TIME 73 9496
TEST_TIME 74 9497
TEST_TIME 75 9443
TEST_TIME 76 9440
TEST_TIME 77 9409
TEST_TIME 78 9365
TEST_TIME 79 9377
TEST_TIME 80 9376
TEST_TIME 81 9333
TEST_TIME 82 9315
TEST_TIME 83 9266
TEST_TIME 84 9265
TEST_TIME 85 9238
TEST_TIME 86 9197
TEST_TIME 87 9191
TEST_TIME 88 9212
TEST_TIME 89 9240
TEST_TIME 90 9213
TEST_TIME 91 9195
TEST_TIME 92 9174
TEST_TIME 93 9313
TEST_TIME 94 9315
TEST_TIME 95 9336
TEST_TIME 96 9309
TEST_TIME 97 9434
TEST_TIME 98 9419
TEST_TIME 99 9417
TEST_TIME 100 9418
TEST_TIME 101 9426
TEST_TIME 102 9500
TEST_TIME 103 9448
TEST_TIME 104 9386
TEST_TIME 105 9382
TEST_TIME 106 9321
TEST_TIME 107 9338
TEST_TIME 108 9333
TEST_TIME 109 9311
TEST_TIME 110 9280
TEST_TIME 111 9245
TEST_TIME 112 9193
TEST_TIME 113 9207
TEST_TIME 114 9243
TEST_TIME 115 9224
TEST_TIME 116 9221
TEST_TIME 117 9244
TEST_TIME 118 9259
TEST_TIME 119 9286
TEST_TIME 120 9293

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-10-2020 16:27:55 to 31-10-2020 16:27: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 1537063 1537063 0 0.00 0 24337 6 0 0
doc_list_biud 1537061 1537061 0 0.00 0 30725 28 0 0
sp_add_invoice_to_stock 5085 4946 139 2.73 31 164537 17574 0 139
sp_cancel_adding_invoice 5824 5824 0 0.00 5 51031 777 0 0
sp_cancel_client_order 1831 1831 0 0.00 4 10574 352 0 0
sp_cancel_customer_reserve 571460 571460 0 0.00 1 30728 58 0 0
sp_cancel_pay_from_customer 751 751 0 0.00 3 6431 80 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 2 5625 86 0 0
sp_cancel_supplier_invoice 6503 6490 13 0.19 1 20325 843 0 13
sp_cancel_supplier_order 415 415 0 0.00 7 1010648 103350 0 0
sp_cancel_write_off 10522 10522 0 0.00 1 13343 106 0 0
sp_client_order 20900 20900 0 0.00 3 19372 333 0 0
sp_customer_reserve 455399 455399 0 0.00 0 61550 174 0 0
sp_fill_shopping_cart 31926 31926 0 0.00 1 25804 39 0 0
sp_fill_shopping_cart_clo_res 455394 455394 0 0.00 0 14004 13 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 23755 302 0 0
sp_kill_cost_storno 17560 17560 0 0.00 0 10338 52 0 0
sp_kill_qstorno_ret_qs2qd 1160347 1160347 0 0.00 0 30717 32 0 0
sp_kill_qty_storno 611567 611567 0 0.00 0 30725 69 0 0
sp_lock_selected_doc 21078 21078 0 0.00 0 7533 6 0 0
sp_make_cost_storno 28010 28010 0 0.00 0 24058 59 0 0
sp_multiply_rows_for_pdistr 28010 28010 0 0.00 0 16060 29 0 0
sp_multiply_rows_for_qdistr 20899 20899 0 0.00 0 15880 143 0 0
sp_pay_from_customer 7199 7199 0 0.00 4 29661 127 0 0
sp_pay_to_supplier 5786 5786 0 0.00 5 20670 151 0 0
sp_payment_common 12985 12985 0 0.00 1 29634 46 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 10546 319 0 0
sp_qd_handle_on_invoice_upd_sts 10763 10763 0 0.00 0 21737 325 0 0
sp_qd_handle_on_reserve_upd_sts 10086 10086 0 0.00 0 15172 59 0 0
sp_reserve_write_off 10086 10086 0 0.00 7 20643 192 0 0
sp_supplier_invoice 5525 5525 0 0.00 6 81312 3236 0 0
sp_supplier_order 5508 5508 0 0.00 5 33931 1278 0 0
srv_aggregate_perf_data 95 94 1 1.05 2166 428422 24681 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1108 140130 48333 0 0
srv_find_qd_qs_mism 1067219 1067219 0 0.00 0 12304 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 39 62181 1269 0 0
srv_make_money_saldo 821 821 0 0.00 0 30099 63 0 0
srv_recalc_idx_stat 4 4 0 0.00 116871 485208 239492 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4947 4947 0 0.00 0 11371 160 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 1854 68 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 5285 14 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 1643 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 751 751 0 0.00 0 703 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 5567 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 6606 36 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 25454 1727 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2689 13 0 0
t$perf-norm:sp_client_order 20900 20900 0 0.00 0 11298 22 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 6191 19 0 0
t$perf-norm:sp_pay_from_customer 7199 7199 0 0.00 0 9990 10 0 0
t$perf-norm:sp_pay_to_supplier 5786 5786 0 0.00 0 11705 14 0 0
t$perf-norm:sp_reserve_write_off 10086 10086 0 0.00 0 4072 8 0 0
t$perf-norm:sp_supplier_invoice 5525 5525 0 0.00 0 3297 19 0 0
t$perf-norm:sp_supplier_order 5508 5508 0 0.00 0 5478 19 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 9717 132 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 1901 22 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 1447 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10156 9878 278 2.73 0 7062 22 0 278
v_all_customers 38048 38048 0 0.00 0 18 1 0 0
v_all_suppliers 30280 30280 0 0.00 0 86 1 0 0
v_all_wares 41796 41796 0 0.00 0 132 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 662 6 0 0
v_cancel_client_order 3662 3662 0 0.00 0 2388 7 0 0
v_cancel_customer_prepayment 1502 1502 0 0.00 0 3852 10 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1905 6 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 4880 10 0 0
v_cancel_supplier_invoice 924 898 26 2.81 0 4308 30 0 26
v_cancel_supplier_order 830 830 0 0.00 0 2139 8 0 0
v_cancel_write_off 2028 2028 0 0.00 0 2594 19 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 2621 2 0 0
v_max_id_clo_res 988 988 0 0.00 0 3591 16 0 0
v_max_id_ord_sup 5521 5521 0 0.00 0 3542 4 0 0
v_max_non_paid_invoice 1673 1673 0 0.00 0 1375 4 0 0
v_max_non_paid_realizn 3900 3900 0 0.00 0 1291 3 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 5529 9 0 0
v_min_id_clo_res 988 988 0 0.00 0 2933 10 0 0
v_min_id_ord_sup 5521 5521 0 0.00 0 6388 8 0 0
v_min_non_paid_invoice 5786 5786 0 0.00 0 17770 37 0 0
v_min_non_paid_realizn 7199 7199 0 0.00 0 17771 30 0 0
v_our_firm 10345 10345 0 0.00 0 20 1 0 0
v_reserve_write_off 20172 20172 0 0.00 0 3787 8 0 0
x_lock_depdocs_on_canc_invoice 11865 11865 0 0.00 0 23831 114 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 15998 375 0 0
x_make_qstorno_1000_1200 5508 5508 0 0.00 1 33913 1184 0 0
x_make_qstorno_1200_2000 5525 5525 0 0.00 3 79452 3104 0 0
x_make_qstorno_2100_3300 455399 455399 0 0.00 0 61549 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2020 16:27:59 to 31-10-2020 16:28: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
13:57:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:02 15931 8192000000 10341558528 10662957056 1404467888 14.635 100 88 176 0 1439775184 6017040 29290256
13:59:27 18343 8192000000 10409936080 10738728960 1461215824 15.137 100 86 172 0 1497779264 8174128 28389312
14:00:51 28568 8192000000 10468908480 10805436416 1502286192 15.496 100 87 174 2 1540497264 9632944 28578128
14:02:25 6923 8192000000 10539772416 10892718080 1594856704 16.295 100 84 168 0 1631445040 11194000 25394336
14:03:40 11986 8192000000 10613553280 10984529920 1664065904 16.883 100 68 136 1 1695351344 11133008 20152432
14:04:56 5443 8192000000 10688489824 11066040320 1748947904 17.593 100 79 158 0 1784845840 13926832 21971104
14:06:04 4564 8192000000 10726325424 11120513024 1807711824 18.077 100 78 156 2 1842317408 12383136 22222448
14:07:11 2481 8192000000 10785852928 11175440384 1845090816 18.382 100 89 178 3 1882366144 12901584 24373744
14:08:15 1180 8192000000 10816789120 11204943872 1868546896 18.573 100 85 170 0 1907938048 15084976 24306176
14:09:17 4196 8192000000 10787455536 11204083712 1869942656 18.584 100 40 80 2 1890486624 9534304 11009664
14:10:23 25866 8192000000 10808179632 11219038208 1879120224 18.658 100 36 71 1 1893853344 4866848 9866272
14:12:07 8345 8192000000 10806659552 11234234368 1890971280 18.754 100 26 52 0 1905654912 5953440 8730192
14:13:17 10226 8192000000 10810674720 11247382528 1902602224 18.847 100 16 32 1 1912453408 5390624 4460560
14:14:27 15989 8192000000 10823229200 11260456960 1910426032 18.910 100 19 38 1 1925055472 9816864 4812576
14:15:46 16446 8192000000 10838907632 11253899264 1908492864 18.895 100 51 105 2 1929040448 6083824 14463760
14:17:03 6050 8192000000 10811269872 11236384768 1862527136 18.524 98 26 51 1 1875931344 5951152 7453056
14:18:13 6477 8192000000 10777402352 11212386304 1845497968 18.386 98 37 74 2 1866859792 10878048 10483776
14:19:22 34724 8192000000 10692658832 11116617728 1684457360 17.055 92 28 55 4 1697155760 4985632 7712768
14:21:01 4098 8192000000 10705578144 11116032000 1773133568 17.793 99 35 70 2 1788908992 6248752 9526672
14:22:07 6145 8192000000 10722689280 11133919232 1756726912 17.657 97 28 55 0 1770456704 5329328 8400464
14:23:18 17832 8192000000 10764185824 11175079936 1824245520 18.212 99 27 55 0 1839321424 5322560 9753344
14:24:36 22779 8192000000 10763947824 11193417728 1852107744 18.439 100 22 44 1 1863594896 5409600 6077552
14:26:00 9485 8192000000 10789767712 11216887808 1871721744 18.598 100 26 53 0 1889512352 9998240 7792368
14:27:10 2479 8192000000 10837358688 11251142656 1905150192 18.868 100 60 120 0 1929845504 8532112 16163200
14:28:24 32521 8192000000 10872095152 11288170496 1936972880 19.123 100 59 117 3 1967673136 13253088 17447168
14:30:00 37545 8192000000 10871924240 11296731136 1926561488 19.039 99 50 98 0 1955075904 14811552 13702864
14:31:41 34699 8192000000 10895600592 11325575168 1957954512 19.290 100 49 98 1 1987938896 15661760 14322624
14:33:22 6998 8192000000 10923127248 11338936320 1972882608 19.408 100 70 136 0 2010095424 17412896 19799920
14:34:38 9388 8192000000 10932321760 11352555520 1981902032 19.480 100 66 132 2 2023019328 21723968 19393328
14:35:53 6347 8192000000 10914143472 11336945664 1948427200 19.214 99 52 104 1 1984371200 20555072 15388928
14:37:06 6481 8192000000 10946659536 11367464960 1990529168 19.548 100 70 139 3 2035572128 24084576 20958384
14:38:17 2872 8192000000 10947722640 11375398912 1989413184 19.539 100 57 114 0 2034462496 28231152 16818160
14:39:27 2855 8192000000 10952503248 11381133312 1990267152 19.546 100 60 121 2 2040828736 31331600 19229984
14:40:45 6035 8192000000 10961293328 11387789312 1993985312 19.575 100 66 133 1 2049075648 34216720 20873616
14:41:56 3497 8192000000 10950681216 11377291264 1978903520 19.456 100 61 122 0 2036346352 37732864 19709968
14:43:03 1898 8192000000 10969508784 11379261440 1968850672 19.376 99 85 170 0 2038109904 43420400 25838832
14:44:08 10519 8192000000 10953945920 11381530624 1972092416 19.402 100 64 128 0 2037757168 45604144 20060608
14:45:30 3321 8192000000 10965700640 11394936832 1990838432 19.550 100 59 118 0 2053407376 43800880 18768064
14:46:42 1107 8192000000 10978101264 11390042112 1990399952 19.547 100 90 180 4 2054521264 36562800 27558512
14:47:45 10826 8192000000 10925875104 11364630528 1961900704 19.321 100 42 84 3 2014499872 38722704 13876464
14:49:13 6062 8192000000 10928317440 11361067008 1939009440 19.139 99 33 66 0 1990018976 39523120 11486416
14:50:21 6044 8192000000 10911955392 11337388032 1920482384 18.991 99 44 86 0 1979486944 45001072 14003488
14:51:31 13750 8192000000 10914193456 11361202176 1951532016 19.239 100 30 60 1 2008651856 46202800 10917040
14:52:49 19026 8192000000 10932184912 11373723648 1951599312 19.239 100 30 60 0 2014517840 52122112 10796416
14:54:09 13382 8192000000 10936362352 11366010880 1929270208 19.061 99 56 112 0 1999800032 50923824 19606000
14:55:24 22822 8192000000 10893594464 11337687040 1946892000 19.202 100 20 40 2 1970299488 16922480 6485008
14:57:37 33289 8192000000 10902430640 11362521088 1945645856 19.192 99 25 51 1 1974994432 21823072 7525504
14:59:11 52761 8192000000 10882023248 11342417920 1907796288 18.889 97 39 77 1 1934823280 15804448 11222544
15:01:04 75193 8192000000 10905430928 11374219264 1984591968 19.501 100 25 50 1 2000539568 9499424 6448176
15:03:29 88723 8192000000 10896541616 11364667392 1977711024 19.447 100 17 34 1 1990848976 8424880 4713072
15:05:59 140130 8192000000 10897093152 11361189888 1922391472 19.006 97 17 34 1 1941618624 14375248 4851904
15:09:20 70677 8192000000 10925571888 11394875392 1990831152 19.550 100 19 37 2 2015133424 19222928 5079344
15:11:39 51252 8192000000 10937563040 11403649024 2006568624 19.675 100 17 34 0 2025905552 13909824 5427104
15:13:39 40262 8192000000 10906044096 11371016192 1954100256 19.259 98 19 38 0 1981399488 21530432 5768800
15:15:36 23802 8192000000 10929171920 11402989568 2001592480 19.635 100 42 84 3 2037159440 23336688 12230272
15:17:12 39012 8192000000 10926104656 11415552000 1992866544 19.566 100 9 17 0 2015686400 20239152 2580704
15:18:52 85087 8192000000 10927147088 11397279744 2005458416 19.666 100 28 57 0 2022377488 8968864 7950208
15:21:23 71461 8192000000 10912909248 11399090176 1958947568 19.298 98 5 10 0 1975100304 14694528 1458208
15:23:35 61775 8192000000 10910235984 11387310080 1980881984 19.472 99 13 25 1 1998271536 13524240 3865312
15:25:41 41898 8192000000 10937866272 11399151616 1983672656 19.494 99 43 85 0 2005403792 8907168 12823968
15:27:30 86426 8192000000 10909610288 11374403584 1917285488 18.965 96 14 28 1 1928216672 7408608 3522576
15:29:57 91559 8192000000 10877299792 11345846272 1821256224 18.188 92 21 42 0 1838544528 10815552 6472752
15:32:34 69687 8192000000 10870784592 11338235904 1862733216 18.525 95 18 36 0 1883265712 15018816 5513680
15:35:03 136514 8192000000 10885997104 11346640896 1878636224 18.654 96 9 18 0 1893013520 12142832 2234464
15:38:20 73269 8192000000 10888948976 11360153600 1946891952 19.202 99 22 44 2 1967958704 15038112 6028640
15:40:38 83186 8192000000 10887153616 11350892544 1897807680 18.809 96 21 42 1 1916060016 12225984 6026352
15:43:44 76833 8192000000 10859778496 11328659456 1854447280 18.458 95 20 39 0 1876883712 16556288 5880144
15:46:01 64111 8192000000 10849897760 11322638336 1895564752 18.791 98 13 26 0 1917559472 17981392 4013328
15:48:06 65688 8192000000 10854332448 11325427712 1903919392 18.858 98 20 38 0 1918461024 9490736 5050896
15:50:12 74107 8192000000 10862100496 11337703424 1899616512 18.823 97 14 28 1 1913915552 10208496 4090544
15:53:23 92606 8192000000 10873977808 11357704192 1951919200 19.242 100 16 32 2 1969738496 13244784 4574512
15:56:47 80894 8192000000 10886234528 11349073920 1904094000 18.859 97 22 44 1 1921289248 11195152 6000096
15:59:10 41805 8192000000 10884566064 11358339072 1955293680 19.269 100 9 18 1 1972641696 15476736 1871280
16:00:54 58179 8192000000 10919939648 11390255104 1986255344 19.514 100 13 26 1 2000664048 10632784 3775920
16:03:01 110615 8192000000 10928127040 11403800576 1964327200 19.340 98 25 50 2 1992356592 20834768 7194624
16:05:52 93282 8192000000 10949794112 11433762816 1976421360 19.436 98 21 42 2 1999423120 17134816 5866944
16:08:27 104278 8192000000 10920684496 11399831552 1965925552 19.353 98 24 48 1 1986203504 13599424 6678528
16:11:47 70563 8192000000 10928916192 11399860224 1985073712 19.505 99 27 54 2 2003511104 10517696 7919696
16:13:59 25119 8192000000 10899926672 11380105216 1955529744 19.270 98 11 22 1 1965395200 6792304 3073152
16:15:24 43655 8192000000 10892751280 11367436288 1927819328 19.049 97 18 35 0 1942049840 9446752 4783760
16:17:09 77916 8192000000 10894736912 11361529856 1947187024 19.204 99 13 26 1 1966882688 15375360 4320304
16:19:49 97821 8192000000 10899725552 11372142592 1922544032 19.007 97 7 14 0 1940905248 16420000 1941216
16:22:29 38393 8192000000 10914175120 11388706816 1948456240 19.214 98 27 54 1 1967410720 11521104 7433376
16:24:13 63555 8192000000 10889544112 11352215552 1834867456 18.299 93 20 38 1 1854368896 14187280 5314160
16:26:20 40698 8192000000 9566237392 9883435008 678894400 7.653 35 14 26 1 686072464 3521600 3656464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 139 v_add_invoice_to_stock 335544517
user_exc 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 31-10-2020 16:28:21 to 31-10-2020 16:28:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240235
MON$OLDEST_ACTIVE               240236
MON$OLDEST_SNAPSHOT             240236
MON$NEXT_TRANSACTION            240236
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2030368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33383 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33383 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33383 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33383 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:50: 
:51: mscs-test-161.budzdorov.ru    Sat Oct 31 16:27:07 2020
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Sat Oct 31 16:27:11 2020
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
--- end of diff output ---

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

31.10.20 16:28:23. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-10-31 16:28:26 No severe PSQL-related problems occured
31.10.20 16:28:27. End of report.