Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2021 19:39
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 15898672 kB
MemAvailable 30648272 kB
Buffers 0 kB
Cached 15818064 kB
SwapCached 3704 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-10-21 19:37:53
DTS_END                         2021-10-21 19:37:53.4140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38364
TRN_ID                          235046
ATT_ID                          665
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: 9324.90 18189 1118988
customer order: creation 168.19 369 20183
customer order: refuse 14.35 349 1722
order to supplier: creation 43.94 1231 5273
order to supplier: removal 3.43 105705 412
invoice (draft): creation 44.26 3346 5311
invoice (draft): removal 54.71 846 6565
invoice accept: apply 40.73 17483 4887
invoice accept: cancel 49.54 741 5945
customer reserve: creation 3753.89 173 450467
customer reserve: removal 4862.68 58 583521
realization accept: apply 82.46 187 9895
realization accept: cancel 80.77 101 9692
payment from customer: creation 58.34 136 7001
payment from customer: removal 5.47 87 656
payment to supplier: creation 47.12 170 5654
payment to supplier: removal 3.42 88 410
service: total inventory turnovers 4.31 1269 517
service: total monetary turnovers 6.50 76 780
service: aggregate perf. data 0.78 24474 93
service: refresh index statistics 0.03 206891 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-10-2021 19:40:01 to 21-10-2021 19:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1831
TEST_TIME 2 2301
TEST_TIME 3 3063
TEST_TIME 4 3401
TEST_TIME 5 3875
TEST_TIME 6 3919
TEST_TIME 7 3730
TEST_TIME 8 4083
TEST_TIME 9 4259
TEST_TIME 10 4524
TEST_TIME 11 4800
TEST_TIME 12 5143
TEST_TIME 13 5384
TEST_TIME 14 5855
TEST_TIME 15 6680
TEST_TIME 16 6672
TEST_TIME 17 7121
TEST_TIME 18 7229
TEST_TIME 19 7489
TEST_TIME 20 7507
TEST_TIME 21 7621
TEST_TIME 22 8205
TEST_TIME 23 9296
TEST_TIME 24 10035
TEST_TIME 25 10414
TEST_TIME 26 10845
TEST_TIME 27 10780
TEST_TIME 28 10821
TEST_TIME 29 10791
TEST_TIME 30 10547
TEST_TIME 31 10628
TEST_TIME 32 10537
TEST_TIME 33 10578
TEST_TIME 34 10381
TEST_TIME 35 10255
TEST_TIME 36 10133
TEST_TIME 37 10077
TEST_TIME 38 10121
TEST_TIME 39 10335
TEST_TIME 40 10218
TEST_TIME 41 10206
TEST_TIME 42 10135
TEST_TIME 43 10038
TEST_TIME 44 9840
TEST_TIME 45 9965
TEST_TIME 46 9920
TEST_TIME 47 9801
TEST_TIME 48 9803
TEST_TIME 49 9738
TEST_TIME 50 9746
TEST_TIME 51 9738
TEST_TIME 52 9663
TEST_TIME 53 9699
TEST_TIME 54 9621
TEST_TIME 55 9646
TEST_TIME 56 9583
TEST_TIME 57 9641
TEST_TIME 58 9629
TEST_TIME 59 9745
TEST_TIME 60 9721
TEST_TIME 61 9740
TEST_TIME 62 9800
TEST_TIME 63 9875
TEST_TIME 64 9828
TEST_TIME 65 9950
TEST_TIME 66 9924
TEST_TIME 67 9927
TEST_TIME 68 10021
TEST_TIME 69 9885
TEST_TIME 70 9775
TEST_TIME 71 9727
TEST_TIME 72 9675
TEST_TIME 73 9673
TEST_TIME 74 9591
TEST_TIME 75 9614
TEST_TIME 76 9598
TEST_TIME 77 9541
TEST_TIME 78 9465
TEST_TIME 79 9454
TEST_TIME 80 9483
TEST_TIME 81 9454
TEST_TIME 82 9418
TEST_TIME 83 9491
TEST_TIME 84 9541
TEST_TIME 85 9527
TEST_TIME 86 9545
TEST_TIME 87 9531
TEST_TIME 88 9595
TEST_TIME 89 9654
TEST_TIME 90 9716
TEST_TIME 91 9682
TEST_TIME 92 9689
TEST_TIME 93 9730
TEST_TIME 94 9638
TEST_TIME 95 9586
TEST_TIME 96 9555
TEST_TIME 97 9535
TEST_TIME 98 9467
TEST_TIME 99 9513
TEST_TIME 100 9508
TEST_TIME 101 9496
TEST_TIME 102 9498
TEST_TIME 103 9483
TEST_TIME 104 9474
TEST_TIME 105 9479
TEST_TIME 106 9509
TEST_TIME 107 9514
TEST_TIME 108 9480
TEST_TIME 109 9491
TEST_TIME 110 9519
TEST_TIME 111 9515
TEST_TIME 112 9522
TEST_TIME 113 9492
TEST_TIME 114 9428
TEST_TIME 115 9437
TEST_TIME 116 9421
TEST_TIME 117 9403
TEST_TIME 118 9361
TEST_TIME 119 9363
TEST_TIME 120 9329

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2021 19:40:05 to 21-10-2021 19:40:08.

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 1537076 1537076 0 0.00 0 31511 6 0 0
doc_list_biud 1537075 1537075 0 0.00 0 44513 28 0 0
sp_add_invoice_to_stock 4980 4887 93 1.86 54 180873 17483 0 93
sp_cancel_adding_invoice 5945 5945 0 0.00 5 56760 742 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 18553 350 0 0
sp_cancel_customer_reserve 583521 583521 0 0.00 1 44514 58 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 5187 87 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 6226 88 0 0
sp_cancel_supplier_invoice 6573 6565 8 0.12 2 17549 846 0 8
sp_cancel_supplier_order 412 412 0 0.00 6 1122525 105705 0 0
sp_cancel_write_off 9692 9692 0 0.00 1 25588 101 0 0
sp_client_order 20183 20183 0 0.00 3 69644 370 0 0
sp_customer_reserve 450467 450467 0 0.00 0 106194 173 0 0
sp_fill_shopping_cart 30753 30753 0 0.00 1 14592 37 0 0
sp_fill_shopping_cart_clo_res 450457 450457 0 0.00 0 12776 14 0 0
sp_get_clo_for_invoice 4877 4877 0 0.00 0 30931 354 0 0
sp_kill_cost_storno 16703 16703 0 0.00 0 25575 54 0 0
sp_kill_qstorno_ret_qs2qd 1183711 1183711 0 0.00 0 44492 32 0 0
sp_kill_qty_storno 622627 622627 0 0.00 0 44513 69 0 0
sp_lock_selected_doc 20372 20372 0 0.00 0 6272 5 0 0
sp_make_cost_storno 27426 27426 0 0.00 0 31470 60 0 0
sp_multiply_rows_for_pdistr 27425 27425 0 0.00 0 26399 32 0 0
sp_multiply_rows_for_qdistr 20183 20183 0 0.00 0 69637 175 0 0
sp_pay_from_customer 7001 7001 0 0.00 4 34201 136 0 0
sp_pay_to_supplier 5654 5654 0 0.00 4 31335 171 0 0
sp_payment_common 12655 12655 0 0.00 1 34174 52 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 18541 316 0 0
sp_qd_handle_on_invoice_upd_sts 10820 10820 0 0.00 0 31646 329 0 0
sp_qd_handle_on_reserve_upd_sts 9895 9895 0 0.00 0 12596 61 0 0
sp_reserve_write_off 9895 9895 0 0.00 6 30328 188 0 0
sp_supplier_invoice 5311 5311 0 0.00 6 84342 3346 0 0
sp_supplier_order 5273 5273 0 0.00 4 39209 1231 0 0
srv_aggregate_perf_data 93 93 0 0.00 3149 334352 24474 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2016 172531 51422 0 0
srv_find_qd_qs_mism 1072996 1072996 0 0.00 0 11382 5 0 0
srv_make_invnt_saldo 517 517 0 0.00 30 68623 1270 0 0
srv_make_money_saldo 780 780 0 0.00 1 31640 76 0 0
srv_recalc_idx_stat 4 4 0 0.00 106786 324950 206892 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4887 4887 0 0.00 0 16969 165 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 1936 65 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 6846 18 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1772 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 2591 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 4143 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 2303 31 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 22484 1677 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 2246 10 0 0
t$perf-norm:sp_client_order 20183 20183 0 0.00 0 14068 24 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 5355 17 0 0
t$perf-norm:sp_pay_from_customer 7001 7001 0 0.00 0 7797 13 0 0
t$perf-norm:sp_pay_to_supplier 5654 5654 0 0.00 0 12541 13 0 0
t$perf-norm:sp_reserve_write_off 9895 9895 0 0.00 0 12517 17 0 0
t$perf-norm:sp_supplier_invoice 5311 5311 0 0.00 0 7009 24 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 12679 20 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 139 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 13 0 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 2803 36 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 1056 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9936 9750 186 1.87 0 4584 18 0 186
v_all_customers 36808 36808 0 0.00 0 17 0 0 0
v_all_suppliers 29314 29314 0 0.00 0 16 1 0 0
v_all_wares 40364 40364 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 1193 7 0 0
v_cancel_client_order 3444 3444 0 0.00 0 3765 8 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 3342 9 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 4591 8 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3836 9 0 0
v_cancel_supplier_invoice 758 742 16 2.11 0 525 13 0 16
v_cancel_supplier_order 824 824 0 0.00 0 2556 9 0 0
v_cancel_write_off 1904 1904 0 0.00 0 2834 15 0 0
v_max_id_clo_ord 5271 5271 0 0.00 0 1039 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 1448 7 0 0
v_max_id_ord_sup 5298 5298 0 0.00 0 4835 5 0 0
v_max_non_paid_invoice 1566 1566 0 0.00 0 3157 4 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 9461 8 0 0
v_min_id_clo_ord 5271 5271 0 0.00 0 4970 6 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 3216 10 0 0
v_min_id_ord_sup 5298 5298 0 0.00 0 8210 8 0 0
v_min_non_paid_invoice 5654 5654 0 0.00 0 25465 41 0 0
v_min_non_paid_realizn 7001 7001 0 0.00 0 34170 44 0 0
v_our_firm 10096 10096 0 0.00 0 21 1 0 0
v_reserve_write_off 19790 19790 0 0.00 0 3592 7 0 0
x_lock_depdocs_on_canc_invoice 12139 12139 0 0.00 0 9634 100 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 18000 369 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 1 38631 1154 0 0
x_make_qstorno_1200_2000 5311 5311 0 0.00 3 82668 3209 0 0
x_make_qstorno_2100_3300 450467 450467 0 0.00 0 106192 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2021 19:40:09 to 21-10-2021 19:40:13.

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
17:07:57 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:57 32268 8192000000 9887846656 10196631552 967667168 10.564 100 87 173 1 996774816 5724416 23383232
17:10:33 27641 8192000000 9942431152 10264625152 1021992992 11.091 100 90 180 0 1053656480 8032160 23631328
17:12:05 15487 8192000000 9965540592 10301132800 1056469760 11.423 100 74 148 0 1083837616 8975312 18392544
17:13:25 10583 8192000000 10043992352 10389200896 1122776928 12.053 100 87 170 3 1154010144 10228480 21004736
17:14:43 7434 8192000000 10088506752 10449776640 1178322016 12.575 100 81 164 0 1208302160 11543120 18437024
17:15:53 5622 8192000000 10130953264 10499223552 1218305776 12.946 100 90 180 1 1252633504 13824672 20503056
17:17:01 3408 8192000000 10165505504 10543038464 1248341744 13.223 100 85 168 0 1279996496 12897120 18757632
17:18:06 2586 8192000000 10200352752 10581696512 1281191408 13.524 100 93 187 0 1316363776 14594160 20578208
17:19:11 1260 8192000000 10223452064 10608308224 1303600208 13.728 100 90 179 3 1335779232 12239440 19939584
17:20:14 5942 8192000000 10212224512 10610171904 1307183824 13.761 100 47 94 0 1326842832 9395776 10263232
17:21:25 6299 8192000000 10205663952 10615926784 1314070256 13.823 100 22 45 1 1325311328 5645264 5595808
17:22:33 6289 8192000000 10207097696 10626002944 1319964736 13.876 100 23 46 1 1336541648 11543088 5033824
17:23:40 43320 8192000000 10205074288 10629603328 1322351136 13.898 100 27 54 0 1334210320 5359488 6499696
17:25:25 6106 8192000000 10223471776 10645213184 1330833984 13.975 100 21 40 1 1340397296 4928432 4634880
17:26:34 7246 8192000000 10231200208 10645401600 1331176496 13.978 100 27 54 2 1343772608 6107408 6488704
17:27:42 11465 8192000000 10193811280 10615402496 1293038480 13.632 98 20 40 2 1304092496 6428640 4625376
17:28:58 25305 8192000000 10190579424 10603986944 1250869712 13.246 95 30 60 0 1263845760 6079904 6896144
17:30:24 7010 8192000000 10173398032 10575425536 1260074624 13.331 99 33 64 2 1273429440 5388304 7966512
17:31:35 4667 8192000000 10139078768 10542915584 1250591344 13.244 100 29 56 1 1262874256 5632864 6650048
17:32:39 22659 8192000000 10156364336 10565496832 1246189360 13.203 98 30 59 0 1258289472 5299584 6800528
17:34:19 6181 8192000000 10172893184 10580631552 1280008768 13.513 100 23 46 1 1291106608 5512608 5585232
17:35:27 7654 8192000000 10181680192 10591449088 1291366016 13.617 100 25 49 2 1303381504 5703680 6311808
17:36:36 8758 8192000000 10199416032 10608996352 1300383632 13.699 100 24 46 3 1311214960 5614192 5217136
17:37:46 74058 8192000000 10233105424 10644570112 1326568896 13.936 100 37 74 0 1341974192 5936832 9468464
17:40:08 15508 8192000000 10263533408 10674102272 1352971632 14.174 100 56 110 1 1375476336 9984576 12520128
17:41:27 46109 8192000000 10285074512 10709057536 1373620736 14.359 100 36 72 1 1396317120 14132048 8564336
17:43:15 27739 8192000000 10299577456 10723786752 1386339104 14.473 100 45 90 1 1417662544 19439008 11884432
17:44:50 38096 8192000000 10329702736 10758488064 1401674896 14.610 100 47 94 1 1440146640 27386192 11085552
17:46:39 8158 8192000000 10361583456 10782543872 1421335696 14.785 100 51 102 2 1473398240 39328128 12734416
17:47:50 3384 8192000000 10381179856 10795524096 1414177728 14.721 99 66 132 3 1474208944 43407920 16623296
17:48:57 4662 8192000000 10380758544 10798391296 1415992352 14.737 99 70 139 4 1480307680 46733488 17581840
17:50:11 7172 8192000000 10390257504 10810880000 1427088144 14.836 100 71 142 0 1496418320 50799728 18530448
17:51:25 2015 8192000000 10391762688 10813083648 1417955280 14.755 99 75 147 1 1482028656 45449504 18623872
17:52:32 4425 8192000000 10390781536 10808565760 1421652400 14.787 100 80 159 0 1493457488 51670928 20134160
17:53:40 4083 8192000000 10386779200 10801778688 1415403824 14.732 100 74 148 0 1489104832 55769568 17931440
17:54:50 6029 8192000000 10346730928 10769838080 1391915840 14.523 100 50 99 0 1458536336 53594800 13025696
17:56:08 4470 8192000000 10322946448 10731294720 1345910016 14.111 99 70 141 1 1419392368 55182560 18299792
17:57:15 10289 8192000000 10334874688 10756931584 1362848528 14.263 99 47 94 1 1434476752 58559680 13068544
17:58:29 14757 8192000000 10338912544 10767101952 1351495152 14.161 99 42 84 1 1434867872 71027328 12345392
17:59:46 8234 8192000000 10347560480 10776748032 1361967600 14.255 100 40 79 1 1448848352 74913072 11967680
18:00:55 22752 8192000000 10321865152 10760097792 1379935728 14.416 100 31 62 1 1436230992 47666160 8629104
18:02:23 61042 8192000000 10297272096 10752233472 1354009968 14.184 99 9 18 0 1376593520 20561344 2022208
18:04:28 35786 8192000000 10270801728 10718232576 1335278608 14.015 98 17 33 2 1353052320 13999456 3774256
18:06:05 172529 8192000000 10268301520 10719158272 1319790800 13.875 97 27 54 1 1335956736 9727728 6438208
18:10:00 167436 8192000000 10292578160 10737532928 1311416832 13.799 95 32 64 0 1327330064 9331216 6582016
18:13:53 49110 8192000000 10301627008 10749272064 1362032736 14.256 98 43 85 2 1389828784 16117008 11679040
18:15:48 68837 8192000000 10285895072 10752552960 1367583776 14.305 99 14 29 0 1392381232 21138656 3658800
18:18:03 24257 8192000000 10277627696 10736480256 1380453152 14.421 100 8 16 0 1391479344 9278864 1747328
18:20:24 27874 8192000000 10283532224 10745085952 1374242320 14.365 99 9 18 1 1389111856 12581280 2288256
18:23:11 66643 8192000000 10291367600 10751971328 1384035920 14.453 100 15 30 0 1397659712 10492656 3131136
18:25:21 55495 8192000000 10301045584 10769014784 1387464592 14.483 99 28 56 2 1411391152 17198784 6727776
18:27:21 54363 8192000000 10281660016 10758197248 1389618352 14.502 100 9 18 0 1407536512 15670464 2247696
18:29:17 40970 8192000000 10287725152 10748264448 1389818368 14.504 100 30 60 0 1408164448 9978160 8367920
18:31:08 52793 8192000000 10280609536 10745999360 1339113232 14.049 96 7 14 0 1354048560 13284736 1650592
18:33:37 25341 8192000000 10306235456 10754879488 1381733264 14.432 99 54 108 1 1408181856 14786864 11661728
18:35:10 62693 8192000000 10291891824 10745208832 1347921328 14.129 97 26 52 0 1366622528 12807872 5893328
18:37:20 94581 8192000000 10304130896 10759548928 1358210016 14.221 97 22 44 0 1374418768 11355776 4852976
18:40:06 92748 8192000000 10274515776 10730926080 1311048016 13.796 94 15 30 0 1328256080 14164336 3043728
18:42:39 100000 8192000000 10269514720 10714632192 1327237760 13.942 97 13 26 0 1341557728 11132912 3187056
18:45:23 117576 8192000000 10264209696 10716569600 1352517760 14.170 99 14 28 1 1380397792 24361440 3518592
18:48:34 63534 8192000000 10269495296 10721837056 1365701376 14.289 100 21 42 1 1384320272 13509312 5109584
18:50:38 53744 8192000000 10246289280 10699182080 1317686448 13.856 97 12 24 1 1335809408 15327952 2795008
18:52:32 51737 8192000000 10247035872 10697625600 1347591824 14.126 100 11 22 1 1359458992 9667088 2200080
18:54:26 55255 8192000000 10247689888 10699505664 1323444592 13.908 97 11 22 0 1332882928 6794928 2643408
18:56:21 66308 8192000000 10245844624 10694848512 1293603136 13.637 95 26 52 2 1311900816 12186272 6111408
18:58:34 45724 8192000000 10263297920 10710417408 1344291200 14.096 99 7 14 1 1359218864 13193888 1733776
19:00:30 89097 8192000000 10271021424 10726391808 1348570592 14.135 99 18 36 0 1364358464 11413776 4374096
19:03:00 49102 8192000000 10283762496 10731134976 1353475120 14.179 99 22 44 0 1375033664 16470816 5087728
19:04:49 20768 8192000000 10306081392 10742448128 1366495472 14.296 99 42 84 2 1392630448 17047104 9087872
19:06:12 106971 8192000000 10298802960 10767155200 1371980656 14.345 99 19 38 2 1391449376 15517904 3950816
19:09:08 30690 8192000000 10323085328 10762731520 1400814368 14.602 100 55 109 1 1426631168 13922752 11894048
19:10:46 73383 8192000000 10295668128 10741747712 1361974928 14.255 97 28 56 0 1377090352 9009584 6105840
19:13:00 84493 8192000000 10291329408 10757402624 1382216016 14.436 99 9 18 0 1398243888 13752768 2275104
19:15:43 52574 8192000000 10286274496 10754035712 1384367808 14.456 100 25 50 1 1406853984 16522560 5963616
19:17:37 71596 8192000000 10281569040 10746892288 1383185312 14.445 100 13 26 0 1396929648 10551648 3192688
19:19:49 51907 8192000000 10280602448 10731220992 1345470192 14.107 97 29 58 0 1360972800 8861248 6641360
19:21:46 78577 8192000000 10282049264 10743652352 1339008368 14.048 97 20 40 0 1354080512 10672144 4400000
19:24:07 69797 8192000000 10281432064 10738888704 1360911664 14.246 98 8 16 0 1374830912 12178688 1740560
19:26:18 72074 8192000000 10294483440 10748153856 1346633792 14.117 97 20 40 0 1360354208 9342384 4378032
19:29:22 47753 8192000000 10296063872 10744348672 1356894128 14.209 98 22 44 0 1372860240 11119008 4847104
19:31:13 105137 8192000000 10282226288 10735206400 1370887648 14.335 99 24 48 0 1387985776 11139952 5958176
19:34:00 45209 8192000000 10258477504 10724872192 1355318256 14.195 99 13 26 0 1369115408 11122096 2675056
19:35:49 50158 8192000000 10238026656 10698358784 1302356912 13.717 95 11 22 0 1317237696 12425280 2455504
19:37:42 29068 8192000000 9722743664 10092306432 850618928 9.406 63 5 10 0 860194096 8854992 720176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-10-2021 19:40:15 to 21-10-2021 19:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2021 19:40:31 to 21-10-2021 19:40:33.

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          233693
MON$OLDEST_ACTIVE               235294
MON$OLDEST_SNAPSHOT             235294
MON$NEXT_TRANSACTION            235294
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                       2029152
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.33522 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33522 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33522 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33522 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Oct 21 19:38:20 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:14: 
--- end of diff output ---

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

21.10.21 19:40:34. 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-10-21 19:40:36 No severe PSQL-related problems occured
21.10.21 19:40:37. End of report.