Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2020 19: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 17426180 kB
MemAvailable 31473160 kB
Buffers 0 kB
Cached 14239632 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 19:26:56
DTS_END                         2020-10-31 19:26:56.6930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/59132
TRN_ID                          231565
ATT_ID                          637
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: 9360.29 25677 1123235
customer order: creation 162.57 371 19508
customer order: refuse 14.31 392 1717
order to supplier: creation 43.99 1255 5279
order to supplier: removal 3.73 93534 447
invoice (draft): creation 44.83 3411 5379
invoice (draft): removal 56.02 771 6722
invoice accept: apply 39.93 17697 4792
invoice accept: cancel 50.38 778 6046
customer reserve: creation 3725.95 173 447114
customer reserve: removal 4925.95 55 591114
realization accept: apply 81.14 183 9737
realization accept: cancel 87.19 106 10463
payment from customer: creation 57.41 137 6889
payment from customer: removal 5.29 61 635
payment to supplier: creation 46.88 135 5625
payment to supplier: removal 3.28 92 394
service: total inventory turnovers 4.88 1173 586
service: total monetary turnovers 5.76 59 691
service: aggregate perf. data 0.78 25566 93
service: refresh index statistics 0.03 367596 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2172
TEST_TIME 2 2855
TEST_TIME 3 4452
TEST_TIME 4 5406
TEST_TIME 5 5111
TEST_TIME 6 4907
TEST_TIME 7 5403
TEST_TIME 8 5446
TEST_TIME 9 5750
TEST_TIME 10 5862
TEST_TIME 11 5781
TEST_TIME 12 6267
TEST_TIME 13 6689
TEST_TIME 14 6665
TEST_TIME 15 6846
TEST_TIME 16 6878
TEST_TIME 17 7792
TEST_TIME 18 8034
TEST_TIME 19 8326
TEST_TIME 20 8781
TEST_TIME 21 9318
TEST_TIME 22 9271
TEST_TIME 23 9853
TEST_TIME 24 10449
TEST_TIME 25 10701
TEST_TIME 26 10304
TEST_TIME 27 10315
TEST_TIME 28 10405
TEST_TIME 29 10386
TEST_TIME 30 10183
TEST_TIME 31 10198
TEST_TIME 32 10064
TEST_TIME 33 10025
TEST_TIME 34 9972
TEST_TIME 35 9793
TEST_TIME 36 9777
TEST_TIME 37 9643
TEST_TIME 38 9613
TEST_TIME 39 9518
TEST_TIME 40 9476
TEST_TIME 41 9409
TEST_TIME 42 9419
TEST_TIME 43 9367
TEST_TIME 44 9248
TEST_TIME 45 9299
TEST_TIME 46 9570
TEST_TIME 47 9601
TEST_TIME 48 9627
TEST_TIME 49 9595
TEST_TIME 50 9535
TEST_TIME 51 9593
TEST_TIME 52 9563
TEST_TIME 53 9465
TEST_TIME 54 9477
TEST_TIME 55 9372
TEST_TIME 56 9381
TEST_TIME 57 9442
TEST_TIME 58 9334
TEST_TIME 59 9303
TEST_TIME 60 9327
TEST_TIME 61 9291
TEST_TIME 62 9221
TEST_TIME 63 9165
TEST_TIME 64 9057
TEST_TIME 65 9116
TEST_TIME 66 9262
TEST_TIME 67 9281
TEST_TIME 68 9296
TEST_TIME 69 9317
TEST_TIME 70 9324
TEST_TIME 71 9359
TEST_TIME 72 9405
TEST_TIME 73 9385
TEST_TIME 74 9372
TEST_TIME 75 9484
TEST_TIME 76 9560
TEST_TIME 77 9584
TEST_TIME 78 9541
TEST_TIME 79 9456
TEST_TIME 80 9438
TEST_TIME 81 9389
TEST_TIME 82 9424
TEST_TIME 83 9368
TEST_TIME 84 9373
TEST_TIME 85 9338
TEST_TIME 86 9275
TEST_TIME 87 9252
TEST_TIME 88 9192
TEST_TIME 89 9286
TEST_TIME 90 9285
TEST_TIME 91 9338
TEST_TIME 92 9371
TEST_TIME 93 9434
TEST_TIME 94 9401
TEST_TIME 95 9363
TEST_TIME 96 9450
TEST_TIME 97 9432
TEST_TIME 98 9440
TEST_TIME 99 9425
TEST_TIME 100 9472
TEST_TIME 101 9458
TEST_TIME 102 9505
TEST_TIME 103 9464
TEST_TIME 104 9389
TEST_TIME 105 9362
TEST_TIME 106 9300
TEST_TIME 107 9311
TEST_TIME 108 9320
TEST_TIME 109 9329
TEST_TIME 110 9297
TEST_TIME 111 9253
TEST_TIME 112 9216
TEST_TIME 113 9244
TEST_TIME 114 9281
TEST_TIME 115 9332
TEST_TIME 116 9325
TEST_TIME 117 9328
TEST_TIME 118 9356
TEST_TIME 119 9392
TEST_TIME 120 9362

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-10-2020 19:27:52 to 31-10-2020 19:27:56.

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 1536930 1536930 0 0.00 0 27649 6 0 0
doc_list_biud 1536929 1536929 0 0.00 0 41276 27 0 0
sp_add_invoice_to_stock 4907 4792 115 2.34 52 173258 17697 0 115
sp_cancel_adding_invoice 6046 6046 0 0.00 4 52205 779 0 0
sp_cancel_client_order 1717 1717 0 0.00 4 12976 392 0 0
sp_cancel_customer_reserve 591114 591114 0 0.00 1 25379 56 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 2 2437 61 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 2 3483 93 0 0
sp_cancel_supplier_invoice 6736 6722 14 0.20 2 22904 771 0 14
sp_cancel_supplier_order 447 447 0 0.00 5 1021472 93534 0 0
sp_cancel_write_off 10463 10463 0 0.00 1 29935 107 0 0
sp_client_order 19508 19508 0 0.00 4 52660 372 0 0
sp_customer_reserve 447114 447114 0 0.00 0 55535 173 0 0
sp_fill_shopping_cart 30154 30154 0 0.00 1 17207 36 0 0
sp_fill_shopping_cart_clo_res 447109 447109 0 0.00 0 22511 13 0 0
sp_get_clo_for_invoice 4787 4787 0 0.00 0 27898 299 0 0
sp_kill_cost_storno 17538 17538 0 0.00 0 27642 54 0 0
sp_kill_qstorno_ret_qs2qd 1199860 1199860 0 0.00 0 24762 30 0 0
sp_kill_qty_storno 631033 631033 0 0.00 0 41273 66 0 0
sp_lock_selected_doc 20227 20227 0 0.00 0 9170 7 0 0
sp_make_cost_storno 27038 27038 0 0.00 0 24629 56 0 0
sp_multiply_rows_for_pdistr 27038 27038 0 0.00 0 22774 34 0 0
sp_multiply_rows_for_qdistr 19508 19508 0 0.00 0 52626 165 0 0
sp_pay_from_customer 6889 6889 0 0.00 4 22846 137 0 0
sp_pay_to_supplier 5625 5625 0 0.00 5 14550 135 0 0
sp_payment_common 12514 12514 0 0.00 1 18052 42 0 0
sp_qd_handle_on_cancel_clo 1717 1717 0 0.00 0 12968 344 0 0
sp_qd_handle_on_invoice_upd_sts 10833 10833 0 0.00 0 41268 320 0 0
sp_qd_handle_on_reserve_upd_sts 9737 9737 0 0.00 0 17223 57 0 0
sp_reserve_write_off 9737 9737 0 0.00 6 17333 184 0 0
sp_supplier_invoice 5379 5379 0 0.00 6 108099 3412 0 0
sp_supplier_order 5279 5279 0 0.00 5 23758 1255 0 0
srv_aggregate_perf_data 93 93 0 0.00 2276 691996 25567 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2944 146719 54584 0 0
srv_find_qd_qs_mism 1077216 1077216 0 0.00 0 26463 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 44 115350 1174 0 0
srv_make_money_saldo 691 691 0 0.00 0 15626 60 0 0
srv_recalc_idx_stat 4 4 0 0.00 171422 557961 367597 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4792 4792 0 0.00 0 12655 166 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 1 2900 81 0 0
t$perf-norm:sp_cancel_client_order 1717 1717 0 0.00 0 1598 12 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2795 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 1638 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 2920 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 1959 10 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 22366 1355 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 715 4 0 0
t$perf-norm:sp_client_order 19508 19508 0 0.00 0 23069 18 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 932 4 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 8126 9 0 0
t$perf-norm:sp_pay_to_supplier 5625 5625 0 0.00 0 8744 10 0 0
t$perf-norm:sp_reserve_write_off 9737 9737 0 0.00 0 6234 10 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 3687 15 0 0
t$perf-norm:sp_supplier_order 5279 5279 0 0.00 0 4593 12 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 24 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 672 19 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 854 12 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 1018 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9804 9574 230 2.34 0 6163 24 0 230
v_all_customers 35964 35964 0 0.00 0 12 0 0 0
v_all_suppliers 30018 30018 0 0.00 0 12 1 0 0
v_all_wares 39014 39014 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 1342 6 0 0
v_cancel_client_order 3434 3434 0 0.00 0 3735 7 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 2420 6 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 4903 18 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 2387 9 0 0
v_cancel_supplier_invoice 800 772 28 3.50 0 8193 32 0 28
v_cancel_supplier_order 894 894 0 0.00 0 321 3 0 0
v_cancel_write_off 2038 2038 0 0.00 0 4578 17 0 0
v_max_id_clo_ord 5279 5279 0 0.00 0 784 1 0 0
v_max_id_clo_res 994 994 0 0.00 0 2063 15 0 0
v_max_id_ord_sup 5368 5368 0 0.00 0 8590 7 0 0
v_max_non_paid_invoice 1263 1263 0 0.00 0 4491 6 0 0
v_max_non_paid_realizn 3473 3473 0 0.00 0 1414 3 0 0
v_min_id_clo_ord 5279 5279 0 0.00 0 2872 5 0 0
v_min_id_clo_res 994 994 0 0.00 0 2655 8 0 0
v_min_id_ord_sup 5368 5368 0 0.00 0 5794 7 0 0
v_min_non_paid_invoice 5625 5625 0 0.00 0 14395 33 0 0
v_min_non_paid_realizn 6889 6889 0 0.00 0 15165 26 0 0
v_our_firm 9882 9882 0 0.00 0 21 1 0 0
v_reserve_write_off 19474 19474 0 0.00 0 8223 11 0 0
x_lock_depdocs_on_canc_invoice 12382 12382 0 0.00 0 18021 111 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 10071 331 0 0
x_make_qstorno_1000_1200 5279 5279 0 0.00 1 23722 1188 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 2 108043 3279 0 0
x_make_qstorno_2100_3300 447114 447114 0 0.00 0 55533 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2020 19:27:57 to 31-10-2020 19:28:01.

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
16:57:00 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:01 27518 8192000000 10378507872 10704216064 1426561488 14.831 100 90 179 1 1461640000 6200000 28878512
16:59:36 19813 8192000000 10461372752 10797236224 1512521088 15.585 100 92 184 1 1550188464 8506368 29161008
17:01:07 15047 8192000000 10506499008 10853539840 1564271472 16.033 100 80 160 0 1598874688 10086208 24517008
17:02:32 12274 8192000000 10577150368 10937257984 1637512384 16.659 100 79 158 1 1673192336 12429584 23250368
17:03:48 7333 8192000000 10628126128 10998820864 1693804960 17.133 100 80 160 2 1729539616 12561216 23173440
17:04:58 6071 8192000000 10696934912 11073552384 1756067584 17.652 100 79 158 1 1791095856 13061344 21966928
17:06:07 2873 8192000000 10748957088 11136032768 1814630816 18.134 100 88 176 0 1853027936 13711248 24685872
17:07:12 1785 8192000000 10791362976 11177132032 1849115360 18.415 100 93 185 0 1888215264 13222656 25877248
17:08:16 1977 8192000000 10812212384 11200307200 1869752048 18.582 100 88 175 1 1907139568 11456784 25930736
17:09:19 2232 8192000000 10802523632 11202850816 1870118944 18.585 100 72 145 0 1901377600 11389904 19868752
17:10:24 3398 8192000000 10794809264 11208945664 1877353632 18.644 100 50 101 1 1901976032 9710816 14911584
17:11:31 5353 8192000000 10825683888 11229663232 1896634448 18.799 100 43 87 1 1916204736 6785728 12784560
17:12:36 43938 8192000000 10807446336 11238285312 1894030576 18.778 100 31 62 1 1909045232 5839408 9175248
17:14:23 5429 8192000000 10835414144 11259195392 1906120112 18.875 100 27 54 0 1921015040 7313840 7581088
17:15:30 6381 8192000000 10827312224 11258761216 1890360480 18.749 99 26 52 0 1908930240 10573248 7996512
17:16:39 58533 8192000000 10822466368 11254988800 1901357328 18.837 100 32 62 2 1917532512 6507488 9667696
17:18:42 6152 8192000000 10729185792 11146158080 1782227552 17.868 96 25 49 1 1795126352 5014688 7884112
17:19:54 8894 8192000000 10682319424 11105013760 1748716288 17.591 97 23 46 2 1760086880 5070288 6300304
17:21:04 5663 8192000000 10684304320 11090923520 1751132560 17.611 98 32 64 1 1765816128 5180944 9502624
17:22:11 35489 8192000000 10712816336 11128238080 1725349312 17.397 95 22 44 2 1736963504 5281568 6332624
17:23:47 6676 8192000000 10739295168 11164561408 1826935600 18.234 100 26 50 3 1839047312 4575680 7536032
17:24:55 8423 8192000000 10770827408 11187064832 1849996896 18.422 100 39 78 3 1867776656 5403632 12376128
17:26:05 96752 8192000000 10799064912 11224977408 1865181856 18.545 100 27 54 1 1880294704 5698384 9414464
17:28:50 19196 8192000000 10857065984 11278594048 1917095152 18.964 100 42 84 0 1941769168 13045120 11628896
17:30:13 18547 8192000000 10883924480 11317997568 1946864768 19.201 100 44 86 0 1978440752 19366768 12209216
17:31:35 10323 8192000000 10898175808 11326377984 1958569120 19.295 100 56 112 3 1994781072 19267520 16944432
17:32:56 17896 8192000000 10920148608 11353362432 1972518928 19.405 100 51 102 1 2014182896 26368000 15295968
17:34:20 9644 8192000000 10935932960 11369345024 1988026704 19.528 100 43 86 1 2024743040 23985968 12730368
17:35:35 5909 8192000000 10968052368 11396120576 2009183856 19.695 100 67 135 1 2058741040 29564256 19992928
17:36:43 2944 8192000000 10978207040 11404627968 2013902576 19.732 100 69 137 6 2067322272 32410512 21009184
17:37:51 5534 8192000000 10969020304 11387121664 1984282448 19.499 99 69 138 1 2040855984 35239344 21334192
17:39:04 6014 8192000000 10985155232 11414560768 2012384368 19.720 100 78 156 1 2076431488 40181968 23865152
17:40:18 12428 8192000000 10976432448 11411959808 2010304096 19.704 100 69 137 1 2071277024 39638336 21334592
17:41:39 9209 8192000000 10990515392 11426742272 1993335792 19.570 99 55 108 2 2062388544 51255616 17797136
17:42:55 4130 8192000000 10979751264 11404423168 1963852288 19.337 97 66 130 1 2043445632 58476608 21116736
17:44:05 8515 8192000000 10986263552 11431276544 2014800384 19.739 100 40 80 1 2081837760 53673888 13363488
17:45:17 15501 8192000000 10934722752 11383132160 1933700752 19.096 98 35 69 3 2005763680 59749840 12313088
17:46:37 23024 8192000000 10930631280 11386236928 1955335408 19.269 99 36 72 1 2013154320 45691312 12127600
17:48:11 19898 8192000000 10875863248 11326500864 1879784016 18.663 97 22 44 2 1925460688 37387696 8288976
17:49:56 37850 8192000000 10853574064 11316793344 1845639472 18.387 96 21 42 1 1890805072 37605872 7559728
17:51:40 65078 8192000000 10878830192 11337113600 1946864832 19.202 100 39 78 4 1974169840 15727568 11577440
17:54:32 25882 8192000000 10872827152 11329544192 1941362448 19.158 100 31 63 1 1960498592 10527920 8608224
17:55:59 54586 8192000000 10847636688 11312201728 1867339856 18.563 96 8 16 0 1881207376 11541024 2326496
17:58:01 69084 8192000000 10852448800 11309936640 1903178016 18.852 97 26 53 0 1922042624 11374256 7490352
18:00:13 79785 8192000000 10873686768 11326304256 1907053552 18.883 98 19 37 0 1924977920 12207920 5716448
18:02:40 68434 8192000000 10858978400 11322900480 1936489408 19.119 100 25 49 1 1954887472 11248032 7150032
18:04:51 54561 8192000000 10868212592 11334606848 1923272080 19.013 98 20 36 1 1937346240 9043520 5030640
18:06:57 32562 8192000000 10863776624 11324157952 1935416272 19.110 99 31 62 2 1950485840 5993792 9075776
18:08:31 85095 8192000000 10894100848 11350560768 1920254096 18.989 98 20 40 0 1938209904 12197136 5758672
18:11:04 47915 8192000000 10914089344 11381600256 1975036960 19.425 100 22 44 0 2002467472 21065248 6365264
18:13:26 144944 8192000000 10915369808 11379113984 1917328720 18.965 97 9 18 0 1942141952 22466960 2346272
18:16:51 45627 8192000000 10935650320 11397595136 1993686528 19.573 100 34 68 3 2022134832 18463472 9984832
18:19:26 22960 8192000000 10934105552 11387473920 1999560112 19.619 100 29 58 0 2017891280 10588416 7742752
18:20:54 92907 8192000000 10882245760 11358187520 1933231264 19.093 97 18 36 3 1946595584 8268608 5095712
18:23:27 60270 8192000000 10918838480 11393626112 1994773344 19.581 100 24 48 1 2012577504 10877152 6927008
18:25:32 43340 8192000000 10903767984 11383595008 1974601232 19.422 99 32 64 1 1993749072 9857856 9289984
18:27:19 107415 8192000000 10930864160 11408027648 1989961840 19.543 99 20 40 0 2008509104 12461136 6086128
18:30:08 40851 8192000000 10909072064 11366326272 1942351232 19.166 97 24 48 1 1961076480 11892080 6833168
18:31:50 84531 8192000000 10913038656 11395305472 1928425648 19.054 97 15 31 1 1948931376 16055280 4450448
18:34:51 80881 8192000000 10900391472 11377950720 1878586000 18.654 94 20 40 1 1896845488 13004672 5254816
18:37:23 79940 8192000000 10878826688 11336896512 1866150864 18.553 95 18 34 1 1880348672 9182160 5015648
18:39:44 43445 8192000000 10885021456 11324260352 1884909936 18.705 96 49 98 4 1916984832 18274624 13800272
18:41:37 32681 8192000000 10872609552 11341058048 1921176432 18.996 99 13 26 2 1944098096 18714816 4206848
18:43:13 70801 8192000000 10881930976 11316125696 1853283680 18.449 95 29 58 1 1873936768 11981424 8671664
18:45:27 121788 8192000000 10860556352 11322744832 1859665408 18.501 96 25 50 1 1874691968 7938448 7088112
18:48:30 56532 8192000000 10860232544 11343638528 1926398640 19.038 99 20 40 2 1950578992 18757008 5423344
18:50:33 75043 8192000000 10847302880 11324014592 1856901152 18.478 96 12 24 1 1877475152 17069664 3504336
18:52:50 54370 8192000000 10848922336 11317227520 1888005056 18.730 97 16 31 3 1913724816 20752800 4966960
18:55:57 92989 8192000000 10879888496 11339313152 1907160192 18.884 98 22 44 0 1928165584 14244592 6760800
18:58:36 53049 8192000000 10857646016 11326988288 1881031520 18.673 96 14 28 1 1896703312 11587328 4084464
19:00:45 45712 8192000000 10884573600 11339993088 1915732000 18.953 98 28 56 1 1933034512 8827200 8475312
19:02:39 121213 8192000000 10894975168 11365081088 1962053280 19.322 100 17 34 0 1977862224 10985520 4823424
19:05:48 146718 8192000000 10893176144 11362484224 1863981472 18.536 94 17 34 3 1881881280 13372768 4527040
19:09:16 108921 8192000000 10904726912 11363717120 1954003008 19.258 99 29 58 0 1972104784 9573488 8528288
19:12:05 52370 8192000000 10922330592 11382198272 1972417280 19.405 100 34 68 1 1998547088 16232016 9897792
19:13:58 104500 8192000000 10895792768 11379654656 1963978864 19.338 99 10 20 1 1983086128 16159776 2947488
19:16:43 78590 8192000000 10896572000 11368263680 1909052544 18.899 96 16 33 1 1927061216 13123408 4885264
19:19:02 59325 8192000000 10910049312 11379056640 1953986000 19.258 99 11 22 1 1973247920 16096352 3165568
19:21:02 50035 8192000000 10897648352 11362291712 1917091088 18.964 97 16 32 0 1934150704 12286448 4773168
19:22:57 56295 8192000000 10884306208 11359461376 1918028656 18.971 97 22 44 1 1938890000 13896592 6964752
19:24:54 85993 8192000000 10838126800 11304038400 1814943984 18.136 94 10 20 0 1831810416 14051984 2814448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 115 v_add_invoice_to_stock 335544517
user_exc 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-10-2020 19:28:18 to 31-10-2020 19:28:20.

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          230995
MON$OLDEST_ACTIVE               232099
MON$OLDEST_SNAPSHOT             232099
MON$NEXT_TRANSACTION            232099
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                       2006592
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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Oct 31 19:02:19 2020
:65:     Modifying procedure SP_HALT_ON_ERROR which is currently in use by active user requests
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Oct 31 19:26:59 2020
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sat Oct 31 19:27:03 2020
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Sat Oct 31 19:27:06 2020
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Sat Oct 31 19:27:06 2020
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:82: 
--- end of diff output ---

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

31.10.20 19:28:21. 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 19:28:23 No severe PSQL-related problems occured
31.10.20 19:28:24. End of report.