Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.06.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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 16655608 kB
MemAvailable 31373732 kB
Buffers 0 kB
Cached 14969400 kB
SwapCached 1468 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.1
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-06-16 13:27:00
DTS_END                         2021-06-16 13:27:00.6680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51304
TRN_ID                          230083
ATT_ID                          674
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: 9048.12 26256 1085774
customer order: creation 160.84 415 19301
customer order: refuse 14.55 395 1746
order to supplier: creation 43.96 1404 5275
order to supplier: removal 3.42 145853 410
invoice (draft): creation 44.25 3579 5310
invoice (draft): removal 51.37 1156 6164
invoice accept: apply 40.70 20337 4884
invoice accept: cancel 47.04 1159 5645
customer reserve: creation 3758.02 197 450962
customer reserve: removal 4592.45 84 551094
realization accept: apply 81.28 188 9754
realization accept: cancel 85.71 146 10285
payment from customer: creation 58.25 115 6990
payment from customer: removal 5.52 132 662
payment to supplier: creation 46.66 129 5599
payment to supplier: removal 3.39 107 407
service: total inventory turnovers 3.52 4348 422
service: total monetary turnovers 6.25 627 750
service: aggregate perf. data 0.92 16269 110
service: refresh index statistics 0.03 328473 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-06-2021 13:27:52 to 16-06-2021 13:27:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7244
TEST_TIME 2 6101
TEST_TIME 3 5866
TEST_TIME 4 6035
TEST_TIME 5 6625
TEST_TIME 6 6203
TEST_TIME 7 5995
TEST_TIME 8 5955
TEST_TIME 9 6045
TEST_TIME 10 6022
TEST_TIME 11 5877
TEST_TIME 12 5960
TEST_TIME 13 6092
TEST_TIME 14 6194
TEST_TIME 15 6278
TEST_TIME 16 6414
TEST_TIME 17 6833
TEST_TIME 18 6931
TEST_TIME 19 7090
TEST_TIME 20 7263
TEST_TIME 21 7220
TEST_TIME 22 7410
TEST_TIME 23 7320
TEST_TIME 24 7858
TEST_TIME 25 7879
TEST_TIME 26 8513
TEST_TIME 27 8342
TEST_TIME 28 8254
TEST_TIME 29 8519
TEST_TIME 30 9392
TEST_TIME 31 10033
TEST_TIME 32 11348
TEST_TIME 33 11239
TEST_TIME 34 10997
TEST_TIME 35 10927
TEST_TIME 36 10718
TEST_TIME 37 10584
TEST_TIME 38 10382
TEST_TIME 39 10365
TEST_TIME 40 10192
TEST_TIME 41 10184
TEST_TIME 42 10329
TEST_TIME 43 10286
TEST_TIME 44 10166
TEST_TIME 45 10095
TEST_TIME 46 10021
TEST_TIME 47 10001
TEST_TIME 48 9883
TEST_TIME 49 9768
TEST_TIME 50 9794
TEST_TIME 51 9730
TEST_TIME 52 9642
TEST_TIME 53 9626
TEST_TIME 54 9709
TEST_TIME 55 9735
TEST_TIME 56 9724
TEST_TIME 57 9733
TEST_TIME 58 9808
TEST_TIME 59 9716
TEST_TIME 60 9796
TEST_TIME 61 9757
TEST_TIME 62 9759
TEST_TIME 63 9901
TEST_TIME 64 9754
TEST_TIME 65 9654
TEST_TIME 66 9600
TEST_TIME 67 9537
TEST_TIME 68 9466
TEST_TIME 69 9383
TEST_TIME 70 9426
TEST_TIME 71 9382
TEST_TIME 72 9354
TEST_TIME 73 9340
TEST_TIME 74 9372
TEST_TIME 75 9444
TEST_TIME 76 9386
TEST_TIME 77 9414
TEST_TIME 78 9461
TEST_TIME 79 9447
TEST_TIME 80 9451
TEST_TIME 81 9507
TEST_TIME 82 9508
TEST_TIME 83 9609
TEST_TIME 84 9498
TEST_TIME 85 9400
TEST_TIME 86 9359
TEST_TIME 87 9287
TEST_TIME 88 9209
TEST_TIME 89 9170
TEST_TIME 90 9252
TEST_TIME 91 9203
TEST_TIME 92 9172
TEST_TIME 93 9151
TEST_TIME 94 9127
TEST_TIME 95 9130
TEST_TIME 96 9152
TEST_TIME 97 9126
TEST_TIME 98 9145
TEST_TIME 99 9194
TEST_TIME 100 9257
TEST_TIME 101 9231
TEST_TIME 102 9272
TEST_TIME 103 9289
TEST_TIME 104 9206
TEST_TIME 105 9162
TEST_TIME 106 9123
TEST_TIME 107 9093
TEST_TIME 108 9054
TEST_TIME 109 9019
TEST_TIME 110 9012
TEST_TIME 111 9027
TEST_TIME 112 9047
TEST_TIME 113 9035
TEST_TIME 114 9020
TEST_TIME 115 9006
TEST_TIME 116 9035
TEST_TIME 117 9027
TEST_TIME 118 9132
TEST_TIME 119 9127
TEST_TIME 120 9051

