Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.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 17535784 kB
MemAvailable 31505660 kB
Buffers 0 kB
Cached 14138028 kB
SwapCached 3044 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-26 19:26:59
DTS_END                         2020-10-26 19:26:59.0920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/44086
TRN_ID                          247135
ATT_ID                          709
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: 9514.60 17337 1141752
customer order: creation 180.76 335 21691
customer order: refuse 15.33 288 1839
order to supplier: creation 46.60 1177 5592
order to supplier: removal 3.66 89968 439
invoice (draft): creation 47.25 2865 5670
invoice (draft): removal 53.31 688 6397
invoice accept: apply 42.76 16992 5131
invoice accept: cancel 48.87 669 5864
customer reserve: creation 3969.29 168 476315
customer reserve: removal 4812.25 54 577470
realization accept: apply 85.73 161 10287
realization accept: cancel 74.63 110 8956
payment from customer: creation 61.14 99 7337
payment from customer: removal 6.08 111 730
payment to supplier: creation 51.13 127 6135
payment to supplier: removal 3.52 55 422
service: total inventory turnovers 4.63 920 556
service: total monetary turnovers 6.91 23 829
service: aggregate perf. data 0.73 24332 88
service: refresh index statistics 0.03 207588 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-10-2020 19:27:29 to 26-10-2020 19:27:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2822
TEST_TIME 2 5053
TEST_TIME 3 5100
TEST_TIME 4 5620
TEST_TIME 5 5806
TEST_TIME 6 5911
TEST_TIME 7 5891
TEST_TIME 8 6052
TEST_TIME 9 6094
TEST_TIME 10 6499
TEST_TIME 11 6690
TEST_TIME 12 7450
TEST_TIME 13 8047
TEST_TIME 14 8363
TEST_TIME 15 8484
TEST_TIME 16 8786
TEST_TIME 17 8796
TEST_TIME 18 8836
TEST_TIME 19 9253
TEST_TIME 20 9546
TEST_TIME 21 10113
TEST_TIME 22 10311
TEST_TIME 23 10151
TEST_TIME 24 11045
TEST_TIME 25 11839
TEST_TIME 26 11664
TEST_TIME 27 11369
TEST_TIME 28 11213
TEST_TIME 29 10868
TEST_TIME 30 10983
TEST_TIME 31 10795
TEST_TIME 32 10624
TEST_TIME 33 10502
TEST_TIME 34 10345
TEST_TIME 35 10227
TEST_TIME 36 10238
TEST_TIME 37 10167
TEST_TIME 38 10053
TEST_TIME 39 9950
TEST_TIME 40 9937
TEST_TIME 41 9969
TEST_TIME 42 10160
TEST_TIME 43 10133
TEST_TIME 44 10151
TEST_TIME 45 10122
TEST_TIME 46 10092
TEST_TIME 47 10161
TEST_TIME 48 10244
TEST_TIME 49 10138
TEST_TIME 50 10173
TEST_TIME 51 10286
TEST_TIME 52 10323
TEST_TIME 53 10400
TEST_TIME 54 10314
TEST_TIME 55 10379
TEST_TIME 56 10218
TEST_TIME 57 10154
TEST_TIME 58 10059
TEST_TIME 59 10003
TEST_TIME 60 10003
TEST_TIME 61 9932
TEST_TIME 62 9888
TEST_TIME 63 9943
TEST_TIME 64 9896
TEST_TIME 65 9932
TEST_TIME 66 9913
TEST_TIME 67 9967
TEST_TIME 68 9904
TEST_TIME 69 9891
TEST_TIME 70 9941
TEST_TIME 71 10011
TEST_TIME 72 10020
TEST_TIME 73 10005
TEST_TIME 74 10046
TEST_TIME 75 10043
TEST_TIME 76 10087
TEST_TIME 77 10078
TEST_TIME 78 10065
TEST_TIME 79 10056
TEST_TIME 80 9970
TEST_TIME 81 9902
TEST_TIME 82 9849
TEST_TIME 83 9817
TEST_TIME 84 9757
TEST_TIME 85 9716
TEST_TIME 86 9657
TEST_TIME 87 9670
TEST_TIME 88 9679
TEST_TIME 89 9629
TEST_TIME 90 9695
TEST_TIME 91 9675
TEST_TIME 92 9629
TEST_TIME 93 9698
TEST_TIME 94 9681
TEST_TIME 95 9765
TEST_TIME 96 9742
TEST_TIME 97 9753
TEST_TIME 98 9730
TEST_TIME 99 9735
TEST_TIME 100 9767
TEST_TIME 101 9676
TEST_TIME 102 9618
TEST_TIME 103 9584
TEST_TIME 104 9595
TEST_TIME 105 9552
TEST_TIME 106 9506
TEST_TIME 107 9488
TEST_TIME 108 9421
TEST_TIME 109 9482
TEST_TIME 110 9437
TEST_TIME 111 9476
TEST_TIME 112 9436
TEST_TIME 113 9486
TEST_TIME 114 9495
TEST_TIME 115 9481
TEST_TIME 116 9509
TEST_TIME 117 9489
TEST_TIME 118 9552
TEST_TIME 119 9539
TEST_TIME 120 9517
TEST_TIME 121 9438

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2020 19:27:33 to 26-10-2020 19:27:36.

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 1583319 1583319 0 0.00 0 21857 6 0 0
doc_list_biud 1583316 1583316 0 0.00 0 22040 25 0 0
sp_add_invoice_to_stock 5263 5131 132 2.50 43 178158 16992 0 132
sp_cancel_adding_invoice 5864 5864 0 0.00 5 99130 669 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 14577 289 0 0
sp_cancel_customer_reserve 577470 577470 0 0.00 1 22044 55 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 2 21744 111 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 2 1596 56 0 0
sp_cancel_supplier_invoice 6409 6397 12 0.18 2 15747 688 0 12
sp_cancel_supplier_order 439 439 0 0.00 5 859700 89969 0 0
sp_cancel_write_off 8956 8956 0 0.00 1 11464 110 0 0
sp_client_order 21691 21691 0 0.00 3 47817 335 0 0
sp_customer_reserve 476315 476315 0 0.00 0 58945 169 0 0
sp_fill_shopping_cart 32952 32952 0 0.00 1 8821 35 0 0
sp_fill_shopping_cart_clo_res 476308 476308 0 0.00 0 22269 12 0 0
sp_get_clo_for_invoice 5124 5124 0 0.00 0 19682 298 0 0
sp_kill_cost_storno 15972 15972 0 0.00 0 9303 49 0 0
sp_kill_qstorno_ret_qs2qd 1170732 1170732 0 0.00 0 22038 29 0 0
sp_kill_qty_storno 616376 616376 0 0.00 0 22039 63 0 0
sp_lock_selected_doc 21415 21415 0 0.00 0 9466 6 0 0
sp_make_cost_storno 28883 28883 0 0.00 0 21818 48 0 0
sp_multiply_rows_for_pdistr 28882 28882 0 0.00 0 8720 22 0 0
sp_multiply_rows_for_qdistr 21691 21691 0 0.00 0 47524 157 0 0
sp_pay_from_customer 7337 7337 0 0.00 4 16742 100 0 0
sp_pay_to_supplier 6135 6135 0 0.00 5 22487 128 0 0
sp_payment_common 13471 13471 0 0.00 1 16720 26 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 13747 251 0 0
sp_qd_handle_on_invoice_upd_sts 10988 10988 0 0.00 0 14540 263 0 0
sp_qd_handle_on_reserve_upd_sts 10287 10287 0 0.00 0 10724 49 0 0
sp_reserve_write_off 10287 10287 0 0.00 6 22143 161 0 0
sp_supplier_invoice 5670 5670 0 0.00 6 65679 2866 0 0
sp_supplier_order 5592 5592 0 0.00 5 33832 1178 0 0
srv_aggregate_perf_data 88 88 0 0.00 3201 444606 24333 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1160 152084 51740 0 0
srv_find_qd_qs_mism 1092538 1092538 0 0.00 0 18982 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 37 39838 921 0 0
srv_make_money_saldo 829 829 0 0.00 1 3815 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 114399 380616 207588 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5131 5131 0 0.00 0 12620 139 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 2877 71 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 4977 11 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 11098 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 4005 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 11699 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1468 9 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 33379 1354 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 3876 10 0 0
t$perf-norm:sp_client_order 21691 21691 0 0.00 0 9817 13 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 872 3 0 0
t$perf-norm:sp_pay_from_customer 7337 7337 0 0.00 0 5121 8 0 0
t$perf-norm:sp_pay_to_supplier 6135 6135 0 0.00 0 5982 8 0 0
t$perf-norm:sp_reserve_write_off 10287 10287 0 0.00 0 6585 9 0 0
t$perf-norm:sp_supplier_invoice 5670 5670 0 0.00 0 10740 24 0 0
t$perf-norm:sp_supplier_order 5592 5592 0 0.00 0 12647 19 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 85 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 2113 20 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 1531 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10512 10248 264 2.51 0 8481 19 0 264
v_all_customers 39208 39208 0 0.00 0 17 0 0 0
v_all_suppliers 31168 31168 0 0.00 0 171 1 0 0
v_all_wares 43382 43382 0 0.00 0 137 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 4131 10 0 0
v_cancel_client_order 3678 3678 0 0.00 0 14110 9 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 21552 31 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 14660 14 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 533 3 0 0
v_cancel_supplier_invoice 832 808 24 2.88 0 3762 18 0 24
v_cancel_supplier_order 878 878 0 0.00 0 1619 8 0 0
v_cancel_write_off 2066 2066 0 0.00 0 2515 12 0 0
v_max_id_clo_ord 5591 5591 0 0.00 0 354 1 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 1742 10 0 0
v_max_id_ord_sup 5669 5669 0 0.00 0 6084 4 0 0
v_max_non_paid_invoice 1811 1811 0 0.00 0 5078 5 0 0
v_max_non_paid_realizn 3962 3962 0 0.00 0 4055 3 0 0
v_min_id_clo_ord 5591 5591 0 0.00 0 4682 7 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 4432 10 0 0
v_min_id_ord_sup 5669 5669 0 0.00 0 7954 10 0 0
v_min_non_paid_invoice 6135 6135 0 0.00 0 4888 12 0 0
v_min_non_paid_realizn 7336 7336 0 0.00 0 7686 14 0 0
v_our_firm 10922 10922 0 0.00 0 16 1 0 0
v_reserve_write_off 20574 20574 0 0.00 0 21621 8 0 0
x_lock_depdocs_on_canc_invoice 11857 11857 0 0.00 0 26753 112 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 10811 278 0 0
x_make_qstorno_1000_1200 5592 5592 0 0.00 1 31955 1115 0 0
x_make_qstorno_1200_2000 5670 5670 0 0.00 3 64845 2736 0 0
x_make_qstorno_2100_3300 476315 476315 0 0.00 0 58945 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2020 19:27:37 to 26-10-2020 19:27:41.

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:02 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:04 26184 8192000000 10364040400 10686164992 1421447344 14.786 100 90 180 0 1457039040 6105744 29485952
16:59:36 11744 8192000000 10426138448 10755514368 1480399248 15.305 100 84 168 0 1515018624 7923248 26696128
17:00:54 23084 8192000000 10474361184 10815479808 1528602176 15.725 100 83 166 0 1563626448 9633968 25390304
17:02:21 12619 8192000000 10593388880 10952392704 1640179248 16.681 100 81 162 1 1676226896 11980560 24067088
17:03:39 10578 8192000000 10653747408 11025207296 1708974960 17.260 100 84 168 1 1746083904 13053952 24054992
17:04:54 3242 8192000000 10716472656 11094634496 1776965152 17.824 100 83 164 2 1812839744 12374720 23499872
17:05:59 2064 8192000000 10761067088 11140648960 1821663536 18.191 100 96 190 2 1864520912 15313184 27544192
17:07:03 1127 8192000000 10812131008 11205369856 1874085136 18.617 100 85 171 2 1909992416 11294016 24613264
17:08:05 6967 8192000000 10803787888 11211915264 1877452000 18.645 100 52 104 3 1901338832 9136640 14750192
17:09:12 2798 8192000000 10812151056 11222310912 1892230368 18.764 100 56 114 0 1914021216 5710768 16080080
17:10:17 10881 8192000000 10798587600 11232923648 1897149648 18.803 100 16 32 2 1907932432 6225888 4556896
17:11:28 44067 8192000000 10819238704 11253776384 1907684768 18.888 100 35 70 2 1924183952 5105440 11393744
17:13:15 6904 8192000000 10838485264 11263254528 1914093712 18.939 100 22 43 1 1926602592 6550816 5958064
17:14:23 11782 8192000000 10807846400 11239297024 1893120656 18.771 99 13 26 2 1903161056 5829872 4210528
17:15:35 14715 8192000000 10787033792 11213295616 1801047888 18.023 95 18 36 1 1812210288 5550304 5612096
17:16:51 11684 8192000000 10763490816 11199254528 1822313984 18.197 97 22 44 1 1834128768 5772704 6042080
17:18:10 10484 8192000000 10702079776 11119878144 1736728112 17.491 96 27 53 1 1749696992 5608272 7360608
17:19:24 9271 8192000000 10713670688 11126128640 1765714336 17.732 98 26 52 0 1779830480 5809280 8306864
17:20:36 32461 8192000000 10743355664 11170463744 1813955696 18.128 99 26 52 1 1827687440 5416704 8315040
17:22:28 5202 8192000000 10741604016 11162767360 1810696288 18.102 99 21 42 2 1820909472 4839888 5373296
17:23:34 12165 8192000000 10760512176 11192938496 1853329136 18.449 100 21 42 0 1865493840 5471072 6693632
17:24:48 14060 8192000000 10780107728 11217936384 1870615616 18.589 100 22 42 2 1883386528 5549168 7221744
17:26:05 65535 8192000000 10810616688 11238862848 1882316720 18.684 100 23 46 0 1893933920 4615488 7001712
17:28:16 16056 8192000000 10854196096 11278422016 1927158976 19.044 100 61 122 1 1955031024 10026880 17845168
17:29:41 12964 8192000000 10875200528 11306074112 1946976848 19.202 100 50 100 1 1972763776 11270288 14516640
17:30:57 15471 8192000000 10898787056 11330150400 1967717952 19.367 100 47 94 2 1993370560 11118112 14534496
17:32:17 8501 8192000000 10911255936 11345055744 1972447040 19.405 100 47 94 2 2003671008 16790496 14433472
17:33:26 26451 8192000000 10904202240 11347513344 1960504720 19.310 99 44 88 2 1994249024 20222048 13522256
17:34:55 11305 8192000000 10934482432 11369046016 1971969808 19.401 99 45 88 2 2006706496 21239376 13497312
17:36:11 7313 8192000000 10932120944 11359551488 1940456288 19.150 97 67 134 1 1984420720 23593328 20371104
17:37:22 4170 8192000000 10944477280 11373957120 1973101056 19.410 99 60 118 1 2021632688 29812224 18719408
17:38:33 1159 8192000000 10955695856 11365625856 1967133904 19.363 99 85 171 0 2028245104 35266752 25844448
17:39:37 2270 8192000000 10947551440 11362942976 1967862144 19.368 100 76 152 1 2030570544 38638752 24069648
17:40:43 1677 8192000000 10904687392 11323625472 1931399328 19.078 99 67 133 0 1983632320 31798368 20434624
17:41:49 4634 8192000000 10923133264 11342340096 1948761920 19.217 100 64 128 1 2007495408 38764736 19968752
17:42:59 2242 8192000000 10905404320 11326562304 1932632096 19.088 100 50 98 3 1986109408 38103104 15374208
17:44:04 11744 8192000000 10924001456 11358621696 1947609888 19.207 100 43 86 0 2012892032 50996016 14286128
17:45:21 10080 8192000000 10946389952 11386138624 1953802704 19.257 99 59 118 0 2032393952 59019008 19572240
17:46:41 20729 8192000000 10920861520 11371913216 1924084464 19.020 98 29 58 0 1991050944 56153952 10812528
17:48:03 39572 8192000000 10905000688 11366072320 1925904640 19.034 99 15 30 0 1975888176 44700704 5282832
17:49:43 39705 8192000000 10892610624 11353489408 1902223584 18.844 99 19 38 0 1969790112 60333024 7233504
17:51:23 52204 8192000000 10862107552 11330105344 1907775008 18.889 99 8 16 0 1933362112 23086208 2500896
17:53:19 70830 8192000000 10817679712 11277471744 1858040192 18.487 97 17 34 2 1872349088 9915552 4393344
17:55:30 152084 8192000000 10862551200 11335303168 1888209184 18.731 98 14 28 0 1903053904 10756816 4087904
17:59:08 75733 8192000000 10868855120 11334561792 1922585776 19.008 98 20 40 0 1938939408 10685456 5668176
18:01:35 10173 8192000000 10886759248 11349811200 1956955056 19.282 100 18 36 0 1973826928 11791056 5080816
18:02:47 23714 8192000000 10893106592 11352809472 1961368432 19.317 100 38 75 2 1983467200 10745744 11353024
18:04:13 122885 8192000000 10913392096 11383414784 1962690896 19.327 99 20 40 0 1985891552 17200128 6000528
18:07:29 66164 8192000000 10910980656 11387682816 1980906512 19.472 100 15 30 1 2002209392 17238640 4064240
18:09:49 48235 8192000000 10915110032 11381030912 1990145408 19.545 100 40 79 3 2010648000 9149008 11353584
18:11:46 56475 8192000000 10928369744 11406712832 1982907136 19.488 99 15 30 3 1997864528 11041904 3915488
18:13:43 81757 8192000000 10944288640 11407519744 1986318160 19.515 99 10 20 0 1999348528 10353200 2677168
18:16:22 120482 8192000000 10924477520 11395727360 1907446464 18.886 95 21 42 0 1933167696 19908048 5813184
18:19:24 124823 8192000000 10882139888 11368353792 1859076400 18.496 94 12 24 1 1878781312 16161200 3543712
18:22:29 64765 8192000000 10845287296 11302756352 1908885360 18.898 98 29 58 0 1923397616 6299824 8212432
18:24:35 69924 8192000000 10871472752 11341443072 1914823744 18.945 98 20 39 0 1932033712 11246240 5963728
18:26:52 53351 8192000000 10890360752 11375771648 1963162528 19.331 100 12 24 1 1980950256 14548272 3239456
18:28:45 57318 8192000000 10875994048 11341271040 1928663248 19.056 99 19 38 2 1949101968 14954016 5484704
18:30:45 69975 8192000000 10842978064 11323514880 1925541648 19.031 100 11 22 0 1939489312 10637360 3310304
18:33:02 73312 8192000000 10869788384 11331633152 1890068640 18.746 97 12 24 1 1901551968 8005424 3477904
18:35:22 41575 8192000000 10857960608 11315122176 1900544128 18.831 98 40 80 0 1925160080 12887536 11728416
18:37:05 67886 8192000000 10867710688 11330404352 1904716736 18.864 99 9 18 0 1919293840 12560592 2016512
18:39:13 20072 8192000000 10878444144 11335663616 1929416720 19.062 99 35 70 2 1949458512 10705856 9335936
18:40:41 25170 8192000000 10880934288 11340361728 1934873984 19.106 100 40 80 1 1961319168 15240960 11204224
18:42:14 87120 8192000000 10872542832 11334504448 1854443664 18.458 95 22 44 0 1873101280 12339040 6318576
18:44:42 12837 8192000000 10908337856 11356700672 1965030144 19.346 100 44 86 0 1986348384 9503712 11814528
18:46:04 103506 8192000000 10883617712 11353587712 1961708608 19.320 100 24 48 0 1982300944 13805376 6786960
18:48:51 51183 8192000000 10830840672 11303669760 1875476336 18.629 97 12 24 0 1889925360 11332736 3116288
18:50:50 59788 8192000000 10865286416 11337248768 1943532112 19.175 100 9 18 0 1954407216 7852640 3022464
18:52:50 62267 8192000000 10858070304 11335446528 1928545328 19.055 99 19 38 0 1947570240 13439520 5585392
18:54:54 69695 8192000000 10882780528 11358314496 1956159168 19.276 100 16 32 0 1971517504 10141680 5216656
18:57:08 87235 8192000000 10920054576 11378036736 1947633520 19.208 98 22 44 1 1962732288 9116928 5981840
18:59:48 67059 8192000000 10932563728 11395878912 1971797312 19.400 99 15 30 0 1992440944 16075472 4568160
19:01:55 87504 8192000000 10914269920 11369222144 1913768560 18.937 96 28 54 1 1932574112 11818816 6986736
19:04:28 23114 8192000000 10908852544 11354652672 1950482912 19.230 99 49 95 0 1974060288 9921328 13656048
19:05:53 135049 8192000000 10892201808 11364073472 1924355440 19.022 97 12 24 0 1937666784 10470336 2841008
19:09:08 32539 8192000000 10914100096 11386269696 1981828576 19.479 100 17 34 0 2000071904 13559344 4683984
19:10:42 34397 8192000000 10864671664 11341553664 1903635056 18.856 96 10 21 1 1911733920 5567744 2531120
19:12:24 66919 8192000000 10886421856 11359768576 1920371312 18.990 97 14 29 1 1935346208 10573904 4400992
19:14:32 101757 8192000000 10882362736 11346415616 1896581760 18.799 96 11 22 0 1916154960 16694384 2878816
19:17:15 53984 8192000000 10859816544 11327705088 1853779792 18.453 95 16 32 1 1868577248 9854928 4942528
19:19:09 100907 8192000000 10859871312 11326857216 1856841808 18.478 95 19 39 1 1874010912 11726144 5442960
19:22:03 61617 8192000000 10856769088 11304771584 1838197984 18.326 95 27 55 0 1854680016 9443904 7038128
19:24:08 55076 8192000000 10845941008 11330490368 1913922384 18.938 99 12 24 2 1934614320 16863248 3828688
19:26:05 55536 8192000000 10511519248 10931761152 1547920160 15.892 80 24 48 0 1563432384 8658448 6853776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2020 19:27:43 to 26-10-2020 19:27:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 132 v_add_invoice_to_stock 335544517
user_exc 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2020 19:27:59 to 26-10-2020 19:28:01.

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          247415
MON$OLDEST_ACTIVE               247416
MON$OLDEST_SNAPSHOT             247416
MON$NEXT_TRANSACTION            247416
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                       2018624
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.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:127: 
:128: mscs-test-161.budzdorov.ru    Mon Oct 26 19:27:06 2020
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:130: 
--- end of diff output ---

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

26.10.20 19:28:02. 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-26 19:28:04 No severe PSQL-related problems occured
26.10.20 19:28:05. End of report.