Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2021 22:38
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 16307372 kB
MemAvailable 30941236 kB
Buffers 0 kB
Cached 15350356 kB
SwapCached 3640 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08-20 22:37:46
DTS_END                         2021-08-20 22:37:46.6090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57760
TRN_ID                          243340
ATT_ID                          707
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: 8917.65 16597 1070118
customer order: creation 180.52 338 21662
customer order: refuse 15.17 322 1820
order to supplier: creation 46.36 1289 5563
order to supplier: removal 3.32 97729 398
invoice (draft): creation 47.36 3340 5683
invoice (draft): removal 49.37 776 5924
invoice accept: apply 41.56 19408 4987
invoice accept: cancel 44.49 908 5339
customer reserve: creation 3838.66 194 460639
customer reserve: removal 4352.81 59 522337
realization accept: apply 86.72 190 10406
realization accept: cancel 79.58 119 9550
payment from customer: creation 60.77 130 7292
payment from customer: removal 5.58 78 670
payment to supplier: creation 49.21 133 5905
payment to supplier: removal 3.66 98 439
service: total inventory turnovers 4.99 1036 599
service: total monetary turnovers 6.62 23 794
service: aggregate perf. data 0.89 20617 107
service: refresh index statistics 0.03 185161 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2021 22:38:50 to 20-08-2021 22:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3494
TEST_TIME 2 3880
TEST_TIME 3 5116
TEST_TIME 4 4701
TEST_TIME 5 5546
TEST_TIME 6 5570
TEST_TIME 7 5508
TEST_TIME 8 5859
TEST_TIME 9 5986
TEST_TIME 10 5900
TEST_TIME 11 5925
TEST_TIME 12 6021
TEST_TIME 13 6194
TEST_TIME 14 6224
TEST_TIME 15 6225
TEST_TIME 16 6362
TEST_TIME 17 6324
TEST_TIME 18 6771
TEST_TIME 19 7234
TEST_TIME 20 7483
TEST_TIME 21 8012
TEST_TIME 22 8167
TEST_TIME 23 8282
TEST_TIME 24 8841
TEST_TIME 25 9255
TEST_TIME 26 9523
TEST_TIME 27 9602
TEST_TIME 28 10160
TEST_TIME 29 10212
TEST_TIME 30 10027
TEST_TIME 31 10198
TEST_TIME 32 10046
TEST_TIME 33 9913
TEST_TIME 34 9775
TEST_TIME 35 9733
TEST_TIME 36 9688
TEST_TIME 37 9677
TEST_TIME 38 9633
TEST_TIME 39 9522
TEST_TIME 40 9422
TEST_TIME 41 9457
TEST_TIME 42 9282
TEST_TIME 43 9380
TEST_TIME 44 9258
TEST_TIME 45 9147
TEST_TIME 46 9068
TEST_TIME 47 9009
TEST_TIME 48 9172
TEST_TIME 49 9111
TEST_TIME 50 9087
TEST_TIME 51 9144
TEST_TIME 52 9223
TEST_TIME 53 9274
TEST_TIME 54 9187
TEST_TIME 55 9150
TEST_TIME 56 9277
TEST_TIME 57 9357
TEST_TIME 58 9297
TEST_TIME 59 9263
TEST_TIME 60 9459
TEST_TIME 61 9449
TEST_TIME 62 9460
TEST_TIME 63 9435
TEST_TIME 64 9361
TEST_TIME 65 9403
TEST_TIME 66 9357
TEST_TIME 67 9276
TEST_TIME 68 9299
TEST_TIME 69 9263
TEST_TIME 70 9229
TEST_TIME 71 9186
TEST_TIME 72 9144
TEST_TIME 73 9066
TEST_TIME 74 9034
TEST_TIME 75 9007
TEST_TIME 76 8997
TEST_TIME 77 9021
TEST_TIME 78 8987
TEST_TIME 79 9015
TEST_TIME 80 9019
TEST_TIME 81 9000
TEST_TIME 82 9100
TEST_TIME 83 9083
TEST_TIME 84 9122
TEST_TIME 85 9172
TEST_TIME 86 9169
TEST_TIME 87 9205
TEST_TIME 88 9198
TEST_TIME 89 9209
TEST_TIME 90 9124
TEST_TIME 91 9108
TEST_TIME 92 9081
TEST_TIME 93 9027
TEST_TIME 94 8948
TEST_TIME 95 8944
TEST_TIME 96 8896
TEST_TIME 97 8975
TEST_TIME 98 8955
TEST_TIME 99 8954
TEST_TIME 100 8985
TEST_TIME 101 8992
TEST_TIME 102 8972
TEST_TIME 103 9001
TEST_TIME 104 9036
TEST_TIME 105 9033
TEST_TIME 106 9053
TEST_TIME 107 9104
TEST_TIME 108 9130
TEST_TIME 109 9110
TEST_TIME 110 9038
TEST_TIME 111 9055
TEST_TIME 112 9008
TEST_TIME 113 8992
TEST_TIME 114 8954
TEST_TIME 115 8917
TEST_TIME 116 8881
TEST_TIME 117 8906
TEST_TIME 118 8891
TEST_TIME 119 8908
TEST_TIME 120 8922

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2021 22:38:54 to 20-08-2021 22:38:57.

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 1499356 1499356 0 0.00 0 19148 7 0 0
doc_list_biud 1499354 1499354 0 0.00 0 65727 26 0 0
sp_add_invoice_to_stock 5091 4987 104 2.04 34 215380 19409 0 104
sp_cancel_adding_invoice 5339 5339 0 0.00 4 76450 909 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 9631 323 0 0
sp_cancel_customer_reserve 522337 522337 0 0.00 1 65728 59 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 6686 79 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 4956 99 0 0
sp_cancel_supplier_invoice 5930 5924 6 0.10 2 25242 776 0 6
sp_cancel_supplier_order 398 398 0 0.00 15 1043567 97729 0 0
sp_cancel_write_off 9550 9550 0 0.00 1 11019 120 0 0
sp_client_order 21662 21662 0 0.00 3 19528 338 0 0
sp_customer_reserve 460639 460639 0 0.00 0 37306 194 0 0
sp_fill_shopping_cart 32897 32897 0 0.00 1 11315 39 0 0
sp_fill_shopping_cart_clo_res 460630 460630 0 0.00 0 15631 14 0 0
sp_get_clo_for_invoice 4973 4973 0 0.00 0 35191 314 0 0
sp_kill_cost_storno 15998 15998 0 0.00 0 7202 61 0 0
sp_kill_qstorno_ret_qs2qd 1060546 1060546 0 0.00 0 65723 32 0 0
sp_kill_qty_storno 560747 560747 0 0.00 0 65727 69 0 0
sp_lock_selected_doc 21232 21232 0 0.00 0 6995 5 0 0
sp_make_cost_storno 28576 28576 0 0.00 0 18236 60 0 0
sp_multiply_rows_for_pdistr 28576 28576 0 0.00 0 8427 31 0 0
sp_multiply_rows_for_qdistr 21662 21662 0 0.00 0 12512 150 0 0
sp_pay_from_customer 7292 7292 0 0.00 4 18261 131 0 0
sp_pay_to_supplier 5905 5905 0 0.00 5 20157 133 0 0
sp_payment_common 13197 13197 0 0.00 1 20128 42 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 9625 298 0 0
sp_qd_handle_on_invoice_upd_sts 10312 10312 0 0.00 0 17970 313 0 0
sp_qd_handle_on_reserve_upd_sts 10406 10406 0 0.00 0 17911 58 0 0
sp_reserve_write_off 10406 10406 0 0.00 6 18249 190 0 0
sp_supplier_invoice 5683 5683 0 0.00 6 74525 3341 0 0
sp_supplier_order 5563 5563 0 0.00 5 23735 1290 0 0
srv_aggregate_perf_data 107 107 0 0.00 2679 387928 20618 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2186 114104 43529 0 0
srv_find_qd_qs_mism 1022905 1022905 0 0.00 0 19147 6 0 0
srv_make_invnt_saldo 599 599 0 0.00 30 52880 1037 0 0
srv_make_money_saldo 794 794 0 0.00 1 1037 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 62096 318507 185162 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 4988 4988 0 0.00 0 16677 185 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 11664 174 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 2399 14 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 1857 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 5132 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 4343 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 3173 30 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 22888 1485 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 1773 7 0 0
t$perf-norm:sp_client_order 21662 21662 0 0.00 0 11415 21 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 5196 10 0 0
t$perf-norm:sp_pay_from_customer 7292 7292 0 0.00 0 12385 14 0 0
t$perf-norm:sp_pay_to_supplier 5906 5906 0 0.00 0 13273 12 0 0
t$perf-norm:sp_reserve_write_off 10406 10406 0 0.00 0 7451 11 0 0
t$perf-norm:sp_supplier_invoice 5683 5683 0 0.00 0 4499 21 0 0
t$perf-norm:sp_supplier_order 5563 5563 0 0.00 0 4857 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 322 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 126 3 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 2173 24 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 2944 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 8 0 0
v_add_invoice_to_stock 10154 9946 208 2.04 0 4144 17 0 208
v_all_customers 39326 39326 0 0.00 0 18 1 0 0
v_all_suppliers 31066 31066 0 0.00 0 121 1 0 0
v_all_wares 43324 43324 0 0.00 0 162 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 416 6 0 0
v_cancel_client_order 3640 3640 0 0.00 0 1876 5 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2124 5 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 2501 8 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 4941 13 0 0
v_cancel_supplier_invoice 782 770 12 1.53 0 4277 25 0 12
v_cancel_supplier_order 796 796 0 0.00 0 3429 10 0 0
v_cancel_write_off 2106 2106 0 0.00 0 4854 16 0 0
v_max_id_clo_ord 5562 5562 0 0.00 0 1049 2 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 5167 20 0 0
v_max_id_ord_sup 5672 5672 0 0.00 0 10631 5 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 3056 4 0 0
v_max_non_paid_realizn 3899 3899 0 0.00 0 3190 5 0 0
v_min_id_clo_ord 5562 5562 0 0.00 0 1879 6 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 3245 13 0 0
v_min_id_ord_sup 5672 5672 0 0.00 0 6528 7 0 0
v_min_non_paid_invoice 5905 5905 0 0.00 0 8266 25 0 0
v_min_non_paid_realizn 7292 7292 0 0.00 0 9547 22 0 0
v_our_firm 10784 10784 0 0.00 0 15 1 0 0
v_reserve_write_off 20812 20812 0 0.00 0 5640 6 0 0
x_lock_depdocs_on_canc_invoice 10878 10878 0 0.00 0 24884 133 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4560 301 0 0
x_make_qstorno_1000_1200 5563 5563 0 0.00 1 23683 1215 0 0
x_make_qstorno_1200_2000 5683 5683 0 0.00 2 74486 3194 0 0
x_make_qstorno_2100_3300 460639 460639 0 0.00 0 37304 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2021 22:38:58 to 20-08-2021 22:39:02.

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
20:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:51 21315 8192000000 10372290816 10691096576 1408661072 14.672 100 78 156 0 1439581904 5380816 25540016
20:10:31 28963 8192000000 10476009808 10813177856 1527485680 15.715 100 86 172 1 1562620672 8073728 27061264
20:12:05 17355 8192000000 10527997296 10872352768 1581982160 16.185 100 91 182 0 1619506864 9609616 27915088
20:13:26 14011 8192000000 10594032080 10955124736 1655942160 16.815 100 77 153 2 1689317360 10629136 22746064
20:14:42 8667 8192000000 10678020784 11049414656 1734760352 17.475 100 80 157 2 1770067232 13243248 22063632
20:15:54 5659 8192000000 10724939488 11101515776 1787823792 17.914 100 83 166 0 1824337520 13885056 22628672
20:17:02 2979 8192000000 10774578368 11154546688 1826996320 18.235 100 90 181 0 1866627424 14604096 25027008
20:18:07 2375 8192000000 10815083248 11197362176 1864106000 18.537 100 93 188 0 1905110064 14532800 26471264
20:19:10 32742 8192000000 10802067504 11219705856 1881245184 18.675 100 44 88 2 1907176160 13585344 12345632
20:20:46 5036 8192000000 10781487024 11217981440 1881457344 18.677 100 20 38 4 1893161024 6448064 5255616
20:21:53 12731 8192000000 10813939296 11240800256 1894971776 18.786 100 24 48 1 1908358368 6608096 6778496
20:23:10 67151 8192000000 10846546992 11252240384 1903443424 18.854 100 47 95 4 1922553984 5163792 13946768
20:25:17 4693 8192000000 10827783664 11245170688 1897012032 18.802 100 22 44 1 1908635280 5310592 6312656
20:26:23 42338 8192000000 10779599520 11214106624 1861262064 18.514 98 19 38 0 1872000176 5093792 5644320
20:28:07 6494 8192000000 10782687168 11206709248 1848901152 18.413 99 34 68 3 1865895216 6995568 9998496
20:29:16 13134 8192000000 10718657024 11138609152 1743762704 17.550 95 14 29 0 1752499072 4887664 3848704
20:30:38 60880 8192000000 10692002672 11115790336 1718478640 17.340 95 24 48 0 1731006464 5492720 7035104
20:32:41 7792 8192000000 10718723312 11133911040 1787109248 17.908 99 30 60 2 1801862160 6523728 8229184
20:33:52 10512 8192000000 10762717632 11187859456 1842748032 18.363 100 26 53 1 1860829696 10596032 7485632
20:35:03 58398 8192000000 10802481664 11207282688 1863084880 18.528 100 58 115 5 1885876240 5830000 16961360
20:37:02 5357 8192000000 10793308800 11217903616 1877106320 18.642 100 34 68 2 1893253520 6608768 9538432
20:38:10 2186 8192000000 10859279776 11264704512 1918067248 18.971 100 77 152 4 1947508384 6724016 22717120
20:39:19 11788 8192000000 10875121904 11287810048 1930286144 19.069 100 62 124 0 1961840416 14259712 17294560
20:40:37 24674 8192000000 10859034624 11297304576 1938937200 19.138 100 33 66 0 1961464720 12075072 10452448
20:42:06 3856 8192000000 10902251040 11327696896 1958885472 19.297 100 58 114 2 1992870624 17008672 16976480
20:43:14 9680 8192000000 10913519392 11337207808 1969229568 19.379 100 58 112 1 2002053824 16847072 15977184
20:44:33 8772 8192000000 10916646496 11341606912 1968091824 19.370 100 53 105 1 2001787872 18548848 15147200
20:45:45 13863 8192000000 10922480992 11353010176 1983001136 19.488 100 57 112 1 2018832368 19682112 16149120
20:47:04 6850 8192000000 10946284864 11370983424 1997766944 19.605 100 67 132 0 2037277072 20158864 19351264
20:48:18 10812 8192000000 10955372048 11390582784 2008164288 19.687 100 50 100 1 2046445392 23476416 14804688
20:49:30 9898 8192000000 10960825008 11392094208 1991276704 19.554 99 60 120 2 2035898272 26616800 18004768
20:50:45 2969 8192000000 10973071712 11402911744 2003240208 19.648 99 66 132 1 2052161568 29358608 19562752
20:51:54 3164 8192000000 10963039840 11383861248 1996846448 19.598 100 79 158 0 2050481184 29763696 23871040
20:53:06 4518 8192000000 10949323248 11372662784 1952669344 19.248 98 69 137 0 2010153744 36103456 21380944
20:54:16 2886 8192000000 10909228272 11325972480 1921367520 18.998 98 74 147 3 1986305440 41292448 23645472
20:55:22 5262 8192000000 10965438768 11383918592 1982626224 19.485 100 69 138 0 2055054256 49578736 22849296
20:56:37 6070 8192000000 10914854880 11322114048 1913013440 18.931 97 63 125 3 1971100784 37957632 20129712
20:57:54 2373 8192000000 10918129344 11332608000 1937054240 19.123 100 72 143 1 2001139920 42070992 22014688
20:59:00 18053 8192000000 10884123264 11330158592 1921511024 18.999 99 34 68 0 1972012112 38350352 12150736
21:00:29 4763 8192000000 10921064880 11353227264 1948471072 19.214 100 54 106 0 2009871696 43783376 17617248
21:01:35 15946 8192000000 10901181536 11350843392 1943449232 19.174 100 26 52 0 1987557872 35316000 8792640
21:03:20 14067 8192000000 10912392400 11363545088 1957019728 19.282 100 17 34 0 1992771600 29168544 6583328
21:04:36 21345 8192000000 10886596000 11349708800 1935637760 19.112 100 15 30 1 1971985440 30892832 5454848
21:06:00 50652 8192000000 10893560192 11339059200 1927232160 19.045 99 15 30 0 1952587344 20250080 5105104
21:07:55 91070 8192000000 10876748640 11337224192 1906098272 18.875 97 26 52 1 1920772656 7641136 7033248
21:10:33 8183 8192000000 10889650832 11345858560 1964646048 19.343 100 17 34 2 1976281856 6344000 5291808
21:11:42 44007 8192000000 10909092016 11370135552 1979339104 19.459 100 29 57 3 1999128624 11149392 8640128
21:13:29 88634 8192000000 10912405968 11383574528 1969106208 19.378 99 10 20 1 1986666336 14949536 2610592
21:17:08 84045 8192000000 10922512048 11397124096 1995573840 19.588 100 17 34 0 2021338336 20984464 4780032
21:19:32 99909 8192000000 10927690480 11402358784 2003685808 19.652 100 14 29 1 2017460896 9484160 4290928
21:22:14 59126 8192000000 10926082160 11387076608 1985410384 19.508 99 32 64 1 2013405600 19213504 8781712
21:24:17 72320 8192000000 10927423184 11418951680 2004674256 19.660 100 8 16 1 2025846544 18640672 2531616
21:26:30 43730 8192000000 10954621344 11408732160 2008435488 19.689 100 37 74 2 2030599120 10901184 11262448
21:28:19 71881 8192000000 10933520864 11429724160 2011480560 19.713 100 16 32 1 2032860304 16460720 4919024
21:30:33 49431 8192000000 10954864736 11414618112 1993322720 19.570 99 17 33 4 2007206960 8566608 5317632
21:32:24 32973 8192000000 10926229488 11374141440 1911844384 18.921 95 35 70 0 1930177920 8941904 9391632
21:34:00 85163 8192000000 10912660240 11381940224 1964795520 19.344 99 11 22 0 1978392832 11004032 2593280
21:36:29 77604 8192000000 10891443056 11370516480 1951329648 19.237 99 21 42 1 1973655968 16450192 5876128
21:38:55 28769 8192000000 10883475216 11346968576 1918421040 18.974 98 22 44 0 1936775168 12271520 6082608
21:40:28 106186 8192000000 10888482768 11340828672 1880745712 18.671 96 29 58 3 1902177152 13169856 8261584
21:43:19 59478 8192000000 10869650048 11319402496 1873828992 18.615 96 41 82 1 1896992304 11624704 11538608
21:45:19 63914 8192000000 10853836080 11335999488 1908485648 18.894 98 11 22 0 1920208960 8549360 3173952
21:47:23 47576 8192000000 10825301504 11283021824 1861034944 18.512 97 23 46 3 1877502064 9110592 7356528
21:49:11 45235 8192000000 10825406000 11300392960 1894533728 18.782 99 9 18 1 1910284320 12698736 3051856
21:51:04 21532 8192000000 10824885216 11297968128 1911619968 18.920 100 17 34 0 1923320656 6883072 4817616
21:52:34 75634 8192000000 10813552320 11297656832 1856964032 18.479 97 6 12 0 1875145616 16268048 1913536
21:55:04 39724 8192000000 10823930448 11287146496 1851414192 18.434 96 15 30 0 1862999872 7571824 4013856
21:56:46 58302 8192000000 10860875632 11333222400 1917981792 18.971 100 14 28 0 1934434672 12725440 3727440
21:58:59 70447 8192000000 10850923840 11318988800 1862255664 18.522 96 23 46 0 1889075184 20219808 6599712
22:01:26 114104 8192000000 10859018368 11317743616 1889878160 18.745 98 15 30 1 1909267552 15210528 4178864
22:04:21 36534 8192000000 10884112656 11348901888 1930821232 19.073 99 33 66 0 1953616688 13851472 8943984
22:05:58 17555 8192000000 10906839680 11361206272 1965550976 19.350 100 42 84 2 1989686976 12730224 11405776
22:07:18 80200 8192000000 10893865024 11369164800 1971867008 19.400 100 29 58 0 1990075344 10140816 8067520
22:09:43 28870 8192000000 10884512912 11354710016 1942546016 19.167 98 24 43 3 1952779120 4631584 5601520
22:11:12 52090 8192000000 10905922400 11373989888 1962580240 19.327 99 16 32 1 1980419216 13032960 4806016
22:13:05 51917 8192000000 10894795488 11379458048 1977677376 19.446 100 6 12 1 1994124640 14359680 2087584
22:14:57 35604 8192000000 10897940368 11370078208 1960616544 19.311 99 23 45 1 1973326128 6625792 6083792
22:16:34 84171 8192000000 10909330112 11390377984 1972782096 19.408 99 16 32 2 1986498192 8780544 4935552
22:19:03 37850 8192000000 10916138112 11375050752 1925126704 19.028 97 22 42 0 1943186288 11962992 6096592
22:20:41 76288 8192000000 10897242336 11378098176 1921441168 18.998 97 17 34 2 1939249888 13242512 4566208
22:22:58 95102 8192000000 10883305440 11376336896 1893162288 18.771 96 10 20 1 1913402800 17683264 2557248
22:25:37 65418 8192000000 10862594576 11331325952 1861929616 18.519 95 8 15 0 1873391664 9439552 2022496
22:27:52 81951 8192000000 10863430960 11334938624 1898353232 18.813 97 21 42 0 1911500848 6927824 6219792
22:30:22 35403 8192000000 10850505056 11325894656 1934450000 19.102 100 4 8 1 1941835104 5773456 1611648
22:32:10 71910 8192000000 10863373696 11347513344 1945774416 19.193 100 9 18 1 1960494272 11756976 2962880
22:34:25 50100 8192000000 10853458704 11325812736 1890557120 18.750 97 7 14 0 1900280432 8108208 1615104
22:36:26 78420 8192000000 10855825072 11323883520 1889187408 18.739 98 18 36 1 1907170752 12281504 5701840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-08-2021 22:39:04 to 20-08-2021 22:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 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 20-08-2021 22:39:20 to 20-08-2021 22:39:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243270
MON$OLDEST_ACTIVE               244534
MON$OLDEST_SNAPSHOT             244534
MON$NEXT_TRANSACTION            244534
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-03 16:41:55.3470
MON$PAGES                       2021568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:200: 
:201: mscs-test-161.budzdorov.ru    Fri Aug 20 22:37:55 2021
:202:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:203: 
:204: 
:205: mscs-test-161.budzdorov.ru    Fri Aug 20 22:37:55 2021
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:207: 
:208: 
:209: mscs-test-161.budzdorov.ru    Fri Aug 20 22:37:55 2021
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:211: 
--- end of diff output ---

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

20.08.21 22:39:23. There are 3 logs in /var/tmp/logs-oltp30 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-08-20 22:39:25 No severe PSQL-related problems occured
20.08.21 22:39:27. End of report.