Performance per minute, chart


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

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 1501824 1501824 0 0.00 0 19095 8 0 0
doc_list_biud 1501824 1501824 0 0.00 0 81813 39 0 0
sp_add_invoice_to_stock 5009 4884 125 2.49 28 171504 20338 0 125
sp_cancel_adding_invoice 5645 5645 0 0.00 6 85830 1159 0 0
sp_cancel_client_order 1746 1746 0 0.00 5 7235 396 0 0
sp_cancel_customer_reserve 551094 551094 0 0.00 1 27126 85 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 9351 132 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 11412 108 0 0
sp_cancel_supplier_invoice 6169 6164 5 0.08 2 81818 1157 0 5
sp_cancel_supplier_order 410 410 0 0.00 7 1396149 145853 0 0
sp_cancel_write_off 10285 10285 0 0.00 1 29969 146 0 0
sp_client_order 19301 19301 0 0.00 4 32436 416 0 0
sp_customer_reserve 450962 450962 0 0.00 0 41077 198 0 0
sp_fill_shopping_cart 29881 29881 0 0.00 1 11466 45 0 0
sp_fill_shopping_cart_clo_res 450960 450960 0 0.00 0 23571 17 0 0
sp_get_clo_for_invoice 4882 4882 0 0.00 0 38605 415 0 0
sp_kill_cost_storno 16999 16999 0 0.00 0 9346 74 0 0
sp_kill_qstorno_ret_qs2qd 1119047 1119047 0 0.00 0 81811 45 0 0
sp_kill_qty_storno 589980 589980 0 0.00 0 81812 98 0 0
sp_lock_selected_doc 20272 20272 0 0.00 0 11644 5 0 0
sp_make_cost_storno 27225 27225 0 0.00 0 12363 54 0 0
sp_multiply_rows_for_pdistr 27225 27225 0 0.00 0 8003 35 0 0
sp_multiply_rows_for_qdistr 19301 19301 0 0.00 0 21498 234 0 0
sp_pay_from_customer 6990 6990 0 0.00 4 12313 115 0 0
sp_pay_to_supplier 5599 5599 0 0.00 5 11831 129 0 0
sp_payment_common 12589 12589 0 0.00 1 9703 22 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 5522 359 0 0
sp_qd_handle_on_invoice_upd_sts 10527 10527 0 0.00 0 38530 501 0 0
sp_qd_handle_on_reserve_upd_sts 9754 9754 0 0.00 0 31959 61 0 0
sp_reserve_write_off 9754 9754 0 0.00 7 32291 189 0 0
sp_supplier_invoice 5310 5310 0 0.00 7 82277 3580 0 0
sp_supplier_order 5275 5275 0 0.00 6 98988 1404 0 0
srv_aggregate_perf_data 110 110 0 0.00 2710 169664 16270 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1504 149462 43202 0 0
srv_find_qd_qs_mism 1039076 1039076 0 0.00 0 19093 7 0 0
srv_make_invnt_saldo 422 422 0 0.00 42 107167 4348 0 0
srv_make_money_saldo 750 750 0 0.00 1 11971 627 0 0
srv_recalc_idx_stat 4 4 0 0.00 141876 585734 328474 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4884 4884 0 0.00 0 9506 209 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 3792 111 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 4320 19 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 3236 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 491 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1047 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 6881 48 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 28150 1709 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 2265 8 0 0
t$perf-norm:sp_client_order 19301 19301 0 0.00 0 7124 20 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 763 5 0 0
t$perf-norm:sp_pay_from_customer 6990 6990 0 0.00 0 5061 9 0 0
t$perf-norm:sp_pay_to_supplier 5600 5600 0 0.00 0 3112 9 0 0
t$perf-norm:sp_reserve_write_off 9754 9754 0 0.00 0 6754 11 0 0
t$perf-norm:sp_supplier_invoice 5310 5310 0 0.00 0 4050 21 0 0
t$perf-norm:sp_supplier_order 5275 5275 0 0.00 0 10715 24 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 485 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 121 4 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 2516 16 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 2775 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 52 15 0 0
v_add_invoice_to_stock 10014 9764 250 2.49 0 7553 19 0 250
v_all_customers 35366 35366 0 0.00 0 112 1 0 0
v_all_suppliers 29184 29184 0 0.00 0 18 1 0 0
v_all_wares 38602 38602 0 0.00 0 114 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1034 8 0 0
v_cancel_client_order 3492 3492 0 0.00 0 4838 7 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 5642 16 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 1533 8 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 4682 12 0 0
v_cancel_supplier_invoice 736 726 10 1.35 0 579 14 0 10
v_cancel_supplier_order 820 820 0 0.00 0 2317 10 0 0
v_cancel_write_off 1888 1888 0 0.00 0 9071 20 0 0
v_max_id_clo_ord 5275 5275 0 0.00 0 2983 2 0 0
v_max_id_clo_res 968 968 0 0.00 0 1576 20 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 5789 6 0 0
v_max_non_paid_invoice 1587 1587 0 0.00 0 246 2 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 2188 3 0 0
v_min_id_clo_ord 5275 5275 0 0.00 0 2803 7 0 0
v_min_id_clo_res 968 968 0 0.00 0 8942 27 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 4662 8 0 0
v_min_non_paid_invoice 5599 5599 0 0.00 0 7447 17 0 0
v_min_non_paid_realizn 6990 6990 0 0.00 0 6299 13 0 0
v_our_firm 9856 9856 0 0.00 0 14 1 0 0
v_reserve_write_off 19508 19508 0 0.00 0 4890 5 0 0
x_lock_depdocs_on_canc_invoice 11446 11446 0 0.00 0 10591 140 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 11537 431 0 0
x_make_qstorno_1000_1200 5275 5275 0 0.00 2 98982 1302 0 0
x_make_qstorno_1200_2000 5310 5310 0 0.00 3 79081 3423 0 0
x_make_qstorno_2100_3300 450962 450962 0 0.00 0 41076 177 0 0

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

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:04 4 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
10:58:06 34122 8192000000 9718492304 10015571968 798130944 8.877 100 88 176 0 827545552 7917328 21497280
10:59:47 24462 8192000000 9764076448 10069852160 837440000 9.274 100 89 178 1 870376592 12230240 20706352
11:01:14 13742 8192000000 9795582224 10112393216 862652560 9.527 100 79 158 0 904647024 23836560 18157904
11:02:37 12317 8192000000 9845344208 10177974272 902761888 9.926 100 75 151 1 957001936 38376768 15863280
11:03:51 11434 8192000000 9920468944 10269011968 953181536 10.422 100 73 146 2 1037615472 69642176 14791760
11:05:03 6254 8192000000 9954072320 10305863680 994803520 10.828 100 79 156 1 1072165776 61279728 16082528
11:06:14 7351 8192000000 9974490608 10345967616 1019585184 11.068 100 65 129 2 1100571936 67964192 13022560
11:07:23 1661 8192000000 10020469216 10391609344 1048169504 11.343 100 89 179 0 1135262944 68949088 18144352
11:08:26 1766 8192000000 10052958784 10421817344 1062629888 11.482 100 93 188 0 1168819792 86412784 19777120
11:09:29 12592 8192000000 10002077968 10381004800 1072372256 11.575 100 61 121 4 1109241856 24250224 12619376
11:10:43 10323 8192000000 9984200496 10374959104 1077751040 11.626 100 33 65 0 1112360352 27381392 7227920
11:11:55 5849 8192000000 9996010512 10389520384 1087562192 11.719 100 36 74 1 1120714864 25935872 7216800
11:13:02 62104 8192000000 10004082528 10418921472 1089815600 11.741 100 36 71 1 1120756688 23176416 7764672
11:15:05 5740 8192000000 10005051216 10409857024 1092114768 11.763 100 32 64 1 1122108784 23344240 6649776
11:16:12 10303 8192000000 10025218048 10437234688 1099180080 11.830 100 27 53 0 1122491136 17498912 5812144
11:17:26 5195 8192000000 10023755488 10420375552 1094181088 11.782 100 50 100 1 1134133808 28818928 11133792
11:18:33 36798 8192000000 9986279680 10402938880 1026775440 11.137 94 26 52 1 1055975312 23238864 5961008
11:20:10 4805 8192000000 9958051648 10339487744 1011416496 10.989 96 39 77 0 1041067056 20993712 8656848
11:21:15 6034 8192000000 9936472112 10313969664 1011083440 10.986 97 31 63 0 1031043872 12085584 7874848
11:22:23 6599 8192000000 9933483664 10319331328 988813184 10.770 96 33 65 1 1014102320 18142208 7146928
11:23:30 40586 8192000000 9947771200 10347368448 1034563392 11.212 100 37 71 3 1057214736 15524544 7126800
11:25:11 7879 8192000000 9967706512 10357493760 1049786512 11.359 100 36 71 2 1074502624 17772368 6943744
11:26:20 10827 8192000000 9975233232 10379665408 1057616544 11.434 100 33 66 1 1085099472 19975616 7507312
11:27:40 49852 8192000000 10007925328 10411012096 1076698096 11.616 100 51 99 1 1123688464 36544576 10445792
11:29:36 25336 8192000000 10035970032 10443292672 1090014512 11.743 100 43 84 0 1145794064 46778256 9001296
11:31:12 32887 8192000000 10063570112 10481246208 1105052544 11.886 100 43 85 3 1174215696 60041232 9121920
11:32:53 16948 8192000000 10081624736 10489405440 1112764704 11.959 100 55 110 0 1195083248 71002192 11316352
11:34:16 9387 8192000000 10114561776 10520285184 1125316592 12.077 100 60 120 1 1223376176 85479056 12580528
11:35:31 11871 8192000000 10160634528 10568871936 1137264720 12.190 100 59 116 0 1263456944 113547568 12644656
11:36:48 8610 8192000000 10223870704 10632601600 1163451888 12.436 100 75 147 1 1323812944 144552896 15808160
11:38:06 11206 8192000000 10218304880 10633293824 1165031456 12.450 100 42 84 1 1327146832 152476832 9638544
11:39:21 7751 8192000000 10282252144 10697011200 1175696848 12.550 100 62 123 0 1381178016 191836288 13644880
11:40:40 4164 8192000000 10335185728 10747678720 1189560016 12.679 100 68 136 1 1433044928 228252352 15232560
11:41:51 1500 8192000000 10359706128 10760404992 1176976560 12.562 99 93 184 0 1450837456 254124240 19736656
11:42:56 1972 8192000000 10404699504 10812428288 1191496752 12.697 100 89 176 0 1507460576 296796000 19167824
11:44:01 1503 8192000000 10457075024 10866610176 1179342784 12.584 100 83 166 0 1552604016 354320288 18940944
11:45:10 1564 8192000000 10444469072 10857238528 1188368352 12.668 100 81 163 1 1538793584 331512736 18912496
11:46:17 1542 8192000000 10466810144 10869518336 1156698960 12.372 99 82 163 0 1569146304 393510608 18936736
11:47:27 1746 8192000000 10472152496 10884538368 1161732496 12.419 100 95 189 1 1565470304 381263840 22473968
11:48:35 1975 8192000000 10518995392 10919292928 1164225504 12.443 100 92 185 1 1616057312 429037760 22794048
11:49:45 5726 8192000000 10565082560 10971566080 1179132048 12.582 100 91 184 1 1665858256 465108320 21617888
11:50:56 1808 8192000000 10632693920 11046133760 1188589968 12.670 100 84 168 0 1725570320 516201520 20778832
11:52:04 12337 8192000000 10604540976 11035357184 1191991744 12.702 100 53 107 0 1710086496 503963344 14131408
11:53:25 4252 8192000000 10594130064 11060883456 1206098320 12.833 100 48 96 0 1697779056 478717168 12963568
11:54:33 9278 8192000000 10629028304 11065647104 1196619488 12.745 100 48 96 0 1722736496 513802400 12314608
11:55:47 8694 8192000000 10639480624 11086606336 1160290880 12.406 99 38 74 1 1736830688 566047920 10491888
11:57:03 19303 8192000000 10619086672 11145261056 1154926544 12.356 100 32 65 0 1741360256 577507824 8925888
11:58:48 20219 8192000000 10289109696 10825748480 1138001520 12.197 100 16 32 1 1462610000 319469936 5138544
12:00:43 22279 8192000000 10043603856 10477899776 1120867104 12.035 100 44 89 1 1155366480 24644480 9854896
12:02:07 149460 8192000000 10073644544 10534481920 1137885392 12.196 100 23 46 2 1199417264 56728800 4803072
12:06:21 85633 8192000000 10134316160 10572247040 1131656144 12.137 99 26 50 2 1230132992 92966624 5510224
12:08:48 6752 8192000000 10132647488 10564694016 1133689632 12.156 100 39 75 1 1225697456 84593312 7414512
12:09:58 41802 8192000000 10066917792 10534277120 1122100960 12.047 98 16 32 2 1162746800 37073856 3571984
12:11:46 22330 8192000000 10090765952 10552336384 1134842400 12.167 99 11 22 0 1195684032 58586640 2254992
12:13:10 54080 8192000000 10115537392 10583056384 1134883888 12.167 99 13 26 1 1196101344 58718832 2498624
12:15:05 45649 8192000000 10066674240 10532110336 1133865584 12.158 99 16 32 0 1164445008 27297344 3282080
12:16:51 52698 8192000000 10116514640 10584080384 1124089152 12.066 98 16 32 0 1179318400 52066304 3162944
12:19:11 46566 8192000000 10133878640 10588377088 1154477280 12.352 100 35 70 1 1224709392 62776688 7455424
12:21:06 61729 8192000000 10098638112 10618798080 1161141744 12.414 100 11 22 0 1258082432 94250912 2689776
12:23:13 35854 8192000000 10116075600 10569625600 1117353184 12.002 98 36 71 1 1185011632 60270880 7387568
12:25:11 71343 8192000000 10089383584 10558496768 1116723184 11.996 98 16 32 0 1180808000 60222352 3862464
12:27:24 53737 8192000000 10075666512 10552037376 1127041280 12.093 98 11 21 1 1185310080 56203872 2064928
12:29:20 93302 8192000000 10067798432 10587033600 1073910256 11.589 94 14 28 2 1130379872 53659296 2810320
12:31:54 105158 8192000000 10071759264 10547666944 1101252688 11.850 98 13 26 0 1138647056 34650736 2743632
12:35:03 50340 8192000000 10070350896 10568183808 1112857264 11.959 99 5 10 0 1161417712 47523632 1036816
12:36:54 62936 8192000000 10063516896 10547175424 1097194016 11.811 97 16 32 1 1136934368 36555488 3184864
12:38:57 22304 8192000000 10067083392 10500976640 1128488992 12.107 100 41 82 1 1159329472 21813136 9027344
12:40:22 104820 8192000000 10117472448 10570227712 1109048992 11.923 97 24 46 1 1221511344 107363920 5098432
12:43:08 15613 8192000000 10064384576 10491678720 1128329424 12.106 100 36 71 0 1151466000 16406432 6730144
12:44:26 26210 8192000000 10106984416 10556829696 1123077360 12.056 99 41 82 0 1194305024 62903056 8324608
12:45:54 75179 8192000000 10091737840 10516480000 1084074256 11.686 96 22 44 1 1134494768 45960944 4459568
12:48:14 106316 8192000000 10127406272 10600083456 1073376896 11.584 95 8 17 0 1122614256 47736928 1500432
12:51:03 132025 8192000000 10042952752 10505633792 1113930016 11.970 99 21 41 1 1151031968 32183184 4918768
12:54:22 79820 8192000000 10091283456 10553356288 1103081520 11.867 98 12 24 1 1162441792 56575600 2784672
12:56:44 78121 8192000000 10036507232 10581037056 1070380560 11.556 96 12 24 1 1167759248 94687952 2690736
12:59:02 18712 8192000000 10043659920 10470166528 1115548624 11.985 100 38 77 0 1153712848 29242176 8922048
13:00:26 49463 8192000000 10085361456 10524348416 1123244496 12.058 100 21 42 0 1159747248 31958112 4544640
13:02:16 50463 8192000000 10139134848 10597605376 1138046304 12.197 100 26 52 0 1221230000 77143392 6040304
13:04:13 40347 8192000000 10151577776 10596274176 1113528096 11.966 97 22 44 1 1210533872 92295776 4710000
13:06:12 100974 8192000000 10073286912 10552115200 1088938032 11.733 96 19 38 1 1181078000 88529392 3610576
13:08:55 33694 8192000000 10115562256 10580439040 1120603824 12.033 98 31 62 1 1193372528 66296272 6472432
13:10:31 115072 8192000000 10062874448 10537373696 1137382016 12.191 100 13 26 0 1181946544 42157792 2406736
13:13:50 57598 8192000000 10068592544 10544947200 1118889696 12.017 98 11 22 0 1167567344 46969216 1708432
13:15:50 61760 8192000000 10098291136 10544369664 1125764704 12.081 99 22 43 2 1172913216 42701632 4446880
13:18:00 80070 8192000000 10095845392 10600980480 1125874464 12.082 98 20 40 0 1198481056 68192896 4413696
13:20:32 41561 8192000000 10106573872 10563321856 1120287968 12.030 98 22 44 0 1181734112 57183168 4262976
13:22:17 49904 8192000000 10100052832 10581483520 1093122624 11.772 96 28 56 2 1162452384 63683104 5646656
13:24:20 134864 8192000000 10125996704 10624548864 1094815936 11.788 96 12 24 2 1167414544 69799536 2799072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-06-2021 13:28:06 to 16-06-2021 13:28:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-06-2021 13:28:22 to 16-06-2021 13:28:24.

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          229317
MON$OLDEST_ACTIVE               230213
MON$OLDEST_SNAPSHOT             230213
MON$NEXT_TRANSACTION            230213
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                       2103424
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/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 ---
:84: 
:85: mscs-test-161.budzdorov.ru    Wed Jun 16 13:27:01 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Wed Jun 16 13:27:11 2021
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
--- end of diff output ---

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

16.06.21 13:28:25. 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-06-16 13:28:27 No severe PSQL-related problems occured
16.06.21 13:28:29. End of report.