Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.02.2021 13:27
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 16451588 kB
MemAvailable 31127148 kB
Buffers 0 kB
Cached 15196868 kB
SwapCached 4092 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-16 13:26:57
DTS_END                         2021-02-16 13:26:57.4510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53396
TRN_ID                          237821
ATT_ID                          612
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: 8990.22 23496 1078826
customer order: creation 169.87 386 20384
customer order: refuse 14.59 339 1751
order to supplier: creation 44.19 1311 5303
order to supplier: removal 3.38 115881 405
invoice (draft): creation 45.36 3441 5443
invoice (draft): removal 51.43 879 6171
invoice accept: apply 41.22 17159 4946
invoice accept: cancel 45.91 881 5509
customer reserve: creation 3837.18 167 460462
customer reserve: removal 4451.68 71 534202
realization accept: apply 82.93 176 9951
realization accept: cancel 74.70 124 8964
payment from customer: creation 58.83 120 7060
payment from customer: removal 5.69 93 683
payment to supplier: creation 49.08 129 5890
payment to supplier: removal 3.33 79 399
service: total inventory turnovers 3.78 3302 454
service: total monetary turnovers 6.11 465 733
service: aggregate perf. data 0.93 16582 112
service: refresh index statistics 0.03 308340 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-02-2021 13:27:56 to 16-02-2021 13:27:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4895
TEST_TIME 2 3333
TEST_TIME 3 3554
TEST_TIME 4 3881
TEST_TIME 5 4655
TEST_TIME 6 4722
TEST_TIME 7 5093
TEST_TIME 8 5321
TEST_TIME 9 5441
TEST_TIME 10 5613
TEST_TIME 11 5704
TEST_TIME 12 5905
TEST_TIME 13 6134
TEST_TIME 14 6213
TEST_TIME 15 6413
TEST_TIME 16 6469
TEST_TIME 17 6713
TEST_TIME 18 7334
TEST_TIME 19 7344
TEST_TIME 20 7454
TEST_TIME 21 7805
TEST_TIME 22 8164
TEST_TIME 23 8521
TEST_TIME 24 8648
TEST_TIME 25 8727
TEST_TIME 26 9216
TEST_TIME 27 10154
TEST_TIME 28 10982
TEST_TIME 29 10814
TEST_TIME 30 10717
TEST_TIME 31 10602
TEST_TIME 32 10402
TEST_TIME 33 10176
TEST_TIME 34 10253
TEST_TIME 35 10217
TEST_TIME 36 10151
TEST_TIME 37 10059
TEST_TIME 38 9962
TEST_TIME 39 9901
TEST_TIME 40 9816
TEST_TIME 41 9640
TEST_TIME 42 9673
TEST_TIME 43 9682
TEST_TIME 44 9621
TEST_TIME 45 9655
TEST_TIME 46 9661
TEST_TIME 47 9687
TEST_TIME 48 9677
TEST_TIME 49 9615
TEST_TIME 50 9724
TEST_TIME 51 9795
TEST_TIME 52 9834
TEST_TIME 53 9799
TEST_TIME 54 9833
TEST_TIME 55 9876
TEST_TIME 56 9928
TEST_TIME 57 9881
TEST_TIME 58 9721
TEST_TIME 59 9648
TEST_TIME 60 9590
TEST_TIME 61 9512
TEST_TIME 62 9480
TEST_TIME 63 9431
TEST_TIME 64 9409
TEST_TIME 65 9416
TEST_TIME 66 9363
TEST_TIME 67 9418
TEST_TIME 68 9426
TEST_TIME 69 9441
TEST_TIME 70 9387
TEST_TIME 71 9442
TEST_TIME 72 9496
TEST_TIME 73 9472
TEST_TIME 74 9448
TEST_TIME 75 9564
TEST_TIME 76 9501
TEST_TIME 77 9382
TEST_TIME 78 9329
TEST_TIME 79 9251
TEST_TIME 80 9201
TEST_TIME 81 9203
TEST_TIME 82 9159
TEST_TIME 83 9145
TEST_TIME 84 9163
TEST_TIME 85 9139
TEST_TIME 86 9141
TEST_TIME 87 9157
TEST_TIME 88 9226
TEST_TIME 89 9256
TEST_TIME 90 9278
TEST_TIME 91 9438
TEST_TIME 92 9398
TEST_TIME 93 9336
TEST_TIME 94 9316
TEST_TIME 95 9246
TEST_TIME 96 9230
TEST_TIME 97 9195
TEST_TIME 98 9184
TEST_TIME 99 9152
TEST_TIME 100 9165
TEST_TIME 101 9132
TEST_TIME 102 9197
TEST_TIME 103 9183
TEST_TIME 104 9162
TEST_TIME 105 9188
TEST_TIME 106 9253
TEST_TIME 107 9259
TEST_TIME 108 9240
TEST_TIME 109 9258
TEST_TIME 110 9261
TEST_TIME 111 9193
TEST_TIME 112 9159
TEST_TIME 113 9116
TEST_TIME 114 9066
TEST_TIME 115 9052
TEST_TIME 116 9023
TEST_TIME 117 9036
TEST_TIME 118 9014
TEST_TIME 119 8996
TEST_TIME 120 8991

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-02-2021 13:27:59 to 16-02-2021 13:28:03.

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 1503302 1503302 0 0.00 0 23368 6 0 0
doc_list_biud 1503299 1503299 0 0.00 0 35904 31 0 0
sp_add_invoice_to_stock 5062 4946 116 2.29 36 176627 17160 0 116
sp_cancel_adding_invoice 5509 5509 0 0.00 5 95632 881 0 0
sp_cancel_client_order 1751 1751 0 0.00 5 24189 339 0 0
sp_cancel_customer_reserve 534202 534202 0 0.00 1 17612 72 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 2 6637 94 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 8889 79 0 0
sp_cancel_supplier_invoice 6177 6171 6 0.09 2 25156 879 0 6
sp_cancel_supplier_order 405 405 0 0.00 12 1172325 115882 0 0
sp_cancel_write_off 8964 8964 0 0.00 1 9684 124 0 0
sp_client_order 20384 20384 0 0.00 4 56469 386 0 0
sp_customer_reserve 460462 460462 0 0.00 0 104048 167 0 0
sp_fill_shopping_cart 31115 31115 0 0.00 1 16903 43 0 0
sp_fill_shopping_cart_clo_res 460458 460458 0 0.00 0 20680 17 0 0
sp_get_clo_for_invoice 4942 4942 0 0.00 0 31901 356 0 0
sp_kill_cost_storno 15555 15555 0 0.00 0 9593 54 0 0
sp_kill_qstorno_ret_qs2qd 1083944 1083944 0 0.00 0 18966 38 0 0
sp_kill_qty_storno 571895 571895 0 0.00 0 35904 82 0 0
sp_lock_selected_doc 20772 20772 0 0.00 0 9283 7 0 0
sp_make_cost_storno 27843 27843 0 0.00 0 17105 56 0 0
sp_multiply_rows_for_pdistr 27842 27842 0 0.00 0 17133 31 0 0
sp_multiply_rows_for_qdistr 20384 20384 0 0.00 0 56459 221 0 0
sp_pay_from_customer 7060 7060 0 0.00 4 15876 121 0 0
sp_pay_to_supplier 5890 5890 0 0.00 5 17199 130 0 0
sp_payment_common 12948 12948 0 0.00 1 13372 28 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 24180 306 0 0
sp_qd_handle_on_invoice_upd_sts 10451 10451 0 0.00 0 35904 369 0 0
sp_qd_handle_on_reserve_upd_sts 9951 9951 0 0.00 0 8698 47 0 0
sp_reserve_write_off 9951 9951 0 0.00 7 17043 176 0 0
sp_supplier_invoice 5443 5443 0 0.00 5 100650 3441 0 0
sp_supplier_order 5303 5303 0 0.00 5 34043 1311 0 0
srv_aggregate_perf_data 112 112 0 0.00 2509 281384 16583 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1569 155062 47149 0 0
srv_find_qd_qs_mism 1030591 1030591 0 0.00 0 23367 5 0 0
srv_make_invnt_saldo 454 454 0 0.00 38 83050 3302 0 0
srv_make_money_saldo 733 733 0 0.00 1 11139 465 0 0
srv_recalc_idx_stat 4 4 0 0.00 238651 447556 308340 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4946 4946 0 0.00 0 10976 173 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 6374 105 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 4767 18 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 780 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 649 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 559 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 706 11 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 19848 1443 0 0
t$perf-norm:sp_cancel_write_off 1077 1077 0 0.00 0 9033 22 0 0
t$perf-norm:sp_client_order 20384 20384 0 0.00 0 8974 18 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 464 2 0 0
t$perf-norm:sp_pay_from_customer 7060 7060 0 0.00 0 3354 8 0 0
t$perf-norm:sp_pay_to_supplier 5890 5890 0 0.00 0 9007 10 0 0
t$perf-norm:sp_reserve_write_off 9951 9951 0 0.00 0 9040 13 0 0
t$perf-norm:sp_supplier_invoice 5443 5443 0 0.00 0 6452 18 0 0
t$perf-norm:sp_supplier_order 5303 5303 0 0.00 0 9333 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 147 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 303 7 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 1255 11 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 1434 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10116 9884 232 2.29 0 6189 16 0 232
v_all_customers 37584 37584 0 0.00 0 20 1 0 0
v_all_suppliers 29972 29972 0 0.00 0 60 1 0 0
v_all_wares 40768 40768 0 0.00 0 177 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 4930 14 0 0
v_cancel_client_order 3502 3502 0 0.00 0 2464 4 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 6631 12 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 2463 6 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 822 5 0 0
v_cancel_supplier_invoice 890 878 12 1.34 0 1082 14 0 12
v_cancel_supplier_order 810 810 0 0.00 0 327 3 0 0
v_cancel_write_off 2154 2154 0 0.00 0 2488 13 0 0
v_max_id_clo_ord 5302 5302 0 0.00 0 430 1 0 0
v_max_id_clo_res 944 944 0 0.00 0 2990 20 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 4346 3 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 7709 7 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 1064 3 0 0
v_min_id_clo_ord 5302 5302 0 0.00 0 3442 8 0 0
v_min_id_clo_res 944 944 0 0.00 0 9341 13 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 8464 11 0 0
v_min_non_paid_invoice 5890 5890 0 0.00 0 9664 18 0 0
v_min_non_paid_realizn 7058 7058 0 0.00 0 13366 23 0 0
v_our_firm 10008 10008 0 0.00 0 15 1 0 0
v_reserve_write_off 19902 19902 0 0.00 0 4240 6 0 0
x_lock_depdocs_on_canc_invoice 11241 11241 0 0.00 0 12211 113 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 10590 341 0 0
x_make_qstorno_1000_1200 5303 5303 0 0.00 1 33993 1228 0 0
x_make_qstorno_1200_2000 5443 5443 0 0.00 2 98566 3287 0 0
x_make_qstorno_2100_3300 460462 460462 0 0.00 0 104046 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-02-2021 13:28:04 to 16-02-2021 13:28:08.

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:01 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 9372 8192000000 9715033152 10011410432 796489344 8.861 100 91 182 0 825661280 7511568 21660368
10:59:14 18389 8192000000 9753140960 10059665408 833806272 9.238 100 85 170 0 865250304 10948464 20495568
11:00:37 12010 8192000000 9775532736 10088898560 851240608 9.413 100 86 172 0 889365456 17873328 20251520
11:01:54 12509 8192000000 9827089104 10151780352 882160192 9.721 100 77 154 4 936431280 37176816 17094272
11:03:09 10467 8192000000 9892781504 10230263808 943579280 10.328 100 88 175 0 1005594096 43165840 18848976
11:04:24 7267 8192000000 9952506224 10303393792 986793600 10.750 100 86 171 2 1065160832 60374096 17993136
11:05:34 3277 8192000000 9999196896 10356408320 1015497664 11.029 100 88 176 0 1113991920 80982464 17511792
11:06:38 2355 8192000000 10016427600 10379669504 1036680272 11.233 100 85 169 0 1125880000 72456928 16742800
11:07:43 1349 8192000000 10069698448 10438615040 1060201536 11.458 100 90 180 1 1178598800 100141984 18255280
11:08:45 5357 8192000000 9986526288 10369728512 1068339728 11.536 100 55 110 1 1097049040 16239520 12469792
11:09:51 5096 8192000000 10003060656 10387615744 1073629296 11.587 100 45 88 0 1112479248 29765888 9084064
11:10:57 4584 8192000000 10001384752 10410311680 1084436672 11.690 100 19 38 0 1123801152 35494752 3869728
11:12:06 14171 8192000000 9999517168 10407989248 1090911792 11.751 100 21 42 1 1122542624 26994000 4636832
11:13:22 48710 8192000000 10003316448 10419232768 1092117008 11.763 100 30 59 2 1108747968 9153056 7477904
11:15:12 8838 8192000000 10005717888 10413010944 1088764672 11.731 99 35 70 0 1132157056 35498144 7894240
11:16:22 6522 8192000000 10019921264 10414551040 1097679072 11.816 100 47 94 2 1130379200 22933488 9766640
11:17:32 11972 8192000000 9994115040 10402615296 1054875360 11.407 97 15 29 0 1092365488 34231472 3258656
11:18:45 43148 8192000000 9942773648 10331602944 944248160 10.335 88 31 61 1 972152224 21426608 6477456
11:20:29 3700 8192000000 9936500896 10314358784 1024549040 11.116 99 32 63 0 1051392976 20213216 6630720
11:21:33 12460 8192000000 9928597920 10313621504 993752288 10.818 98 35 70 0 1020193088 18617632 7823168
11:22:47 75458 8192000000 9936709168 10337894400 1036108240 11.227 100 24 47 3 1055628784 13117936 6402608
11:25:03 5717 8192000000 9985786576 10381737984 1050473152 11.365 100 39 78 1 1095087568 36185168 8429248
11:26:10 9638 8192000000 9977032976 10384347136 1060829504 11.464 100 34 68 3 1080080864 11298384 7952976
11:27:27 19712 8192000000 10038807872 10431369216 1091756192 11.759 100 55 111 0 1143118336 39362576 11999568
11:28:56 47815 8192000000 10064784176 10461368320 1106227296 11.897 100 41 82 0 1154064912 39098656 8738960
11:30:49 10408 8192000000 10097031664 10506014720 1122755696 12.053 100 51 100 0 1209918240 75855584 11306960
11:32:03 14312 8192000000 10100008336 10507034624 1130961040 12.130 100 54 108 1 1214360176 71748352 11650784
11:33:25 26941 8192000000 10132794480 10549141504 1132691296 12.147 100 57 114 0 1250324416 104307424 13325696
11:34:59 19551 8192000000 10143681920 10575003648 1150538416 12.315 100 40 80 1 1260612784 101537104 8537264
11:36:22 10844 8192000000 10175265072 10608762880 1161803296 12.420 100 63 126 1 1294229104 118187712 14238096
11:37:35 5918 8192000000 10219399568 10623000576 1167468352 12.473 100 69 138 1 1328336912 144644928 16223632
11:38:46 5816 8192000000 10239416416 10648219648 1158110880 12.386 99 70 139 1 1332604896 159369904 15124112
11:40:01 1902 8192000000 10272889168 10679889920 1167525840 12.474 100 74 147 0 1375284160 191263904 16494416
11:41:08 1568 8192000000 10320624336 10731339776 1176633504 12.559 100 84 166 0 1426691312 230902240 19155568
11:42:14 3667 8192000000 10376415360 10778402816 1176795872 12.560 100 79 158 1 1473767120 277842224 19129024
11:43:22 3987 8192000000 10418127056 10831265792 1161553008 12.418 100 75 149 1 1527414880 348009552 17852320
11:44:30 1609 8192000000 10459606464 10866782208 1178374144 12.575 100 76 152 0 1561370048 365533248 17462656
11:45:36 2142 8192000000 10452906864 10859606016 1169037424 12.488 100 89 179 0 1549372464 358888224 21446816
11:46:42 4502 8192000000 10465402864 10896486400 1164654704 12.447 100 45 90 1 1568870112 391985344 12230064
11:47:55 5647 8192000000 10471491664 10887249920 1159472000 12.398 100 54 108 0 1568524976 396231552 12821424
11:49:24 14394 8192000000 10465288464 10924408832 1158927952 12.393 100 36 72 0 1556705712 387982400 9795360
11:50:39 20137 8192000000 10469654336 10926784512 1159634768 12.400 100 30 60 3 1574396624 406887408 7874448
11:52:33 39934 8192000000 10378420640 10835484672 1130923936 12.130 100 23 47 0 1510416944 372796528 6696480
11:54:14 44770 8192000000 10077157584 10568089600 1097525600 11.814 97 6 12 0 1158548048 59879040 1143408
11:56:04 54697 8192000000 10073985552 10498572288 1085012608 11.695 97 32 65 0 1148842336 57079760 6749968
11:58:04 117092 8192000000 10098198240 10559275008 1118197184 12.010 99 27 54 0 1195074672 70853616 6023872
12:01:20 16299 8192000000 10088818032 10509230080 1099292496 11.831 97 55 110 1 1161143072 50468912 11381664
12:02:38 23987 8192000000 10061250448 10521706496 1113788704 11.968 99 14 28 1 1163381744 46610464 2982576
12:04:11 87066 8192000000 10072724672 10527432704 1091395616 11.756 97 9 18 0 1127023360 33751808 1875936
12:06:39 52416 8192000000 10135004512 10580422656 1137380880 12.191 100 11 22 0 1202989008 63499792 2108336
12:08:42 94378 8192000000 10080567296 10542718976 1135586704 12.174 100 23 46 0 1248675344 107626288 5462352
12:11:52 58804 8192000000 10089695760 10562166784 1135254800 12.171 99 13 26 0 1169302048 31225584 2821664
12:13:55 52930 8192000000 10069585840 10527191040 1109674224 11.929 97 21 41 1 1160780944 46570720 4536000
12:15:51 65228 8192000000 10092964912 10546315264 1145397616 12.266 100 14 28 1 1210500656 62107008 2996032
12:17:56 63750 8192000000 10093874736 10564050944 1127854160 12.101 98 19 38 0 1176321248 44358256 4108832
12:20:00 53393 8192000000 10138365632 10608873472 1141742064 12.232 99 23 46 0 1218580080 71945200 4892816
12:21:58 52290 8192000000 10111516128 10561007616 1146982608 12.281 99 20 40 2 1216120960 65109280 4029072
12:23:50 91897 8192000000 10069529696 10512359424 1116071920 11.990 97 21 42 0 1156027248 35815824 4139504
12:26:28 69777 8192000000 10064325040 10541105152 1124650560 12.071 98 9 18 1 1162517440 36323984 1542896
12:28:46 64098 8192000000 10057708544 10503475200 1077413920 11.623 94 9 18 0 1099450224 20705728 1330576
12:30:50 46014 8192000000 10067769408 10493116416 1054762576 11.406 93 28 56 3 1106105280 45191440 6151264
12:32:44 52858 8192000000 10098704624 10552926208 1112542352 11.956 98 33 66 0 1177995104 58911328 6541424
12:34:37 78520 8192000000 10058480928 10522107904 1047399952 11.336 93 14 28 1 1133266944 82724304 3142688
12:36:55 17618 8192000000 10085650688 10500227072 1101131456 11.848 99 50 99 1 1162160032 51160576 9868000
12:38:15 60522 8192000000 10054250032 10529808384 1084848976 11.694 97 24 48 1 1139996128 50456304 4690848
12:40:16 12393 8192000000 10081162944 10505773056 1114790544 11.978 100 58 116 1 1167564496 41123840 11650112
12:41:31 78218 8192000000 10052196688 10510880768 1127448032 12.097 100 21 42 0 1179957648 48080272 4429344
12:44:29 81825 8192000000 10075476224 10546610176 1118721584 12.015 99 16 32 0 1180421680 58231248 3468848
12:47:08 48084 8192000000 10058231440 10507182080 1124441904 12.069 100 20 39 0 1172633232 43977104 4214224
12:48:58 58266 8192000000 10082191360 10533376000 1107896496 11.912 98 30 59 1 1176648896 62401920 6350480
12:50:57 54855 8192000000 10115532624 10560688128 1111810704 11.950 98 23 46 0 1194730768 77740784 5179280
12:52:52 66959 8192000000 10201188272 10688196608 1139346352 12.209 100 24 48 0 1261058416 116086192 5625872
12:55:37 59731 8192000000 10175321280 10610786304 1106967440 11.904 97 29 58 0 1218960624 105122832 6870352
12:59:13 65242 8192000000 10092944112 10531676160 1127372496 12.097 99 37 74 1 1167044944 32559616 7112832
13:01:35 74958 8192000000 10107084224 10590982144 1133524368 12.155 100 7 14 0 1196910816 62063040 1323408
13:03:50 76897 8192000000 10103345200 10555678720 1133611952 12.155 99 11 22 0 1181373424 45581584 2179888
13:06:09 50084 8192000000 10095155616 10535456768 1145800544 12.270 99 17 33 2 1210409200 60766384 3842272
13:08:05 73542 8192000000 10099133440 10544287744 1135445456 12.173 98 22 44 0 1190071744 49762112 4864176
13:10:23 64146 8192000000 10133894480 10624942080 1129615760 12.118 97 23 46 1 1195347920 61241008 4491152
13:12:34 155061 8192000000 10104332768 10546339840 1087634240 11.720 94 19 38 2 1124959936 33405680 3920016
13:16:09 127615 8192000000 10091701712 10575867904 1107800704 11.912 97 16 32 0 1184981152 73646608 3533840
13:20:41 50481 8192000000 10056977072 10528923648 1124519168 12.070 99 12 24 0 1172056608 45373120 2164320
13:23:57 72146 8192000000 10080493440 10521448448 1093180112 11.773 96 14 28 0 1132048672 35962816 2905744
13:26:10 26244 8192000000 10054718976 10499010560 1101989696 11.857 97 22 45 0 1136099664 29112464 4997504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-02-2021 13:28:10 to 16-02-2021 13:28:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-02-2021 13:28:25 to 16-02-2021 13:28: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          234196
MON$OLDEST_ACTIVE               237937
MON$OLDEST_SNAPSHOT             237937
MON$NEXT_TRANSACTION            237937
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                       2100800
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2366 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2366 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2366 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.2366 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 ---
--- end of diff output ---

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

16.02.21 13:28: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
2021-02-16 13:28:31 No severe PSQL-related problems occured
16.02.21 13:28:32. End of report.