Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2020 04:28
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16960292 kB
MemAvailable 31506996 kB
Buffers 20 kB
Cached 14695092 kB
SwapCached 3064 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-31 04:26:52
DTS_END                         2020-10-31 04:26:52.6360
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/54420
TRN_ID                          231775
ATT_ID                          619
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: 8592.92 22775 1031150
customer order: creation 169.47 423 20336
customer order: refuse 14.76 385 1771
order to supplier: creation 44.31 1450 5317
order to supplier: removal 3.33 124534 399
invoice (draft): creation 44.78 3663 5374
invoice (draft): removal 48.17 1074 5780
invoice accept: apply 40.08 18124 4810
invoice accept: cancel 43.75 986 5250
customer reserve: creation 3605.39 181 432647
customer reserve: removal 4287.73 75 514527
realization accept: apply 82.13 193 9855
realization accept: cancel 82.51 140 9901
payment from customer: creation 58.63 122 7035
payment from customer: removal 5.33 103 640
payment to supplier: creation 48.73 127 5848
payment to supplier: removal 3.36 77 403
service: total inventory turnovers 3.54 3813 425
service: total monetary turnovers 5.94 712 713
service: aggregate perf. data 0.96 13750 115
service: refresh index statistics 0.03 285577 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-10-2020 04:28:55 to 31-10-2020 04:28:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3246
TEST_TIME 2 4461
TEST_TIME 3 4834
TEST_TIME 4 4415
TEST_TIME 5 4398
TEST_TIME 6 4370
TEST_TIME 7 4712
TEST_TIME 8 4544
TEST_TIME 9 4758
TEST_TIME 10 5034
TEST_TIME 11 5079
TEST_TIME 12 5364
TEST_TIME 13 6137
TEST_TIME 14 6186
TEST_TIME 15 6540
TEST_TIME 16 6457
TEST_TIME 17 6871
TEST_TIME 18 7080
TEST_TIME 19 7352
TEST_TIME 20 7462
TEST_TIME 21 7629
TEST_TIME 22 7717
TEST_TIME 23 8027
TEST_TIME 24 8414
TEST_TIME 25 8698
TEST_TIME 26 9071
TEST_TIME 27 9490
TEST_TIME 28 9996
TEST_TIME 29 10407
TEST_TIME 30 10289
TEST_TIME 31 10417
TEST_TIME 32 10518
TEST_TIME 33 10376
TEST_TIME 34 10356
TEST_TIME 35 10134
TEST_TIME 36 9914
TEST_TIME 37 9873
TEST_TIME 38 9848
TEST_TIME 39 9810
TEST_TIME 40 9945
TEST_TIME 41 9851
TEST_TIME 42 9723
TEST_TIME 43 9631
TEST_TIME 44 9520
TEST_TIME 45 9550
TEST_TIME 46 9449
TEST_TIME 47 9353
TEST_TIME 48 9321
TEST_TIME 49 9304
TEST_TIME 50 9337
TEST_TIME 51 9314
TEST_TIME 52 9379
TEST_TIME 53 9279
TEST_TIME 54 9266
TEST_TIME 55 9269
TEST_TIME 56 9417
TEST_TIME 57 9334
TEST_TIME 58 9302
TEST_TIME 59 9315
TEST_TIME 60 9381
TEST_TIME 61 9347
TEST_TIME 62 9290
TEST_TIME 63 9281
TEST_TIME 64 9148
TEST_TIME 65 9198
TEST_TIME 66 9110
TEST_TIME 67 9065
TEST_TIME 68 9119
TEST_TIME 69 9081
TEST_TIME 70 9073
TEST_TIME 71 9087
TEST_TIME 72 9122
TEST_TIME 73 9089
TEST_TIME 74 9065
TEST_TIME 75 9088
TEST_TIME 76 9141
TEST_TIME 77 9168
TEST_TIME 78 9185
TEST_TIME 79 9229
TEST_TIME 80 9128
TEST_TIME 81 9058
TEST_TIME 82 9016
TEST_TIME 83 9011
TEST_TIME 84 8952
TEST_TIME 85 8888
TEST_TIME 86 8867
TEST_TIME 87 8890
TEST_TIME 88 8880
TEST_TIME 89 8893
TEST_TIME 90 8868
TEST_TIME 91 8852
TEST_TIME 92 8830
TEST_TIME 93 8899
TEST_TIME 94 8864
TEST_TIME 95 8892
TEST_TIME 96 8954
TEST_TIME 97 8964
TEST_TIME 98 8969
TEST_TIME 99 8956
TEST_TIME 100 8905
TEST_TIME 101 8837
TEST_TIME 102 8826
TEST_TIME 103 8771
TEST_TIME 104 8713
TEST_TIME 105 8660
TEST_TIME 106 8673
TEST_TIME 107 8683
TEST_TIME 108 8664
TEST_TIME 109 8675
TEST_TIME 110 8676
TEST_TIME 111 8670
TEST_TIME 112 8665
TEST_TIME 113 8691
TEST_TIME 114 8727
TEST_TIME 115 8739
TEST_TIME 116 8732
TEST_TIME 117 8724
TEST_TIME 118 8686
TEST_TIME 119 8646
TEST_TIME 120 8598

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-10-2020 04:28:59 to 31-10-2020 04:29:02.

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 1434047 1434047 0 0.00 0 28451 7 0 0
doc_list_biud 1434047 1434047 0 0.00 0 53431 35 0 0
sp_add_invoice_to_stock 4922 4810 112 2.27 51 215679 18124 0 112
sp_cancel_adding_invoice 5250 5250 0 0.00 5 95471 987 0 0
sp_cancel_client_order 1771 1771 0 0.00 4 27297 386 0 0
sp_cancel_customer_reserve 514527 514527 0 0.00 1 38602 76 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 3448 103 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 2524 77 0 0
sp_cancel_supplier_invoice 5789 5780 9 0.15 2 53436 1075 0 9
sp_cancel_supplier_order 399 399 0 0.00 10 1292909 124534 0 0
sp_cancel_write_off 9901 9901 0 0.00 1 11863 140 0 0
sp_client_order 20336 20336 0 0.00 4 111040 423 0 0
sp_customer_reserve 432647 432647 0 0.00 0 88042 181 0 0
sp_fill_shopping_cart 31024 31024 0 0.00 1 23454 41 0 0
sp_fill_shopping_cart_clo_res 432640 432640 0 0.00 0 31652 19 0 0
sp_get_clo_for_invoice 4799 4799 0 0.00 0 42601 383 0 0
sp_kill_cost_storno 16194 16194 0 0.00 0 14282 71 0 0
sp_kill_qstorno_ret_qs2qd 1045134 1045134 0 0.00 0 53430 41 0 0
sp_kill_qty_storno 552282 552282 0 0.00 0 53431 89 0 0
sp_lock_selected_doc 20349 20349 0 0.00 0 10681 5 0 0
sp_make_cost_storno 27537 27537 0 0.00 0 11605 55 0 0
sp_multiply_rows_for_pdistr 27537 27537 0 0.00 0 7887 37 0 0
sp_multiply_rows_for_qdistr 20336 20336 0 0.00 0 111029 244 0 0
sp_pay_from_customer 7035 7035 0 0.00 4 15432 123 0 0
sp_pay_to_supplier 5848 5848 0 0.00 5 17215 128 0 0
sp_payment_common 12883 12883 0 0.00 1 7893 19 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 27286 354 0 0
sp_qd_handle_on_invoice_upd_sts 10049 10049 0 0.00 0 39424 420 0 0
sp_qd_handle_on_reserve_upd_sts 9855 9855 0 0.00 0 22584 61 0 0
sp_reserve_write_off 9855 9855 0 0.00 7 22601 193 0 0
sp_supplier_invoice 5374 5374 0 0.00 5 151311 3664 0 0
sp_supplier_order 5317 5317 0 0.00 5 32538 1451 0 0
srv_aggregate_perf_data 115 115 0 0.00 2482 159567 13751 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1110 165484 42662 0 0
srv_find_qd_qs_mism 986205 986205 0 0.00 0 28434 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 47 129947 3814 0 0
srv_make_money_saldo 713 713 0 0.00 1 20951 712 0 0
srv_recalc_idx_stat 4 4 0 0.00 89042 521114 285577 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4810 4810 0 0.00 0 18110 200 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 3456 98 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 4874 21 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 2423 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 5077 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 4405 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1199 19 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 27039 1762 0 0
t$perf-norm:sp_cancel_write_off 1050 1050 0 0.00 0 2340 8 0 0
t$perf-norm:sp_client_order 20336 20336 0 0.00 0 13583 21 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 2382 11 0 0
t$perf-norm:sp_pay_from_customer 7035 7035 0 0.00 0 8770 11 0 0
t$perf-norm:sp_pay_to_supplier 5848 5848 0 0.00 0 13634 13 0 0
t$perf-norm:sp_reserve_write_off 9855 9855 0 0.00 0 5852 10 0 0
t$perf-norm:sp_supplier_invoice 5374 5374 0 0.00 0 12288 28 0 0
t$perf-norm:sp_supplier_order 5317 5317 0 0.00 0 2727 17 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 5640 78 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 2270 27 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 1330 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9822 9598 224 2.28 0 5581 20 0 224
v_all_customers 37016 37016 0 0.00 0 14 1 0 0
v_all_suppliers 29858 29858 0 0.00 0 62 1 0 0
v_all_wares 40672 40672 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 5153 25 0 0
v_cancel_client_order 3542 3542 0 0.00 0 4264 8 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 3438 8 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 5141 12 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1621 7 0 0
v_cancel_supplier_invoice 834 816 18 2.15 0 4439 22 0 18
v_cancel_supplier_order 798 798 0 0.00 0 3230 8 0 0
v_cancel_write_off 2100 2100 0 0.00 0 4182 18 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 1400 2 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 2088 15 0 0
v_max_id_ord_sup 5372 5372 0 0.00 0 8557 6 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 1163 3 0 0
v_max_non_paid_realizn 3776 3776 0 0.00 0 4134 4 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 3031 6 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 8264 32 0 0
v_min_id_ord_sup 5372 5372 0 0.00 0 7308 9 0 0
v_min_non_paid_invoice 5848 5848 0 0.00 0 7886 14 0 0
v_min_non_paid_realizn 7035 7035 0 0.00 0 6458 12 0 0
v_our_firm 10174 10174 0 0.00 0 24 1 0 0
v_reserve_write_off 19710 19710 0 0.00 0 5704 7 0 0
x_lock_depdocs_on_canc_invoice 10622 10622 0 0.00 0 18429 111 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 18584 390 0 0
x_make_qstorno_1000_1200 5317 5317 0 0.00 2 32533 1362 0 0
x_make_qstorno_1200_2000 5374 5374 0 0.00 3 139030 3510 0 0
x_make_qstorno_2100_3300 432647 432647 0 0.00 0 88023 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2020 04:29:03 to 31-10-2020 04:29:07.

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
01:56:56 5 8192000000 8802629680 8815566848 7682560 0.093 3 2 2 1 7833120 74160 76400
01:57:57 27946 8192000000 9718183344 10014576640 797394432 8.870 100 91 182 0 827454752 7838576 22221744
01:59:28 21001 8192000000 9751711840 10057830400 825314736 9.152 100 85 169 1 857702992 11851280 20536976
02:00:53 13292 8192000000 9800567040 10115645440 861491472 9.515 100 90 180 0 902784432 19931344 21361616
02:02:18 10015 8192000000 9858945712 10191761408 912787152 10.025 100 81 162 0 970745552 40380352 17578048
02:03:30 10755 8192000000 9916076064 10256519168 944310896 10.335 100 85 170 0 1037673920 75586608 17776416
02:04:44 6539 8192000000 9958516624 10313854976 981247936 10.696 100 79 158 0 1064450496 66086272 17116288
02:05:53 3337 8192000000 9973893248 10333741056 1010603712 10.981 100 81 160 0 1088425632 61711200 16110720
02:06:58 2527 8192000000 10031662176 10395926528 1038571024 11.251 100 93 184 1 1147049904 90559136 17919744
02:08:03 1906 8192000000 10010492624 10383687680 1053963024 11.399 100 89 178 0 1124056944 52252752 17841168
02:09:05 2873 8192000000 9994324656 10380648448 1060124416 11.458 100 61 120 1 1105202576 32496448 12581712
02:10:13 4178 8192000000 9996005344 10387931136 1068012368 11.533 100 48 98 1 1104720752 26086320 10622064
02:11:19 5739 8192000000 9984354048 10376269824 1077628704 11.625 100 23 47 0 1097418208 14797824 4991680
02:12:26 45363 8192000000 9984456512 10390196224 1082304752 11.669 100 43 87 0 1107498384 15805376 9388256
02:14:12 4767 8192000000 10009605936 10406649856 1086543312 11.710 100 54 109 2 1119292576 20803040 11946224
02:15:18 4524 8192000000 10006856544 10399408128 1088245520 11.726 100 38 75 2 1124075184 27540288 8289376
02:16:25 14133 8192000000 9983992800 10393223168 1081603776 11.663 99 43 84 3 1113793232 21894080 10295376
02:17:40 20567 8192000000 10015887264 10419335168 1048462240 11.346 96 42 84 1 1093023776 36219168 8342368
02:19:01 4598 8192000000 9967884640 10346835968 1013479952 11.009 96 46 93 1 1041876960 17630544 10766464
02:20:06 6793 8192000000 9940926064 10331787264 1009643584 10.972 97 41 83 1 1040089712 20978768 9467360
02:21:14 45849 8192000000 9916315472 10299166720 985956848 10.742 97 48 93 3 1024291552 28784880 9549824
02:23:00 4520 8192000000 9962101136 10343702528 1034665728 11.213 100 60 115 4 1073721664 26500704 12555232
02:24:06 5113 8192000000 9972799664 10350522368 1049591712 11.357 100 44 89 2 1087950608 28785824 9573072
02:25:12 8139 8192000000 9977479168 10371338240 1066869456 11.522 100 52 103 1 1103348704 24799680 11679568
02:26:21 17770 8192000000 10004094416 10414833664 1077985920 11.628 100 29 58 0 1119085920 35601824 5498176
02:27:48 6559 8192000000 10042338096 10433744896 1106524160 11.899 100 67 134 1 1150273616 29564240 14185216
02:28:58 31982 8192000000 10077137104 10484649984 1122268880 12.048 100 36 72 1 1187192848 56895360 8028608
02:30:41 9711 8192000000 10108948528 10511286272 1141650304 12.231 100 39 78 2 1206757680 55929104 9178272
02:31:57 13809 8192000000 10155407888 10562674688 1156170160 12.367 100 36 72 1 1265936976 101255056 8511760
02:33:22 20229 8192000000 10232831888 10643615744 1169095872 12.488 100 56 110 0 1345003872 163492624 12415376
02:34:48 16782 8192000000 10273897376 10692407296 1179975904 12.590 100 49 98 0 1378386160 186932432 11477824
02:36:08 7605 8192000000 10304807440 10713825280 1177419264 12.566 99 61 121 1 1397190768 206144432 13627072
02:37:21 4301 8192000000 10326941504 10738024448 1190555632 12.689 100 67 134 1 1428142992 222284080 15303280
02:38:30 3189 8192000000 10366083392 10781052928 1185938864 12.646 100 84 168 2 1473233984 266601360 20693760
02:39:37 6747 8192000000 10303099264 10727653376 1177624560 12.568 99 70 139 0 1400954320 207292640 16037120
02:40:50 1780 8192000000 10357314800 10762297344 1185218064 12.639 100 73 146 2 1457830960 255962128 16650768
02:41:58 4473 8192000000 10348574480 10760945664 1182588032 12.614 100 70 139 3 1449660960 251252400 15820528
02:43:09 1107 8192000000 10394977824 10794680320 1171017376 12.506 99 92 184 0 1485647648 292749632 21880640
02:44:17 2506 8192000000 10340378656 10758107136 1162772736 12.429 99 73 145 1 1441258336 261591872 16893728
02:45:25 1247 8192000000 10388830592 10790277120 1163958000 12.440 100 78 156 0 1482892000 300533632 18400368
02:46:34 8112 8192000000 10371728016 10794381312 1152530304 12.333 99 45 90 0 1465974176 301512032 11931840
02:47:50 9523 8192000000 10402091200 10831060992 1139675136 12.212 100 56 112 0 1502512000 349345456 13491408
02:49:12 13091 8192000000 10400030096 10819878912 1136631648 12.184 99 40 80 0 1494072816 347425680 10015488
02:50:34 11032 8192000000 10392905856 10817896448 1137101280 12.188 100 35 70 0 1494354864 348616224 8637360
02:51:49 13475 8192000000 10388198352 10862317568 1142369088 12.238 100 35 69 1 1471937872 320819856 8748928
02:53:09 17973 8192000000 10297472224 10773602304 1126697184 12.090 100 25 49 2 1438097088 304587200 6812704
02:54:31 10657 8192000000 10171046480 10637479936 1121151280 12.038 99 31 62 2 1300540096 172669728 6719088
02:55:43 30005 8192000000 10083234864 10546331648 1112764224 11.959 99 9 18 0 1180255024 65506592 1984208
02:57:14 16335 8192000000 10131817568 10541817856 1125641600 12.080 99 53 107 1 1206743440 69901168 11200672
02:58:36 63818 8192000000 10094155904 10550546432 1126755664 12.091 99 19 39 2 1198796992 69204880 2836448
03:00:45 165484 8192000000 10108292960 10588491776 1143325952 12.247 100 32 64 0 1228950080 78226704 7397424
03:04:40 60044 8192000000 10114253552 10590998528 1127088208 12.094 98 13 26 1 1207687840 77227008 3372624
03:06:45 47577 8192000000 10106801168 10555256832 1142770480 12.242 100 14 28 1 1213763184 67945040 3047664
03:09:13 52926 8192000000 10099166800 10557775872 1110136816 11.934 98 25 49 0 1164752464 49454576 5161072
03:11:07 20696 8192000000 10105670368 10543460352 1125963408 12.083 99 18 37 1 1196855072 67013968 3877696
03:12:32 59960 8192000000 10100338448 10577408000 1137070400 12.188 100 13 26 1 1217828944 78066944 2691600
03:14:42 29651 8192000000 10109348912 10561245184 1124480432 12.069 99 13 26 2 1213211088 85798640 2932016
03:16:24 35761 8192000000 10068968624 10495664128 1122291792 12.049 99 18 36 0 1150011808 22881200 4838816
03:18:01 49153 8192000000 10101808144 10576060416 1123153600 12.057 99 30 59 1 1198743888 69624608 5965680
03:19:56 41223 8192000000 10130770592 10586333184 1119778656 12.025 98 36 72 1 1212058480 84104832 8174992
03:22:05 44528 8192000000 10102378448 10613002240 1122714144 12.053 99 21 41 5 1222954768 95929552 4311072
03:23:59 67030 8192000000 10127563008 10578935808 1119327168 12.021 99 21 42 1 1174321120 50678768 4315184
03:26:06 61034 8192000000 10072107280 10521681920 1104170352 11.877 98 20 40 0 1187497120 79509792 3816976
03:28:08 83536 8192000000 10128342896 10615934976 1097330336 11.812 96 25 50 0 1202767696 100677920 4759440
03:31:38 64067 8192000000 10092106128 10594328576 1109583968 11.928 98 5 10 0 1167305440 56875456 846016
03:33:43 58435 8192000000 10055386320 10571288576 1102640096 11.863 98 8 16 0 1156796448 52348512 1807840
03:35:50 58540 8192000000 10073548800 10523619328 1113747344 11.968 99 33 67 0 1177949712 57041696 7160672
03:37:49 37902 8192000000 10082885856 10512347136 1111929520 11.951 99 32 64 2 1158684256 39965840 6788896
03:39:36 38568 8192000000 10030438304 10461204480 1048994032 11.351 94 25 50 0 1089870032 35776496 5099504
03:41:15 94068 8192000000 10086470592 10582667264 1083669504 11.682 97 17 34 3 1152028688 64681520 3677664
03:44:01 73586 8192000000 10047478992 10488066048 1048061376 11.342 94 26 52 1 1081246320 28103072 5081872
03:46:19 145292 8192000000 10070793568 10534912000 1109309232 11.926 99 22 44 2 1166467680 52195376 4963072
03:49:50 21317 8192000000 10029699216 10532679680 1101304304 11.850 99 3 6 0 1131387856 29602736 480816
03:51:11 53459 8192000000 10037518080 10487308288 1109475216 11.927 99 4 8 0 1136731104 26617840 638048
03:53:07 46685 8192000000 10076613504 10540236800 1121319168 12.039 100 19 38 3 1176830784 50937600 4574016
03:54:54 52630 8192000000 10068891392 10518376448 1104978240 11.885 98 24 48 0 1154127120 44330272 4818608
03:56:47 58666 8192000000 10101805872 10537619456 1139633296 12.212 100 26 52 0 1185521904 40695488 5193120
03:58:46 49193 8192000000 10067886592 10567548928 1121260160 12.039 99 23 46 0 1213365120 87340032 4764928
04:00:38 58093 8192000000 10102833728 10577698816 1103745296 11.873 98 27 52 0 1198123584 88804704 5573584
04:02:36 78853 8192000000 10074291264 10538987520 1112929056 11.960 99 10 19 0 1182064096 66894368 2240672
04:04:55 28990 8192000000 10080120512 10506469376 1123248080 12.058 99 49 96 1 1149918112 16300896 10369136
04:06:29 149438 8192000000 10046711072 10511220736 1126530752 12.089 100 20 40 1 1169714352 39564720 3618880
04:10:53 69595 8192000000 10084710768 10548391936 1119072432 12.018 99 11 23 2 1176522752 54734512 2715808
04:14:13 53655 8192000000 10095753504 10527232000 1112527792 11.956 98 23 45 1 1178756592 61691024 4537776
04:16:15 49967 8192000000 10101103936 10555133952 1119777248 12.025 99 22 44 0 1193850640 68954384 5119008
04:18:11 55793 8192000000 10106341696 10587701248 1143423344 12.248 100 21 42 0 1223789872 75786848 4579680
04:20:13 29553 8192000000 10095789536 10533261312 1117595776 12.004 99 23 46 1 1186481504 64453776 4431952
04:22:03 41799 8192000000 10074849776 10488786944 1084990624 11.695 96 42 80 2 1123600320 30302416 8307280
04:23:49 117467 8192000000 10064386160 10549682176 1104989888 11.885 99 19 38 0 1175463568 66653584 3820096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-10-2020 04:29:09 to 31-10-2020 04:29:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 112 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-10-2020 04:29:25 to 31-10-2020 04:29:27.

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          231003
MON$OLDEST_ACTIVE               231907
MON$OLDEST_SNAPSHOT             231907
MON$NEXT_TRANSACTION            231907
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                       2084256
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             779
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 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.2242 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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Sat Oct 31 04:27:16 2020
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Sat Oct 31 04:27:16 2020
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Oct 31 04:27:21 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

31.10.20 04:29:28. 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-31 04:29:30 No severe PSQL-related problems occured
31.10.20 04:29:32. End of report.