Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2020 19: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 17761800 kB
MemAvailable 31641352 kB
Buffers 0 kB
Cached 13959780 kB
SwapCached 5448 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-12 19:26:54
DTS_END                         2020-10-12 19:26:54.8920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/44082
TRN_ID                          250396
ATT_ID                          704
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: 9308.07 16745 1116968
customer order: creation 182.82 373 21938
customer order: refuse 15.60 256 1872
order to supplier: creation 49.48 1223 5937
order to supplier: removal 3.53 75653 423
invoice (draft): creation 48.33 3086 5800
invoice (draft): removal 52.35 656 6282
invoice accept: apply 43.80 16073 5256
invoice accept: cancel 47.43 749 5692
customer reserve: creation 3936.00 163 472320
customer reserve: removal 4613.33 46 553599
realization accept: apply 88.87 164 10664
realization accept: cancel 88.51 96 10621
payment from customer: creation 63.31 117 7597
payment from customer: removal 5.94 59 713
payment to supplier: creation 52.52 120 6302
payment to supplier: removal 3.53 83 424
service: total inventory turnovers 5.17 972 620
service: total monetary turnovers 6.62 25 794
service: aggregate perf. data 0.92 17206 110
service: refresh index statistics 0.03 217774 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2020 19:28:46 to 12-10-2020 19:28:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2993
TEST_TIME 2 4033
TEST_TIME 3 5312
TEST_TIME 4 6526
TEST_TIME 5 6289
TEST_TIME 6 5886
TEST_TIME 7 5914
TEST_TIME 8 6482
TEST_TIME 9 6625
TEST_TIME 10 7027
TEST_TIME 11 7622
TEST_TIME 12 7747
TEST_TIME 13 8475
TEST_TIME 14 9471
TEST_TIME 15 9284
TEST_TIME 16 9916
TEST_TIME 17 10280
TEST_TIME 18 11068
TEST_TIME 19 11431
TEST_TIME 20 11627
TEST_TIME 21 11327
TEST_TIME 22 11512
TEST_TIME 23 11655
TEST_TIME 24 11389
TEST_TIME 25 11399
TEST_TIME 26 11020
TEST_TIME 27 10794
TEST_TIME 28 10535
TEST_TIME 29 10379
TEST_TIME 30 10251
TEST_TIME 31 10284
TEST_TIME 32 10286
TEST_TIME 33 10124
TEST_TIME 34 10119
TEST_TIME 35 10097
TEST_TIME 36 10203
TEST_TIME 37 10140
TEST_TIME 38 10145
TEST_TIME 39 10142
TEST_TIME 40 10059
TEST_TIME 41 10060
TEST_TIME 42 10037
TEST_TIME 43 10207
TEST_TIME 44 10227
TEST_TIME 45 10248
TEST_TIME 46 10306
TEST_TIME 47 10300
TEST_TIME 48 10449
TEST_TIME 49 10298
TEST_TIME 50 10141
TEST_TIME 51 10012
TEST_TIME 52 9998
TEST_TIME 53 9924
TEST_TIME 54 9926
TEST_TIME 55 9837
TEST_TIME 56 9796
TEST_TIME 57 9693
TEST_TIME 58 9743
TEST_TIME 59 9795
TEST_TIME 60 9771
TEST_TIME 61 9828
TEST_TIME 62 9773
TEST_TIME 63 9881
TEST_TIME 64 9867
TEST_TIME 65 10018
TEST_TIME 66 10061
TEST_TIME 67 10090
TEST_TIME 68 10099
TEST_TIME 69 10035
TEST_TIME 70 10043
TEST_TIME 71 10080
TEST_TIME 72 9996
TEST_TIME 73 9898
TEST_TIME 74 9876
TEST_TIME 75 9834
TEST_TIME 76 9761
TEST_TIME 77 9675
TEST_TIME 78 9685
TEST_TIME 79 9708
TEST_TIME 80 9714
TEST_TIME 81 9679
TEST_TIME 82 9711
TEST_TIME 83 9696
TEST_TIME 84 9661
TEST_TIME 85 9739
TEST_TIME 86 9800
TEST_TIME 87 9746
TEST_TIME 88 9751
TEST_TIME 89 9698
TEST_TIME 90 9827
TEST_TIME 91 9804
TEST_TIME 92 9778
TEST_TIME 93 9701
TEST_TIME 94 9693
TEST_TIME 95 9655
TEST_TIME 96 9599
TEST_TIME 97 9542
TEST_TIME 98 9525
TEST_TIME 99 9488
TEST_TIME 100 9472
TEST_TIME 101 9435
TEST_TIME 102 9393
TEST_TIME 103 9414
TEST_TIME 104 9418
TEST_TIME 105 9412
TEST_TIME 106 9473
TEST_TIME 107 9582
TEST_TIME 108 9582
TEST_TIME 109 9553
TEST_TIME 110 9543
TEST_TIME 111 9542
TEST_TIME 112 9537
TEST_TIME 113 9540
TEST_TIME 114 9490
TEST_TIME 115 9452
TEST_TIME 116 9408
TEST_TIME 117 9391
TEST_TIME 118 9361
TEST_TIME 119 9361
TEST_TIME 120 9309

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-10-2020 19:28:49 to 12-10-2020 19:28:53.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1555753 1555753 0 0.00 0 15246 6 0 0
doc_list_biud 1555753 1555753 0 0.00 0 22114 21 0 0
sp_add_invoice_to_stock 5427 5256 171 3.15 39 169813 16074 0 171
sp_cancel_adding_invoice 5692 5692 0 0.00 6 53125 749 0 0
sp_cancel_client_order 1872 1872 0 0.00 5 10338 257 0 0
sp_cancel_customer_reserve 553599 553599 0 0.00 1 22116 46 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 4238 59 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 2 5649 84 0 0
sp_cancel_supplier_invoice 6296 6282 14 0.22 1 12987 656 0 14
sp_cancel_supplier_order 423 423 0 0.00 5 831527 75654 0 0
sp_cancel_write_off 10621 10621 0 0.00 1 15924 96 0 0
sp_client_order 21938 21938 0 0.00 4 26903 373 0 0
sp_customer_reserve 472320 472320 0 0.00 0 43846 164 0 0
sp_fill_shopping_cart 33660 33660 0 0.00 1 17057 40 0 0
sp_fill_shopping_cart_clo_res 472318 472318 0 0.00 0 12199 13 0 0
sp_get_clo_for_invoice 5252 5252 0 0.00 0 25138 297 0 0
sp_kill_cost_storno 17450 17450 0 0.00 0 7487 42 0 0
sp_kill_qstorno_ret_qs2qd 1124524 1124524 0 0.00 0 22103 25 0 0
sp_kill_qty_storno 594404 594404 0 0.00 0 22113 55 0 0
sp_lock_selected_doc 22029 22029 0 0.00 0 8075 7 0 0
sp_make_cost_storno 29814 29814 0 0.00 0 15245 55 0 0
sp_multiply_rows_for_pdistr 29814 29814 0 0.00 0 8983 21 0 0
sp_multiply_rows_for_qdistr 21938 21938 0 0.00 0 14991 169 0 0
sp_pay_from_customer 7597 7597 0 0.00 4 17065 118 0 0
sp_pay_to_supplier 6302 6302 0 0.00 4 15721 121 0 0
sp_payment_common 13899 13899 0 0.00 2 12596 33 0 0
sp_qd_handle_on_cancel_clo 1872 1872 0 0.00 0 10332 228 0 0
sp_qd_handle_on_invoice_upd_sts 10943 10943 0 0.00 0 16693 261 0 0
sp_qd_handle_on_reserve_upd_sts 10664 10664 0 0.00 0 13431 57 0 0
sp_reserve_write_off 10664 10664 0 0.00 6 17170 165 0 0
sp_supplier_invoice 5800 5800 0 0.00 6 83302 3086 0 0
sp_supplier_order 5937 5937 0 0.00 5 34687 1223 0 0
srv_aggregate_perf_data 110 110 0 0.00 2561 174029 17206 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1937 115163 51375 0 0
srv_find_qd_qs_mism 1067569 1067569 0 0.00 0 13492 5 0 0
srv_make_invnt_saldo 620 620 0 0.00 30 39808 973 0 0
srv_make_money_saldo 794 794 0 0.00 0 3496 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 108433 327031 217775 0 0
t$perf-abend:sp_add_invoice_to_stock 171 171 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 5256 5256 0 0.00 0 10534 118 0 0
t$perf-norm:sp_cancel_adding_invoice 474 474 0 0.00 0 5452 84 0 0
t$perf-norm:sp_cancel_client_order 1872 1872 0 0.00 0 4623 17 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 1927 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 518 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 296 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 3286 20 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 27671 1248 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 4584 13 0 0
t$perf-norm:sp_client_order 21938 21938 0 0.00 0 8269 12 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 1489 6 0 0
t$perf-norm:sp_pay_from_customer 7597 7597 0 0.00 0 3977 7 0 0
t$perf-norm:sp_pay_to_supplier 6302 6302 0 0.00 0 5817 7 0 0
t$perf-norm:sp_reserve_write_off 10664 10664 0 0.00 0 5616 8 0 0
t$perf-norm:sp_supplier_invoice 5800 5800 0 0.00 0 3939 13 0 0
t$perf-norm:sp_supplier_order 5937 5937 0 0.00 0 8336 13 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 2525 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 620 620 0 0.00 0 786 15 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 7595 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10844 10502 342 3.15 0 5118 22 0 342
v_all_customers 39924 39924 0 0.00 0 21 0 0 0
v_all_suppliers 32182 32182 0 0.00 0 29 1 0 0
v_all_wares 43876 43876 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 948 948 0 0.00 0 4068 15 0 0
v_cancel_client_order 3744 3744 0 0.00 0 2817 6 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 2598 5 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 1679 6 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2692 11 0 0
v_cancel_supplier_invoice 912 884 28 3.07 0 4901 22 0 28
v_cancel_supplier_order 846 846 0 0.00 0 1664 4 0 0
v_cancel_write_off 2114 2114 0 0.00 0 2960 17 0 0
v_max_id_clo_ord 5936 5936 0 0.00 0 2401 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 1263 5 0 0
v_max_id_ord_sup 5786 5786 0 0.00 0 4461 5 0 0
v_max_non_paid_invoice 1933 1933 0 0.00 0 5305 9 0 0
v_max_non_paid_realizn 4088 4088 0 0.00 0 3568 4 0 0
v_min_id_clo_ord 5936 5936 0 0.00 0 3577 10 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 6214 24 0 0
v_min_id_ord_sup 5786 5786 0 0.00 0 4368 6 0 0
v_min_non_paid_invoice 6302 6302 0 0.00 0 9618 19 0 0
v_min_non_paid_realizn 7597 7597 0 0.00 0 8143 20 0 0
v_our_firm 10970 10970 0 0.00 0 51 1 0 0
v_reserve_write_off 21328 21328 0 0.00 0 7666 7 0 0
x_lock_depdocs_on_canc_invoice 11532 11532 0 0.00 0 10283 98 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 9393 274 0 0
x_make_qstorno_1000_1200 5937 5937 0 0.00 2 34680 1150 0 0
x_make_qstorno_1200_2000 5800 5800 0 0.00 3 77229 2937 0 0
x_make_qstorno_2100_3300 472320 472320 0 0.00 0 43843 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2020 19:28:54 to 12-10-2020 19:28:58.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
16:56:58 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:57:59 15690 8192000000 10334894560 10649542656 1394654112 14.547 100 90 181 0 1429995600 5562896 29778592
16:59:24 12311 8192000000 10403037056 10729971712 1460855248 15.133 100 86 172 0 1496680192 7581536 28243408
17:00:41 14355 8192000000 10469947184 10810503168 1520161024 15.652 100 83 166 1 1556260816 9625600 26474192
17:01:57 15540 8192000000 10572368640 10929348608 1627133376 16.571 100 85 170 0 1665092480 12622848 25336256
17:03:16 9266 8192000000 10627702896 10997542912 1683078272 17.043 100 76 152 1 1718510848 13076288 22356288
17:04:28 4734 8192000000 10699093200 11078082560 1755671952 17.649 100 84 168 5 1792720016 12370240 24677824
17:05:34 2241 8192000000 10745810416 11129745408 1811423632 18.108 100 88 176 0 1848251552 12638528 24189392
17:06:38 2735 8192000000 10794699632 11186814976 1858900512 18.494 100 91 181 1 1898001568 13656608 25444448
17:07:42 25959 8192000000 10823707312 11226599424 1886017216 18.714 100 57 112 0 1909527104 7759632 15750256
17:09:08 6783 8192000000 10802731680 11229650944 1887089792 18.722 100 28 55 4 1903362480 8422848 7849840
17:10:16 8929 8192000000 10803277184 11238199296 1892014544 18.762 100 4 8 0 1898598992 5282720 1301728
17:11:31 9245 8192000000 10840539024 11257532416 1915615888 18.952 100 56 110 2 1935883408 5119936 15147584
17:12:49 4536 8192000000 10874653344 11283247104 1925794768 19.033 100 84 170 3 1957384368 6994000 24595600
17:13:58 5973 8192000000 10849132624 11281084416 1923998304 19.019 100 33 66 4 1941355744 7168992 10188448
17:15:07 8890 8192000000 10822036656 11265769472 1900600336 18.831 99 18 36 1 1911050256 5550144 4899776
17:16:16 20856 8192000000 10784456864 11214696448 1870148560 18.585 99 21 43 0 1881640976 5054912 6437504
17:18:17 7732 8192000000 10734378032 11151761408 1790583152 17.937 98 25 50 1 1803737120 6193920 6960048
17:19:25 5158 8192000000 10726822000 11142742016 1807498128 18.075 100 23 46 1 1821118960 5896816 7724016
17:20:32 14328 8192000000 10703972656 11122712576 1748011392 17.585 97 30 60 3 1761875440 5335440 8528608
17:21:47 38724 8192000000 10728650320 11150811136 1793401696 17.960 98 27 54 2 1806803776 5248672 8153408
17:23:26 6005 8192000000 10760235024 11190054912 1847784864 18.404 100 26 53 0 1862132016 6720832 7626320
17:24:32 6088 8192000000 10786092912 11211055104 1860523888 18.508 100 22 44 2 1876530992 9525760 6481344
17:25:39 13190 8192000000 10784324912 11217039360 1869931920 18.584 100 16 32 0 1879801712 5315280 4554512
17:26:54 31354 8192000000 10825749296 11253248000 1903010000 18.850 100 32 64 0 1917811520 5854240 8947280
17:28:31 12164 8192000000 10870489872 11292864512 1931851712 19.082 100 59 118 3 1965079024 15644112 17583200
17:29:48 4728 8192000000 10892864528 11313131520 1946153552 19.196 100 50 99 2 1978883584 17636816 15093216
17:30:57 32548 8192000000 10903231856 11350585344 1968284784 19.372 100 34 68 0 2001672544 22348064 11039696
17:32:39 12591 8192000000 10937726928 11366109184 1981194320 19.474 100 57 114 0 2027948416 30265696 16488400
17:33:56 15753 8192000000 10953899056 11382636544 1993706608 19.573 100 62 124 0 2046480064 33900240 18873216
17:35:18 18075 8192000000 10940034336 11367723008 1977365248 19.444 100 56 110 0 2029903408 35637856 16900304
17:36:44 1935 8192000000 10980697232 11394920448 1997682912 19.604 100 83 163 3 2060395888 38501136 24211840
17:37:48 2121 8192000000 10937996736 11353178112 1937794400 19.129 98 74 149 0 2002353120 41688160 22870560
17:38:52 3418 8192000000 10924662912 11332919296 1934566848 19.103 100 69 135 2 2002476512 47200304 20709360
17:40:06 3312 8192000000 10886834624 11331399680 1929096960 19.060 100 44 88 4 1984175088 41026368 14051760
17:41:13 13883 8192000000 10887399008 11317792768 1920188992 18.988 100 37 75 1 1968338016 35746992 12402032
17:42:29 25140 8192000000 10881347104 11334516736 1921041168 18.995 99 27 54 0 1968618560 38380432 9196960
17:43:56 16416 8192000000 10844092832 11287777280 1877538112 18.645 99 37 74 1 1921236192 31785920 11912160
17:45:13 19305 8192000000 10862480960 11308752896 1908719424 18.896 100 16 32 2 1941835872 27346336 5770112
17:46:34 45769 8192000000 10846579024 11278573568 1854748640 18.461 97 33 66 0 1882187216 17788960 9649616
17:48:25 23893 8192000000 10844727632 11291090944 1911533456 18.919 100 15 30 1 1928182576 12993904 3655216
17:50:09 19483 8192000000 10868542320 11298779136 1906194416 18.876 99 46 91 2 1929006480 9708080 13103984
17:51:32 115163 8192000000 10853802112 11310235648 1908192272 18.892 99 21 42 0 1923525472 9546528 5786672
17:54:28 34420 8192000000 10844044864 11288657920 1911251376 18.917 99 31 62 1 1926997760 6846000 8900384
17:56:05 37375 8192000000 10873016288 11330506752 1947994736 19.211 100 19 38 1 1964949104 11402928 5551440
17:57:44 45399 8192000000 10904106432 11356037120 1965640096 19.351 100 31 62 0 1991095296 16637392 8817808
17:59:31 59816 8192000000 10884514688 11351285760 1931777392 19.081 98 20 40 2 1953637680 16506912 5353376
18:01:32 66478 8192000000 10878066400 11345342464 1931749424 19.081 98 13 26 1 1947318096 11533056 4035616
18:03:39 96458 8192000000 10921504432 11370065920 1960739424 19.312 99 24 46 3 1978155728 10964368 6451936
18:06:15 72772 8192000000 10937367680 11385176064 1977720624 19.447 99 23 45 1 1995435664 10993136 6721904
18:08:31 87552 8192000000 10897820800 11367526400 1950279712 19.229 98 13 26 1 1973699040 19759520 3659808
18:10:58 97206 8192000000 10892611424 11353673728 1909505264 18.903 96 11 23 0 1928830592 16183376 3141952
18:13:43 78784 8192000000 10895228336 11375124480 1959511056 19.302 100 11 22 0 1980051136 17383792 3156288
18:16:02 95935 8192000000 10899022160 11353432064 1954484144 19.262 99 33 66 1 1972256912 8303088 9469680
18:18:38 60308 8192000000 10878308368 11350102016 1931809248 19.081 98 11 22 0 1942950560 8052672 3088640
18:20:40 55777 8192000000 10874386432 11346604032 1907072896 18.883 97 6 12 2 1923734864 14677264 1984704
18:22:36 54518 8192000000 10871083936 11345305600 1928815232 19.057 98 10 20 2 1941390576 9812480 2762864
18:24:51 70155 8192000000 10851370528 11319820288 1792749824 17.954 91 16 30 2 1804421488 7322672 4348992
18:27:09 68253 8192000000 10855486160 11319017472 1823247344 18.204 93 9 18 0 1834734032 9188448 2298240
18:29:17 57150 8192000000 10879773152 11339276288 1917811456 18.969 99 19 38 2 1937051920 13818672 5421792
18:31:19 57156 8192000000 10876468544 11342503936 1925123984 19.028 99 9 18 0 1945979024 18676304 2178736
18:33:16 104627 8192000000 10871977232 11331903488 1846131632 18.391 94 24 49 0 1871753312 19041776 6579904
18:36:15 27272 8192000000 10919347104 11365359616 1960442768 19.310 99 61 122 1 1990984384 12986048 17555568
18:37:43 36705 8192000000 10881877376 11323523072 1914484544 18.943 97 37 74 0 1932317824 7507712 10325568
18:39:20 72111 8192000000 10855348672 11319734272 1894895184 18.785 97 23 46 0 1911095776 8912352 7288240
18:41:48 58981 8192000000 10877870288 11348254720 1950311360 19.229 100 11 20 0 1968346928 15288064 2747504
18:43:47 61174 8192000000 10877614496 11342106624 1919046560 18.979 98 15 30 0 1940488592 16880336 4561696
18:45:48 62929 8192000000 10883199072 11351371776 1949764160 19.225 99 17 34 1 1971592464 17259648 4568656
18:48:39 37977 8192000000 10903618080 11357609984 1954831648 19.265 99 30 60 1 1974537120 11183984 8521488
18:50:27 49906 8192000000 10908684640 11366907904 1932104000 19.084 98 19 38 1 1952554416 14643888 5806528
18:53:02 64638 8192000000 10924975936 11379240960 1963849984 19.337 99 41 82 0 1986735280 10732528 12152768
18:55:42 87212 8192000000 10904715344 11372720128 1951293456 19.237 98 18 36 1 1974140112 18147760 4698896
18:58:10 102216 8192000000 10911491408 11368337408 1934145136 19.100 97 23 46 1 1950356112 10264288 5946688
19:01:04 25447 8192000000 10917764080 11387756544 1990703056 19.549 100 10 20 2 2004076768 10911952 2461760
19:02:30 64385 8192000000 10879711952 11353477120 1936339936 19.118 98 9 18 0 1945365728 6931920 2093872
19:04:40 63669 8192000000 10857850736 11327959040 1853827968 18.453 94 19 38 0 1867630096 8016640 5785488
19:06:56 77598 8192000000 10931426160 11385409536 1954203504 19.260 99 13 26 0 1970863360 13125600 3534256
19:09:17 44901 8192000000 10906105920 11376427008 1961944336 19.321 99 25 50 2 1994262144 24069264 8248544
19:11:07 92520 8192000000 10905960736 11383390208 1928584096 19.056 97 15 31 1 1956460768 23693088 4183584
19:13:41 97889 8192000000 10854804224 11309490176 1802176256 18.032 92 18 36 1 1817146528 9852704 5117568
19:16:19 37672 8192000000 10880967920 11332411392 1930532384 19.071 99 39 78 1 1951538512 10278112 10728016
19:17:56 52921 8192000000 10885568352 11330007040 1932625328 19.088 99 27 53 1 1952563168 12485776 7452064
19:19:50 91263 8192000000 10865895680 11316240384 1891380240 18.757 97 39 78 1 1911590768 9058560 11151968
19:22:23 40929 8192000000 10862123344 11326435328 1943356224 19.174 100 33 66 1 1962266224 9859152 9050848
19:24:10 75947 8192000000 10856903264 11344609280 1945952944 19.194 100 5 10 0 1958283264 10743056 1587264
19:26:27 41724 8192000000 9699532192 10047619072 772575216 8.618 40 8 16 0 787829664 12870496 2383952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 171 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 12-10-2020 19:29:15 to 12-10-2020 19:29:17.

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          249935
MON$OLDEST_ACTIVE               250760
MON$OLDEST_SNAPSHOT             250760
MON$NEXT_TRANSACTION            250760
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                       2003264
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.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
--- end of diff output ---

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

12.10.20 19:29:18. 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-12 19:29:20 No severe PSQL-related problems occured
12.10.20 19:29:22. End of report.