Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.02.2021 13:28
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 16459984 kB
MemAvailable 31144524 kB
Buffers 0 kB
Cached 15246580 kB
SwapCached 3892 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 2021-02-23 13:26:58
DTS_END                         2021-02-23 13:26:58.6390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49408
TRN_ID                          233840
ATT_ID                          677
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: 9041.22 31494 1084946
customer order: creation 164.87 448 19784
customer order: refuse 13.93 367 1672
order to supplier: creation 45.49 1361 5459
order to supplier: removal 3.57 129058 428
invoice (draft): creation 45.76 3535 5491
invoice (draft): removal 52.30 1102 6276
invoice accept: apply 40.55 19026 4866
invoice accept: cancel 46.70 985 5604
customer reserve: creation 3748.93 186 449871
customer reserve: removal 4582.25 78 549870
realization accept: apply 83.02 220 9962
realization accept: cancel 87.13 136 10455
payment from customer: creation 58.50 138 7020
payment from customer: removal 6.03 99 724
payment to supplier: creation 47.72 167 5726
payment to supplier: removal 3.31 74 397
service: total inventory turnovers 3.65 4026 438
service: total monetary turnovers 6.52 627 782
service: aggregate perf. data 0.98 15301 117
service: refresh index statistics 0.03 452954 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-02-2021 13:29:02 to 23-02-2021 13:29:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2662
TEST_TIME 2 3602
TEST_TIME 3 3142
TEST_TIME 4 3536
TEST_TIME 5 3574
TEST_TIME 6 3449
TEST_TIME 7 3629
TEST_TIME 8 4099
TEST_TIME 9 4040
TEST_TIME 10 4307
TEST_TIME 11 4282
TEST_TIME 12 5020
TEST_TIME 13 5015
TEST_TIME 14 5202
TEST_TIME 15 5389
TEST_TIME 16 5527
TEST_TIME 17 5778
TEST_TIME 18 6484
TEST_TIME 19 6461
TEST_TIME 20 6793
TEST_TIME 21 6930
TEST_TIME 22 7027
TEST_TIME 23 7709
TEST_TIME 24 7817
TEST_TIME 25 8120
TEST_TIME 26 8457
TEST_TIME 27 8314
TEST_TIME 28 8530
TEST_TIME 29 9229
TEST_TIME 30 10576
TEST_TIME 31 10586
TEST_TIME 32 10650
TEST_TIME 33 10497
TEST_TIME 34 10617
TEST_TIME 35 10473
TEST_TIME 36 10374
TEST_TIME 37 10342
TEST_TIME 38 10134
TEST_TIME 39 9985
TEST_TIME 40 9934
TEST_TIME 41 9910
TEST_TIME 42 9843
TEST_TIME 43 9788
TEST_TIME 44 9711
TEST_TIME 45 9573
TEST_TIME 46 9692
TEST_TIME 47 9790
TEST_TIME 48 9881
TEST_TIME 49 9785
TEST_TIME 50 9858
TEST_TIME 51 9767
TEST_TIME 52 9751
TEST_TIME 53 9808
TEST_TIME 54 9811
TEST_TIME 55 9739
TEST_TIME 56 9804
TEST_TIME 57 9875
TEST_TIME 58 9905
TEST_TIME 59 9840
TEST_TIME 60 9944
TEST_TIME 61 9859
TEST_TIME 62 9768
TEST_TIME 63 9645
TEST_TIME 64 9598
TEST_TIME 65 9540
TEST_TIME 66 9469
TEST_TIME 67 9450
TEST_TIME 68 9369
TEST_TIME 69 9300
TEST_TIME 70 9320
TEST_TIME 71 9301
TEST_TIME 72 9344
TEST_TIME 73 9344
TEST_TIME 74 9351
TEST_TIME 75 9385
TEST_TIME 76 9339
TEST_TIME 77 9474
TEST_TIME 78 9451
TEST_TIME 79 9517
TEST_TIME 80 9493
TEST_TIME 81 9459
TEST_TIME 82 9510
TEST_TIME 83 9608
TEST_TIME 84 9534
TEST_TIME 85 9435
TEST_TIME 86 9415
TEST_TIME 87 9358
TEST_TIME 88 9293
TEST_TIME 89 9307
TEST_TIME 90 9260
TEST_TIME 91 9252
TEST_TIME 92 9209
TEST_TIME 93 9221
TEST_TIME 94 9226
TEST_TIME 95 9210
TEST_TIME 96 9240
TEST_TIME 97 9284
TEST_TIME 98 9251
TEST_TIME 99 9270
TEST_TIME 100 9279
TEST_TIME 101 9304
TEST_TIME 102 9240
TEST_TIME 103 9202
TEST_TIME 104 9139
TEST_TIME 105 9121
TEST_TIME 106 9122
TEST_TIME 107 9080
TEST_TIME 108 9081
TEST_TIME 109 9066
TEST_TIME 110 9110
TEST_TIME 111 9119
TEST_TIME 112 9098
TEST_TIME 113 9072
TEST_TIME 114 9079
TEST_TIME 115 9050
TEST_TIME 116 9106
TEST_TIME 117 9094
TEST_TIME 118 9120
TEST_TIME 119 9106
TEST_TIME 120 9045

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-02-2021 13:29:06 to 23-02-2021 13:29:09.

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 1501886 1501886 0 0.00 0 20366 7 0 0
doc_list_biud 1501886 1501886 0 0.00 0 84385 36 0 0
sp_add_invoice_to_stock 4967 4866 101 2.03 56 146375 19027 0 101
sp_cancel_adding_invoice 5604 5604 0 0.00 5 121026 985 0 0
sp_cancel_client_order 1672 1672 0 0.00 4 11530 367 0 0
sp_cancel_customer_reserve 549870 549870 0 0.00 1 20611 78 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 5177 100 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 2158 74 0 0
sp_cancel_supplier_invoice 6283 6276 7 0.11 2 84416 1103 0 7
sp_cancel_supplier_order 428 428 0 0.00 6 1396171 129058 0 0
sp_cancel_write_off 10455 10455 0 0.00 1 18226 137 0 0
sp_client_order 19784 19784 0 0.00 4 33181 448 0 0
sp_customer_reserve 449871 449871 0 0.00 0 75329 187 0 0
sp_fill_shopping_cart 30728 30728 0 0.00 1 16233 46 0 0
sp_fill_shopping_cart_clo_res 449869 449869 0 0.00 0 25301 18 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 26752 336 0 0
sp_kill_cost_storno 17180 17180 0 0.00 0 8503 70 0 0
sp_kill_qstorno_ret_qs2qd 1116899 1116899 0 0.00 0 84383 42 0 0
sp_kill_qty_storno 589130 589130 0 0.00 0 84385 91 0 0
sp_lock_selected_doc 20597 20597 0 0.00 0 9529 8 0 0
sp_make_cost_storno 27571 27571 0 0.00 0 20134 71 0 0
sp_multiply_rows_for_pdistr 27571 27571 0 0.00 0 14458 39 0 0
sp_multiply_rows_for_qdistr 19784 19784 0 0.00 0 33169 253 0 0
sp_pay_from_customer 7020 7020 0 0.00 4 17272 139 0 0
sp_pay_to_supplier 5726 5726 0 0.00 5 19100 168 0 0
sp_payment_common 12746 12746 0 0.00 1 11568 36 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 11522 338 0 0
sp_qd_handle_on_invoice_upd_sts 10467 10467 0 0.00 0 25474 426 0 0
sp_qd_handle_on_reserve_upd_sts 9962 9962 0 0.00 0 21744 63 0 0
sp_reserve_write_off 9962 9962 0 0.00 7 21771 221 0 0
sp_supplier_invoice 5491 5491 0 0.00 7 93032 3536 0 0
sp_supplier_order 5459 5459 0 0.00 5 41693 1361 0 0
srv_aggregate_perf_data 117 117 0 0.00 3099 186823 15302 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1530 169225 44367 0 0
srv_find_qd_qs_mism 1038578 1038578 0 0.00 0 16625 6 0 0
srv_make_invnt_saldo 438 438 0 0.00 33 125320 4027 0 0
srv_make_money_saldo 782 782 0 0.00 1 16980 627 0 0
srv_recalc_idx_stat 4 4 0 0.00 221662 629979 452954 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4868 4868 0 0.00 0 24813 200 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 2339 104 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 667 9 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1912 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 1330 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 1040 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 924 21 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 20300 1616 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 2303 9 0 0
t$perf-norm:sp_client_order 19784 19784 0 0.00 0 16240 19 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 354 4 0 0
t$perf-norm:sp_pay_from_customer 7020 7020 0 0.00 0 4783 7 0 0
t$perf-norm:sp_pay_to_supplier 5726 5726 0 0.00 0 9083 10 0 0
t$perf-norm:sp_reserve_write_off 9962 9962 0 0.00 0 9413 9 0 0
t$perf-norm:sp_supplier_invoice 5491 5491 0 0.00 0 7254 18 0 0
t$perf-norm:sp_supplier_order 5459 5459 0 0.00 0 16619 23 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 962 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 999 10 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 272 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 8 0 0
v_add_invoice_to_stock 9928 9726 202 2.03 0 8411 16 0 202
v_all_customers 36456 36456 0 0.00 0 72 1 0 0
v_all_suppliers 30652 30652 0 0.00 0 174 1 0 0
v_all_wares 39564 39564 0 0.00 0 174 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1400 8 0 0
v_cancel_client_order 3344 3344 0 0.00 0 3013 5 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 2148 8 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1676 6 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 398 5 0 0
v_cancel_supplier_invoice 838 824 14 1.67 0 1599 15 0 14
v_cancel_supplier_order 856 856 0 0.00 0 2329 8 0 0
v_cancel_write_off 2052 2052 0 0.00 0 8666 24 0 0
v_max_id_clo_ord 5459 5459 0 0.00 0 1093 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 3877 13 0 0
v_max_id_ord_sup 5487 5487 0 0.00 0 5558 6 0 0
v_max_non_paid_invoice 1346 1346 0 0.00 0 641 3 0 0
v_max_non_paid_realizn 3629 3629 0 0.00 0 4077 4 0 0
v_min_id_clo_ord 5459 5459 0 0.00 0 14378 17 0 0
v_min_id_clo_res 981 981 0 0.00 0 7341 16 0 0
v_min_id_ord_sup 5487 5487 0 0.00 0 6606 16 0 0
v_min_non_paid_invoice 5726 5726 0 0.00 0 9607 26 0 0
v_min_non_paid_realizn 7020 7020 0 0.00 0 11562 29 0 0
v_our_firm 9890 9890 0 0.00 0 51 1 0 0
v_reserve_write_off 19922 19922 0 0.00 0 6617 6 0 0
x_lock_depdocs_on_canc_invoice 11468 11468 0 0.00 0 15646 129 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 6842 348 0 0
x_make_qstorno_1000_1200 5459 5459 0 0.00 2 41685 1258 0 0
x_make_qstorno_1200_2000 5491 5491 0 0.00 3 92828 3379 0 0
x_make_qstorno_2100_3300 449871 449871 0 0.00 0 75327 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-02-2021 13:29:10 to 23-02-2021 13:29:14.

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
10:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 3023 8192000000 9704573424 9995235328 786954784 8.764 100 91 182 0 817128896 7329328 22844784
10:59:09 32066 8192000000 9755771280 10061635584 832510240 9.224 100 87 173 1 862911568 9983392 20417936
11:00:46 18651 8192000000 9796779264 10111029248 864844528 9.549 100 83 166 0 900342208 15994480 19503200
11:02:15 17088 8192000000 9848528912 10178686976 905362112 9.951 100 81 162 1 959619760 36233744 18023904
11:03:39 7972 8192000000 9901821584 10241953792 951189264 10.403 100 80 161 0 1017300624 49263488 16847872
11:04:51 6185 8192000000 9966099472 10313592832 984526688 10.728 100 92 184 0 1070977648 67729344 18721616
11:05:59 3111 8192000000 9997304272 10354761728 1011965600 10.994 100 84 167 1 1107100656 78457632 16677424
11:07:04 4603 8192000000 9993685040 10358243328 1028890656 11.158 100 78 155 1 1105752864 61804816 15057392
11:08:12 1881 8192000000 10039028864 10405535744 1056324352 11.421 100 97 193 0 1151907680 76291536 19291792
11:09:18 1992 8192000000 10043000336 10413412352 1066567936 11.519 100 85 167 1 1154316288 71737984 16010368
11:10:21 5548 8192000000 10005375264 10398281728 1074560368 11.596 100 42 84 0 1122713504 39890544 8262592
11:11:29 6886 8192000000 9999350448 10390888448 1083408464 11.680 100 41 82 0 1119235968 27397168 8430336
11:12:36 4442 8192000000 10005003856 10398310400 1087955520 11.723 100 43 87 0 1117185408 19964624 9265264
11:13:45 41268 8192000000 10013808656 10413060096 1091740928 11.759 100 43 86 0 1128915872 28062016 9112928
11:15:27 3422 8192000000 10027267600 10418634752 1095208912 11.792 100 50 96 3 1134852320 29437072 10206336
11:16:30 7548 8192000000 10019378320 10426146816 1093913648 11.780 100 36 71 3 1127251744 24977088 8361008
11:17:38 11041 8192000000 10004574912 10411614208 1090552816 11.748 100 20 38 1 1115623808 20980400 4090592
11:18:54 26836 8192000000 9996075120 10381893632 1071862080 11.570 99 70 141 3 1095464512 7985776 15616656
11:20:21 9947 8192000000 9957494432 10351120384 997072032 10.850 94 28 56 1 1019003424 15514432 6416960
11:21:32 4638 8192000000 9940986896 10317316096 1003674624 10.914 97 45 88 1 1028021232 13700992 10645616
11:22:38 8802 8192000000 9947619744 10346409984 1033855920 11.206 100 34 68 3 1062001632 20997776 7147936
11:23:51 16021 8192000000 9955337952 10348650496 1018892736 11.061 98 27 53 0 1063466288 38841520 5732032
11:25:08 6665 8192000000 9970824384 10365906944 1045640480 11.319 100 50 99 1 1071580048 14771696 11167872
11:26:17 8938 8192000000 9985710160 10391183360 1066129168 11.515 100 46 92 3 1086641632 10107248 10405216
11:27:33 29042 8192000000 10024114640 10417401856 1084227408 11.688 100 53 107 0 1122535120 26944608 11363104
11:29:06 84418 8192000000 10052783536 10470461440 1097908240 11.818 100 41 82 1 1159926512 52541616 9476656
11:31:40 25896 8192000000 10091032528 10494197760 1119410864 12.021 100 42 84 2 1197442944 69191568 8840512
11:33:11 10500 8192000000 10129709840 10531946496 1127682864 12.100 100 47 93 3 1233927856 96326224 9918768
11:34:26 8752 8192000000 10176449920 10585440256 1142156224 12.236 100 49 99 0 1283624288 129955824 11512240
11:35:54 4352 8192000000 10219852992 10625040384 1156519360 12.371 100 62 122 3 1322418880 152651568 13247952
11:37:02 4213 8192000000 10268846256 10670030848 1169826000 12.495 100 72 142 1 1364668624 179180912 15661712
11:38:10 4915 8192000000 10331834640 10741530624 1172327248 12.519 100 74 146 0 1430593616 241864544 16401824
11:39:23 7427 8192000000 10329538880 10742280192 1191714016 12.699 100 57 113 1 1433717680 229145392 12858272
11:40:39 3560 8192000000 10372544592 10786861056 1202281488 12.798 100 72 139 1 1480372624 261727456 16363680
11:41:48 3205 8192000000 10444993344 10855231488 1198344368 12.761 100 91 178 2 1548885456 329676576 20864512
11:42:58 3074 8192000000 10450720608 10863894528 1183772736 12.625 100 93 186 1 1546583920 341202816 21608368
11:44:09 3431 8192000000 10457177920 10899054592 1183734016 12.625 99 77 152 0 1555228208 352943808 18550384
11:45:19 3386 8192000000 10450660640 10859356160 1177417808 12.566 100 78 155 1 1548599536 352581904 18599824
11:46:28 2010 8192000000 10501929120 10903961600 1174517856 12.539 100 88 174 2 1597783664 402395680 20870128
11:47:33 1529 8192000000 10513025280 10931752960 1169314464 12.490 100 93 186 0 1607081664 415562384 22204816
11:48:39 7365 8192000000 10529153200 10954625024 1171174448 12.508 100 57 113 1 1629726000 444261776 14289776
11:49:53 6656 8192000000 10482840720 10905837568 1172953344 12.524 100 46 92 1 1581897056 396741664 12202048
11:51:19 16257 8192000000 10500199712 10937753600 1140931680 12.224 98 36 72 0 1579970064 429200992 9837392
11:52:40 21686 8192000000 10439533184 10917273600 1139236896 12.208 99 23 46 0 1550322416 404665728 6419792
11:54:17 21704 8192000000 10495081632 10976083968 1129396592 12.116 100 31 62 0 1624255248 485706512 9152144
11:55:46 31064 8192000000 10220878400 10800820224 1130365888 12.125 99 30 60 0 1426411280 288649104 7396288
11:57:17 106103 8192000000 10074849024 10540597248 1064898592 11.503 94 6 12 0 1090718160 24676288 1143280
12:00:03 55754 8192000000 10074535104 10532569088 1119357520 12.021 99 9 18 0 1160979008 39683168 1938320
12:02:00 82467 8192000000 10077847744 10515714048 1083906384 11.685 96 27 54 1 1124901024 35943424 5051216
12:05:26 51804 8192000000 10081007888 10520162304 1128677488 12.109 100 39 77 0 1171848752 35131568 8039696
12:07:23 31580 8192000000 10107627104 10553171968 1137297008 12.190 100 21 42 0 1211813184 70243712 4272464
12:09:07 46870 8192000000 10184880000 10633723904 1153186624 12.339 100 41 82 0 1265605744 103462560 8956560
12:10:58 49815 8192000000 10228939840 10687746048 1143329312 12.247 99 19 38 0 1325057408 177132016 4596080
12:12:50 18076 8192000000 10161016288 10635767808 1144107200 12.254 100 21 41 1 1283139808 134243216 4789392
12:14:09 47137 8192000000 10121123840 10643808256 1144744752 12.260 100 9 18 0 1249453520 102943280 1765488
12:16:55 42592 8192000000 10078597296 10511327232 1098684272 11.825 95 20 40 1 1132327472 29599264 4043936
12:18:39 65363 8192000000 10115662208 10578358272 1147980336 12.291 100 21 42 1 1219321184 66561760 4779088
12:20:54 59465 8192000000 10139965360 10574233600 1149362640 12.304 99 32 64 1 1213583216 57546752 6673824
12:22:57 101917 8192000000 10101347264 10571812864 1128748976 12.110 98 15 30 2 1216820704 84487888 3583840
12:25:39 61683 8192000000 10134532448 10604683264 1147593376 12.287 99 25 48 1 1217209968 64607792 5008800
12:27:52 122193 8192000000 10061950208 10524614656 1127716160 12.100 99 21 42 0 1160558976 28789632 4053184
12:30:56 8910 8192000000 10042966240 10494107648 1134553520 12.164 100 29 59 1 1161178016 20083744 6540752
12:32:13 48967 8192000000 10074042864 10525200384 1141985888 12.234 100 10 20 0 1184820000 40746400 2087712
12:34:02 52451 8192000000 10084686928 10537889792 1092420496 11.766 95 22 42 3 1151394704 53656864 5317344
12:36:48 70395 8192000000 10082913728 10560413696 1082678336 11.673 96 13 26 1 1171409984 86161152 2570496
12:38:59 34502 8192000000 10049429104 10490462208 1104540032 11.881 98 18 36 0 1134213024 25252608 4420384
12:40:34 48650 8192000000 10141253888 10586124288 1134122224 12.160 99 35 70 1 1207614624 66316592 7175808
12:42:25 64429 8192000000 10119362304 10587123712 1096531808 11.805 97 26 52 0 1215784304 113517680 5734816
12:44:42 26236 8192000000 10091822320 10509434880 1096796416 11.807 98 46 90 2 1154770816 48762192 9212208
12:46:09 41988 8192000000 10065350608 10498588672 1096921040 11.808 98 22 44 1 1129330000 27842528 4566432
12:47:55 52304 8192000000 10158012064 10620706816 1118121344 12.009 99 20 40 1 1236882992 114380736 4380912
12:49:49 77194 8192000000 10088538464 10536771584 1109840624 11.931 98 16 32 2 1154272464 41231216 3200624
12:53:25 60367 8192000000 10113165168 10569551872 1115965408 11.989 99 10 20 0 1185317904 67144512 2207984
12:55:56 51847 8192000000 10040169408 10517921792 1091199040 11.754 98 9 18 0 1150611792 57375072 2037680
12:57:51 62008 8192000000 10068976288 10503290880 1104274144 11.878 98 22 44 1 1167478976 58272240 4932592
12:59:54 81179 8192000000 10100904224 10562777088 1125961920 12.083 99 25 51 0 1204888688 73433504 5493264
13:02:15 34396 8192000000 10109095936 10591690752 1122384960 12.050 99 24 48 2 1223000448 95835376 4780112
13:03:59 48740 8192000000 10082624656 10509783040 1105424960 11.889 98 38 76 2 1140243712 26562928 8255824
13:05:48 53988 8192000000 10113490032 10585100288 1120326336 12.030 99 32 64 1 1200320400 73367616 6626448
13:07:43 169225 8192000000 10072744224 10531446784 1119270240 12.020 98 20 40 0 1164608896 41572528 3766128
13:11:32 78274 8192000000 10119004544 10609782784 1143265680 12.246 100 6 12 0 1202536560 58061552 1209328
13:13:50 50568 8192000000 10078123344 10518597632 1138641376 12.203 100 16 32 1 1187177264 45044544 3491344
13:15:43 82341 8192000000 10097749440 10567266304 1119234224 12.020 98 14 28 1 1203690032 80866992 3588816
13:18:06 49154 8192000000 10066730368 10507472896 1106898032 11.903 97 25 49 1 1140037472 28250592 4888848
13:20:06 36950 8192000000 10088644080 10528542720 1132372400 12.144 100 29 58 0 1175211376 37191952 5647024
13:21:43 8457 8192000000 10080841280 10499354624 1123192544 12.057 100 54 108 1 1160584464 27108112 10283808
13:22:52 66257 8192000000 10077115408 10521759744 1072469472 11.576 94 15 30 0 1099245008 24113856 2661680
13:24:59 132356 8192000000 9967292512 10417889280 1013437408 11.009 90 18 37 1 1042761440 25794720 3529312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-02-2021 13:29:16 to 23-02-2021 13:29:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-02-2021 13:29:32 to 23-02-2021 13:29:34.

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          233148
MON$OLDEST_ACTIVE               234041
MON$OLDEST_SNAPSHOT             234041
MON$NEXT_TRANSACTION            234041
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                       2099776
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 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.2372 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 ---
:144: 
:145: mscs-test-161.budzdorov.ru    Tue Feb 23 13:27:19 2021
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Tue Feb 23 13:27:20 2021
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
--- end of diff output ---

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

23.02.21 13:29:35. 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
2021-02-23 13:29:38 No severe PSQL-related problems occured
23.02.21 13:29:39. End of report.