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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-14 07:26:51
DTS_END                         2020-10-14 07:26:51.1760
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(67:17)
IP                              ::1/44780
TRN_ID                          238784
ATT_ID                          643
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: 9001.37 19534 1080164
customer order: creation 167.89 387 20147
customer order: refuse 14.24 312 1709
order to supplier: creation 46.79 1388 5615
order to supplier: removal 3.49 105131 419
invoice (draft): creation 45.32 3680 5438
invoice (draft): removal 52.26 840 6271
invoice accept: apply 41.14 17012 4937
invoice accept: cancel 47.38 793 5685
customer reserve: creation 3704.68 170 444561
customer reserve: removal 4590.88 63 550906
realization accept: apply 82.43 171 9892
realization accept: cancel 78.06 127 9367
payment from customer: creation 58.74 127 7049
payment from customer: removal 5.44 62 653
payment to supplier: creation 48.29 142 5795
payment to supplier: removal 3.55 85 426
service: total inventory turnovers 3.93 3453 472
service: total monetary turnovers 5.95 612 714
service: aggregate perf. data 0.86 14733 103
service: refresh index statistics 0.04 241382 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2020 07:27:57 to 14-10-2020 07:27:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2759
TEST_TIME 2 4346
TEST_TIME 3 4372
TEST_TIME 4 4469
TEST_TIME 5 4696
TEST_TIME 6 4811
TEST_TIME 7 5234
TEST_TIME 8 5344
TEST_TIME 9 5868
TEST_TIME 10 5710
TEST_TIME 11 5872
TEST_TIME 12 5933
TEST_TIME 13 6881
TEST_TIME 14 7287
TEST_TIME 15 7201
TEST_TIME 16 7496
TEST_TIME 17 7580
TEST_TIME 18 8106
TEST_TIME 19 8245
TEST_TIME 20 8492
TEST_TIME 21 9192
TEST_TIME 22 9265
TEST_TIME 23 9288
TEST_TIME 24 9455
TEST_TIME 25 9964
TEST_TIME 26 10012
TEST_TIME 27 10126
TEST_TIME 28 10493
TEST_TIME 29 10668
TEST_TIME 30 10741
TEST_TIME 31 10668
TEST_TIME 32 10556
TEST_TIME 33 10411
TEST_TIME 34 10580
TEST_TIME 35 10508
TEST_TIME 36 10432
TEST_TIME 37 10543
TEST_TIME 38 10455
TEST_TIME 39 10545
TEST_TIME 40 10408
TEST_TIME 41 10196
TEST_TIME 42 10040
TEST_TIME 43 9980
TEST_TIME 44 9869
TEST_TIME 45 9816
TEST_TIME 46 9780
TEST_TIME 47 9703
TEST_TIME 48 9735
TEST_TIME 49 9634
TEST_TIME 50 9614
TEST_TIME 51 9638
TEST_TIME 52 9533
TEST_TIME 53 9498
TEST_TIME 54 9457
TEST_TIME 55 9449
TEST_TIME 56 9417
TEST_TIME 57 9493
TEST_TIME 58 9448
TEST_TIME 59 9432
TEST_TIME 60 9566
TEST_TIME 61 9464
TEST_TIME 62 9570
TEST_TIME 63 9645
TEST_TIME 64 9653
TEST_TIME 65 9627
TEST_TIME 66 9690
TEST_TIME 67 9737
TEST_TIME 68 9749
TEST_TIME 69 9615
TEST_TIME 70 9561
TEST_TIME 71 9553
TEST_TIME 72 9478
TEST_TIME 73 9438
TEST_TIME 74 9413
TEST_TIME 75 9348
TEST_TIME 76 9292
TEST_TIME 77 9320
TEST_TIME 78 9250
TEST_TIME 79 9216
TEST_TIME 80 9259
TEST_TIME 81 9217
TEST_TIME 82 9202
TEST_TIME 83 9219
TEST_TIME 84 9231
TEST_TIME 85 9333
TEST_TIME 86 9361
TEST_TIME 87 9377
TEST_TIME 88 9359
TEST_TIME 89 9488
TEST_TIME 90 9386
TEST_TIME 91 9308
TEST_TIME 92 9261
TEST_TIME 93 9200
TEST_TIME 94 9161
TEST_TIME 95 9238
TEST_TIME 96 9197
TEST_TIME 97 9198
TEST_TIME 98 9172
TEST_TIME 99 9206
TEST_TIME 100 9248
TEST_TIME 101 9193
TEST_TIME 102 9154
TEST_TIME 103 9214
TEST_TIME 104 9176
TEST_TIME 105 9245
TEST_TIME 106 9306
TEST_TIME 107 9287
TEST_TIME 108 9269
TEST_TIME 109 9190
TEST_TIME 110 9149
TEST_TIME 111 9087
TEST_TIME 112 9077
TEST_TIME 113 9045
TEST_TIME 114 8995
TEST_TIME 115 8950
TEST_TIME 116 8937
TEST_TIME 117 9017
TEST_TIME 118 8995
TEST_TIME 119 9039
TEST_TIME 120 9005

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2020 07:28:01 to 14-10-2020 07:28:04.

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 1493116 1493116 0 0.00 0 17482 7 0 0
doc_list_biud 1493116 1493116 0 0.00 0 40618 30 0 0
sp_add_invoice_to_stock 5045 4937 108 2.14 52 170433 17012 0 108
sp_cancel_adding_invoice 5685 5685 0 0.00 4 66658 794 0 0
sp_cancel_client_order 1709 1709 0 0.00 4 13406 312 0 0
sp_cancel_customer_reserve 550906 550906 0 0.00 1 40619 63 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 1904 62 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 2 9041 86 0 0
sp_cancel_supplier_invoice 6276 6271 5 0.07 2 15592 841 0 5
sp_cancel_supplier_order 419 419 0 0.00 6 892463 105132 0 0
sp_cancel_write_off 9367 9367 0 0.00 1 14193 127 0 0
sp_client_order 20147 20147 0 0.00 4 65549 388 0 0
sp_customer_reserve 444561 444561 0 0.00 0 45850 170 0 0
sp_fill_shopping_cart 31182 31182 0 0.00 1 17922 48 0 0
sp_fill_shopping_cart_clo_res 444555 444555 0 0.00 0 17447 15 0 0
sp_get_clo_for_invoice 4931 4931 0 0.00 0 42630 363 0 0
sp_kill_cost_storno 16131 16131 0 0.00 0 8227 57 0 0
sp_kill_qstorno_ret_qs2qd 1117869 1117869 0 0.00 0 40615 34 0 0
sp_kill_qty_storno 589180 589180 0 0.00 0 40618 74 0 0
sp_lock_selected_doc 20469 20469 0 0.00 0 9451 4 0 0
sp_make_cost_storno 27667 27667 0 0.00 0 9991 59 0 0
sp_multiply_rows_for_pdistr 27667 27667 0 0.00 0 9210 35 0 0
sp_multiply_rows_for_qdistr 20147 20147 0 0.00 0 13630 198 0 0
sp_pay_from_customer 7049 7049 0 0.00 4 14880 127 0 0
sp_pay_to_supplier 5795 5795 0 0.00 5 22270 142 0 0
sp_payment_common 12844 12844 0 0.00 1 10227 27 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 13400 287 0 0
sp_qd_handle_on_invoice_upd_sts 10616 10616 0 0.00 0 36099 379 0 0
sp_qd_handle_on_reserve_upd_sts 9892 9892 0 0.00 0 21085 51 0 0
sp_reserve_write_off 9892 9892 0 0.00 7 21133 172 0 0
sp_supplier_invoice 5438 5438 0 0.00 7 93278 3681 0 0
sp_supplier_order 5615 5615 0 0.00 5 25001 1389 0 0
srv_aggregate_perf_data 103 103 0 0.00 3203 116156 14733 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1999 119237 40789 0 0
srv_find_qd_qs_mism 1034197 1034197 0 0.00 0 11015 5 0 0
srv_make_invnt_saldo 472 472 0 0.00 51 103635 3453 0 0
srv_make_money_saldo 714 714 0 0.00 1 16293 613 0 0
srv_recalc_idx_stat 5 5 0 0.00 90692 434014 241382 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4937 4937 0 0.00 0 11514 164 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 2122 57 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 2234 12 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 5441 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 414 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 2772 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 391 10 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 22458 1434 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 2052 7 0 0
t$perf-norm:sp_client_order 20147 20147 0 0.00 0 10653 19 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1038 4 0 0
t$perf-norm:sp_pay_from_customer 7049 7049 0 0.00 0 4451 8 0 0
t$perf-norm:sp_pay_to_supplier 5795 5795 0 0.00 0 5402 8 0 0
t$perf-norm:sp_reserve_write_off 9892 9892 0 0.00 0 4389 7 0 0
t$perf-norm:sp_supplier_invoice 5438 5438 0 0.00 0 4507 17 0 0
t$perf-norm:sp_supplier_order 5615 5615 0 0.00 0 4379 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 248 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 98 5 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 481 7 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 2020 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 16 4 0 0
v_add_invoice_to_stock 10078 9862 216 2.14 0 10099 18 0 216
v_all_customers 36368 36368 0 0.00 0 66 1 0 0
v_all_suppliers 30226 30226 0 0.00 0 89 1 0 0
v_all_wares 40294 40294 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1489 8 0 0
v_cancel_client_order 3418 3418 0 0.00 0 1799 4 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 1898 5 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 4656 8 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 492 4 0 0
v_cancel_supplier_invoice 752 742 10 1.32 0 234 13 0 10
v_cancel_supplier_order 838 838 0 0.00 0 1480 7 0 0
v_cancel_write_off 1974 1974 0 0.00 0 10112 17 0 0
v_max_id_clo_ord 5608 5608 0 0.00 0 5161 4 0 0
v_max_id_clo_res 984 984 0 0.00 0 1397 10 0 0
v_max_id_ord_sup 5426 5426 0 0.00 0 5628 5 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 2582 4 0 0
v_max_non_paid_realizn 3816 3816 0 0.00 0 2300 4 0 0
v_min_id_clo_ord 5608 5608 0 0.00 0 4973 11 0 0
v_min_id_clo_res 984 984 0 0.00 0 707 3 0 0
v_min_id_ord_sup 5426 5426 0 0.00 0 5592 14 0 0
v_min_non_paid_invoice 5795 5795 0 0.00 0 5767 16 0 0
v_min_non_paid_realizn 7049 7049 0 0.00 0 7441 22 0 0
v_our_firm 10392 10392 0 0.00 0 14 1 0 0
v_reserve_write_off 19782 19782 0 0.00 0 6950 5 0 0
x_lock_depdocs_on_canc_invoice 11585 11585 0 0.00 0 11019 102 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 4399 319 0 0
x_make_qstorno_1000_1200 5615 5615 0 0.00 2 24977 1292 0 0
x_make_qstorno_1200_2000 5438 5438 0 0.00 3 91389 3507 0 0
x_make_qstorno_2100_3300 444561 444561 0 0.00 0 37362 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2020 07:28:05 to 14-10-2020 07:28:09.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
04:56:54 1 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
04:57:56 14895 8192000000 9709101344 10002022400 791761680 8.813 100 88 176 1 820669856 7518144 21390032
04:59:16 21135 8192000000 9759276272 10065285120 829982224 9.199 100 83 166 0 861589488 12074736 19532528
05:00:42 9895 8192000000 9794903664 10111168512 867051408 9.571 100 84 168 1 908482544 22279792 19151344
05:02:03 12826 8192000000 9856146576 10188058624 910407712 10.001 100 83 166 0 975048368 46335360 18305296
05:03:20 6372 8192000000 9906523664 10249613312 942536304 10.318 100 86 172 3 1018340656 57218784 18585568
05:04:29 4165 8192000000 9959296704 10310184960 984631632 10.729 100 87 174 1 1070569648 67888928 18049088
05:05:34 3464 8192000000 9974447728 10335272960 1015798112 11.031 100 84 164 0 1092190640 59650160 16742368
05:06:39 1931 8192000000 10031949248 10397605888 1043669056 11.300 100 91 182 1 1140310560 78412160 18229344
05:07:42 5337 8192000000 10028299328 10412408832 1064669920 11.501 100 57 113 1 1144316000 67581584 12064496
05:08:48 13812 8192000000 9974044640 10381594624 1070674960 11.559 100 30 58 1 1090260336 13613056 5972320
05:10:13 5235 8192000000 10003740256 10396168192 1084092352 11.686 100 34 67 0 1112748736 21026672 7629712
05:11:19 5732 8192000000 9992174784 10390192128 1088808400 11.731 100 18 36 1 1113512640 19227376 5476864
05:12:25 8940 8192000000 10000451984 10404622336 1094807600 11.788 100 24 49 1 1126128704 26497568 4823536
05:13:35 68324 8192000000 10004999264 10415443968 1088000272 11.724 99 37 73 2 1112608176 16679184 7928720
05:15:44 4223 8192000000 10002628752 10410397696 1098608816 11.824 100 42 86 2 1125619168 17737856 9272496
05:16:50 5752 8192000000 10004960800 10406449152 1088483840 11.728 100 35 71 5 1114349040 18321728 7543472
05:17:56 7375 8192000000 9967467968 10354163712 993574768 10.816 92 30 60 1 1014371296 14408112 6388416
05:19:07 35733 8192000000 9913994816 10307936256 937355088 10.267 91 21 42 1 978113520 36469696 4288736
05:20:46 8365 8192000000 9940866720 10326827008 1013194112 11.006 99 31 62 0 1044537040 24225920 7117008
05:21:56 8108 8192000000 9952015056 10346196992 1033319904 11.200 100 47 95 1 1068975296 25295008 10360384
05:23:04 12131 8192000000 9954238816 10357526528 1051661104 11.377 100 38 73 1 1079335008 19478288 8195616
05:24:17 16452 8192000000 9964605424 10364932096 1055923920 11.417 100 19 37 3 1084170720 24369344 3877456
05:25:38 3582 8192000000 9990572656 10380828672 1071358832 11.565 100 49 96 0 1101219792 19974976 9885984
05:26:42 9639 8192000000 10001812864 10403225600 1076509568 11.614 99 28 56 3 1096817264 13447728 6859968
05:27:59 10488 8192000000 10041313104 10431885312 1107422736 11.908 100 53 104 0 1147218944 28935248 10860960
05:29:13 25160 8192000000 10068040256 10464428032 1121753104 12.044 100 42 83 0 1176564880 46177968 8633808
05:30:42 32286 8192000000 10138056608 10544185344 1138698816 12.203 100 54 108 1 1254574720 104370736 11505168
05:32:25 7129 8192000000 10154465504 10560163840 1151776400 12.326 100 59 115 1 1267013696 102293264 12944032
05:33:37 5091 8192000000 10186908496 10605084672 1145991648 12.272 99 54 108 1 1286040384 128499952 11548784
05:34:45 5282 8192000000 10220084112 10622263296 1147053056 12.282 99 65 130 4 1317736352 156506736 14176560
05:35:56 7260 8192000000 10224711984 10627026944 1152345680 12.332 99 59 116 0 1325112496 159071232 13695584
05:37:09 9899 8192000000 10265239008 10667741184 1146783664 12.279 100 69 138 1 1369205760 207219280 15202816
05:38:24 16022 8192000000 10284151520 10699055104 1154460592 12.351 100 45 90 1 1384484400 218653952 11369856
05:39:52 4363 8192000000 10222209552 10624438272 1147498032 12.286 100 74 148 3 1326128848 162001088 16629728
05:41:03 1998 8192000000 10254487824 10648047616 1151008816 12.319 100 76 152 0 1341220448 173731728 16479904
05:42:09 5044 8192000000 10297970160 10698575872 1138968128 12.206 99 76 147 1 1389061280 232534688 17558464
05:43:21 3123 8192000000 10311169728 10696728576 1126137440 12.085 100 87 173 1 1403907280 257458544 20311296
05:44:31 3202 8192000000 10340652688 10732785664 1139095280 12.207 100 90 180 0 1438497824 278743248 20659296
05:45:39 3582 8192000000 10318008096 10718502912 1118666960 12.014 100 74 147 2 1418808672 282679408 17462304
05:46:49 3677 8192000000 10382783408 10806693888 1127790288 12.101 100 68 136 0 1476492848 333025136 15677424
05:47:55 11491 8192000000 10276484688 10702135296 1120043520 12.027 99 31 62 0 1384870704 257118656 7708528
05:49:11 19728 8192000000 10275363424 10704183296 1098578224 11.824 98 25 50 0 1358050752 253015408 6457120
05:50:36 8363 8192000000 10303078448 10734002176 1113278240 11.963 100 32 64 2 1409657920 288270976 8108704
05:51:48 21614 8192000000 10254688688 10676350976 1126409968 12.088 100 23 46 2 1364468880 232132912 5926000
05:53:15 33295 8192000000 10203131824 10690744320 1121163936 12.038 100 24 48 2 1350511872 223759456 5588480
05:55:02 46501 8192000000 10113723616 10582642688 1092999568 11.771 98 7 14 0 1205114416 110144720 1970128
05:57:13 89786 8192000000 10106556928 10548498432 1117457296 12.003 99 18 36 1 1197911040 76711296 3742448
05:59:44 33930 8192000000 10114928416 10547339264 1132032928 12.141 100 18 34 1 1211076544 75383856 3659760
06:01:33 65634 8192000000 10110973504 10559475712 1136509792 12.183 100 8 16 0 1193079072 54973888 1595392
06:03:57 93207 8192000000 10073468832 10542043136 1116504384 11.994 99 23 46 3 1160681664 38564944 5612336
06:06:37 119237 8192000000 10081693888 10528829440 1130320512 12.124 99 32 64 1 1185337808 48160656 6856640
06:11:00 13866 8192000000 10113618992 10542592000 1138537968 12.202 99 79 158 3 1211287808 54955104 17794736
06:12:25 30739 8192000000 10089703408 10531684352 1100528416 11.843 96 23 46 0 1180551696 75513152 4510128
06:13:59 47239 8192000000 10112622224 10592538624 1129456128 12.116 99 24 48 2 1235077616 99883520 5737968
06:15:48 36457 8192000000 10089436512 10563162112 1115889984 11.988 98 14 28 3 1178731872 59601872 3240016
06:17:24 38091 8192000000 10055130144 10514952192 1129526000 12.117 100 11 22 0 1170120752 38423456 2171296
06:19:07 73842 8192000000 10066226448 10582990848 1123563488 12.061 99 8 16 0 1186674544 61402864 1708192
06:21:22 42123 8192000000 10070891744 10526707712 1141130128 12.226 100 25 50 1 1180250064 33921424 5198512
06:23:07 77893 8192000000 10075989520 10553794560 1121106416 12.037 98 11 22 1 1186811072 63044368 2660288
06:25:25 51489 8192000000 10101961376 10573742080 1106978768 11.904 97 26 52 0 1212240080 99843696 5417616
06:27:17 17517 8192000000 10101227552 10528800768 1119457600 12.022 99 34 68 1 1181075632 54672032 6946000
06:28:35 63419 8192000000 10149902896 10593742848 1127051136 12.094 100 8 16 1 1226580032 97900672 1628224
06:30:43 20109 8192000000 10070413824 10487709696 1099669168 11.835 98 32 62 2 1130358496 24910176 5779152
06:32:07 47724 8192000000 10112297520 10554404864 1064799200 11.502 95 34 68 0 1158333616 86846352 6688064
06:33:59 65062 8192000000 10076537952 10551525376 1099376928 11.832 98 17 34 0 1164848768 61906720 3565120
06:36:48 39385 8192000000 10006899200 10491064320 1069150624 11.544 99 11 22 0 1118140528 46375648 2614256
06:38:32 66323 8192000000 10034074896 10485387264 1062492592 11.480 97 13 26 1 1108498000 43437056 2568352
06:40:40 29730 8192000000 10039893856 10492313600 1096265360 11.802 100 14 28 1 1146648576 47577008 2806208
06:42:17 65798 8192000000 10054421808 10512670720 1099027152 11.828 99 11 21 1 1161328016 60040608 2260256
06:44:27 45541 8192000000 10057886816 10496991232 1114893136 11.979 100 16 32 0 1150618176 32289552 3435488
06:46:14 70775 8192000000 10084370464 10527657984 1105369472 11.889 98 20 40 3 1189590320 79900400 4320448
06:48:27 76970 8192000000 10116489344 10560966656 1077791904 11.626 95 26 52 0 1174943312 91655616 5495792
06:50:44 65714 8192000000 10085666256 10564292608 1124696224 12.071 100 23 46 2 1217288112 87559360 5032528
06:52:55 46508 8192000000 10068605056 10524286976 1125750640 12.081 100 26 50 1 1187756752 56119376 5886736
06:54:42 11634 8192000000 10125128960 10549706752 1124586352 12.070 99 42 84 0 1204407088 70466112 9354624
06:56:02 61458 8192000000 10077891376 10608680960 1120835360 12.035 100 4 8 0 1182316720 60863792 617568
06:58:44 56664 8192000000 10096069744 10545328128 1134369568 12.163 100 8 16 0 1177674240 41700688 1603984
07:01:41 74018 8192000000 10070961264 10499596288 1081950352 11.666 95 20 40 1 1123592848 37556368 4086128
07:04:03 46574 8192000000 10103410416 10532868096 1134518848 12.164 99 32 64 1 1177153024 35320240 7313936
07:05:53 72090 8192000000 10089996624 10578513920 1142837280 12.242 100 17 34 1 1218628048 72077024 3713744
07:08:05 39662 8192000000 10122182608 10551820288 1150087152 12.310 100 42 84 2 1220836912 61899920 8849840
07:09:45 70706 8192000000 10139142528 10584387584 1109811440 11.931 96 30 60 1 1165119632 48591728 6716464
07:13:03 69966 8192000000 10072401072 10549972992 1091320144 11.755 95 20 41 2 1144254288 48522864 4411280
07:16:25 71841 8192000000 10073427376 10532818944 1124320624 12.068 99 11 22 0 1164564944 37859008 2385312
07:18:37 53070 8192000000 10080815616 10574757888 1101282720 11.850 97 12 24 0 1147068352 43503280 2282352
07:20:49 70645 8192000000 10176681712 10624032768 1119926576 12.026 98 23 46 3 1223694800 98467904 5300320
07:23:21 103717 8192000000 10103686160 10537070592 1103337808 11.869 97 19 38 0 1215115872 107083152 4694912
07:26:11 20813 8192000000 10079788016 10509275136 1097759424 11.816 97 30 61 2 1141591456 37598240 6233792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2020 07:28:11 to 14-10-2020 07:28:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 108 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2020 07:28:27 to 14-10-2020 07:28:29.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236286
MON$OLDEST_ACTIVE               239000
MON$OLDEST_SNAPSHOT             239000
MON$NEXT_TRANSACTION            239000
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2114880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:785: 
:786: mscs-test-161.budzdorov.ru    Wed Oct 14 07:26:54 2020
:787:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:788: 
:789: 
:790: mscs-test-161.budzdorov.ru    Wed Oct 14 07:26:54 2020
:791:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:792: 
:793: 
:794: mscs-test-161.budzdorov.ru    Wed Oct 14 07:26:56 2020
:795:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:796: 
--- end of diff output ---

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

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