Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2021 01: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 16022100 kB
MemAvailable 30679804 kB
Buffers 0 kB
Cached 15656680 kB
SwapCached 3832 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-09 01:37:52
DTS_END                         2021-10-09 01:37:52.5360
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/36246
TRN_ID                          245297
ATT_ID                          691
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: 9445.47 22104 1133456
customer order: creation 179.15 348 21498
customer order: refuse 15.68 312 1882
order to supplier: creation 49.17 1190 5900
order to supplier: removal 3.73 90050 448
invoice (draft): creation 47.81 3027 5737
invoice (draft): removal 53.84 743 6461
invoice accept: apply 43.92 16720 5270
invoice accept: cancel 48.08 805 5770
customer reserve: creation 3992.98 167 479158
customer reserve: removal 4716.18 56 565941
realization accept: apply 86.66 184 10399
realization accept: cancel 73.40 114 8808
payment from customer: creation 61.37 145 7364
payment from customer: removal 6.23 86 748
payment to supplier: creation 50.77 152 6092
payment to supplier: removal 3.47 157 416
service: total inventory turnovers 5.11 949 613
service: total monetary turnovers 6.98 26 837
service: aggregate perf. data 0.92 17309 110
service: refresh index statistics 0.03 309548 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-10-2021 01:39:13 to 09-10-2021 01:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6233
TEST_TIME 2 4836
TEST_TIME 3 4404
TEST_TIME 4 6611
TEST_TIME 5 6334
TEST_TIME 6 5690
TEST_TIME 7 5560
TEST_TIME 8 5277
TEST_TIME 9 5080
TEST_TIME 10 5032
TEST_TIME 11 5156
TEST_TIME 12 5904
TEST_TIME 13 6200
TEST_TIME 14 6311
TEST_TIME 15 6466
TEST_TIME 16 6599
TEST_TIME 17 6688
TEST_TIME 18 7238
TEST_TIME 19 7689
TEST_TIME 20 7882
TEST_TIME 21 7995
TEST_TIME 22 8804
TEST_TIME 23 9395
TEST_TIME 24 9297
TEST_TIME 25 9390
TEST_TIME 26 9796
TEST_TIME 27 10777
TEST_TIME 28 10683
TEST_TIME 29 10535
TEST_TIME 30 10476
TEST_TIME 31 10155
TEST_TIME 32 10434
TEST_TIME 33 10278
TEST_TIME 34 10383
TEST_TIME 35 10238
TEST_TIME 36 10171
TEST_TIME 37 10052
TEST_TIME 38 9844
TEST_TIME 39 10219
TEST_TIME 40 10191
TEST_TIME 41 10127
TEST_TIME 42 9998
TEST_TIME 43 9916
TEST_TIME 44 9859
TEST_TIME 45 9738
TEST_TIME 46 9746
TEST_TIME 47 9746
TEST_TIME 48 9604
TEST_TIME 49 9569
TEST_TIME 50 9524
TEST_TIME 51 9699
TEST_TIME 52 9818
TEST_TIME 53 9813
TEST_TIME 54 9865
TEST_TIME 55 9943
TEST_TIME 56 9918
TEST_TIME 57 9935
TEST_TIME 58 9970
TEST_TIME 59 9927
TEST_TIME 60 9994
TEST_TIME 61 10069
TEST_TIME 62 10018
TEST_TIME 63 9924
TEST_TIME 64 9831
TEST_TIME 65 9897
TEST_TIME 66 9806
TEST_TIME 67 9737
TEST_TIME 68 9745
TEST_TIME 69 9741
TEST_TIME 70 9695
TEST_TIME 71 9719
TEST_TIME 72 9716
TEST_TIME 73 9694
TEST_TIME 74 9714
TEST_TIME 75 9684
TEST_TIME 76 9730
TEST_TIME 77 9793
TEST_TIME 78 9806
TEST_TIME 79 9823
TEST_TIME 80 9837
TEST_TIME 81 9813
TEST_TIME 82 9783
TEST_TIME 83 9768
TEST_TIME 84 9692
TEST_TIME 85 9704
TEST_TIME 86 9679
TEST_TIME 87 9643
TEST_TIME 88 9661
TEST_TIME 89 9596
TEST_TIME 90 9547
TEST_TIME 91 9506
TEST_TIME 92 9529
TEST_TIME 93 9527
TEST_TIME 94 9588
TEST_TIME 95 9612
TEST_TIME 96 9638
TEST_TIME 97 9640
TEST_TIME 98 9627
TEST_TIME 99 9660
TEST_TIME 100 9618
TEST_TIME 101 9734
TEST_TIME 102 9709
TEST_TIME 103 9716
TEST_TIME 104 9716
TEST_TIME 105 9715
TEST_TIME 106 9644
TEST_TIME 107 9619
TEST_TIME 108 9669
TEST_TIME 109 9610
TEST_TIME 110 9583
TEST_TIME 111 9559
TEST_TIME 112 9536
TEST_TIME 113 9538
TEST_TIME 114 9513
TEST_TIME 115 9475
TEST_TIME 116 9514
TEST_TIME 117 9508
TEST_TIME 118 9488
TEST_TIME 119 9451
TEST_TIME 120 9451

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-10-2021 01:39:17 to 09-10-2021 01:39:20.

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 1576762 1576762 0 0.00 0 24543 6 0 0
doc_list_biud 1576738 1576738 0 0.00 0 49787 26 0 0
sp_add_invoice_to_stock 5443 5270 173 3.17 20 203382 16720 0 173
sp_cancel_adding_invoice 5770 5770 0 0.00 4 65630 805 0 0
sp_cancel_client_order 1882 1882 0 0.00 4 10582 313 0 0
sp_cancel_customer_reserve 565941 565941 0 0.00 0 49790 57 0 0
sp_cancel_pay_from_customer 748 748 0 0.00 2 5176 86 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 24404 157 0 0
sp_cancel_supplier_invoice 6478 6461 17 0.26 2 14318 743 0 17
sp_cancel_supplier_order 448 448 0 0.00 8 1133525 90051 0 0
sp_cancel_write_off 8808 8808 0 0.00 1 11904 114 0 0
sp_client_order 21498 21498 0 0.00 3 14276 349 0 0
sp_customer_reserve 479158 479158 0 0.00 0 82025 167 0 0
sp_fill_shopping_cart 33127 33127 0 0.00 1 9635 34 0 0
sp_fill_shopping_cart_clo_res 479130 479130 0 0.00 0 12530 14 0 0
sp_get_clo_for_invoice 5242 5242 0 0.00 0 27301 291 0 0
sp_kill_cost_storno 15742 15742 0 0.00 0 24399 60 0 0
sp_kill_qstorno_ret_qs2qd 1147599 1147599 0 0.00 0 49777 31 0 0
sp_kill_qty_storno 604950 604950 0 0.00 0 49785 66 0 0
sp_lock_selected_doc 21799 21799 0 0.00 0 8120 7 0 0
sp_make_cost_storno 29096 29096 0 0.00 0 24480 57 0 0
sp_multiply_rows_for_pdistr 29096 29096 0 0.00 0 24168 30 0 0
sp_multiply_rows_for_qdistr 21498 21498 0 0.00 0 9277 172 0 0
sp_pay_from_customer 7364 7364 0 0.00 4 24639 146 0 0
sp_pay_to_supplier 6092 6092 0 0.00 4 24731 153 0 0
sp_payment_common 13456 13456 0 0.00 1 24314 59 0 0
sp_qd_handle_on_cancel_clo 1882 1882 0 0.00 0 10574 271 0 0
sp_qd_handle_on_invoice_upd_sts 11011 11011 0 0.00 0 24570 323 0 0
sp_qd_handle_on_reserve_upd_sts 10399 10399 0 0.00 0 17636 53 0 0
sp_reserve_write_off 10399 10399 0 0.00 6 18683 184 0 0
sp_supplier_invoice 5737 5737 0 0.00 7 82753 3027 0 0
sp_supplier_order 5900 5900 0 0.00 4 46570 1190 0 0
srv_aggregate_perf_data 110 110 0 0.00 2576 181562 17310 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1548 151861 45779 0 0
srv_find_qd_qs_mism 1083550 1083550 0 0.00 0 15863 5 0 0
srv_make_invnt_saldo 613 613 0 0.00 34 47549 950 0 0
srv_make_money_saldo 837 837 0 0.00 1 1277 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 83076 416649 309549 0 0
t$perf-abend:sp_add_invoice_to_stock 173 173 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5271 5271 0 0.00 0 14447 161 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 2898 87 0 0
t$perf-norm:sp_cancel_client_order 1882 1882 0 0.00 0 3750 11 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 6126 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 748 748 0 0.00 0 4823 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 2448 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 873 14 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 23780 1476 0 0
t$perf-norm:sp_cancel_write_off 1082 1082 0 0.00 0 1304 4 0 0
t$perf-norm:sp_client_order 21498 21498 0 0.00 0 13464 18 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 1422 7 0 0
t$perf-norm:sp_pay_from_customer 7364 7364 0 0.00 0 6330 9 0 0
t$perf-norm:sp_pay_to_supplier 6092 6092 0 0.00 0 7405 11 0 0
t$perf-norm:sp_reserve_write_off 10399 10399 0 0.00 0 25830 16 0 0
t$perf-norm:sp_supplier_invoice 5737 5737 0 0.00 0 12241 22 0 0
t$perf-norm:sp_supplier_order 5900 5900 0 0.00 0 25678 23 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 641 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 32 1 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 1558 20 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 1750 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 25 12 0 0
v_add_invoice_to_stock 10828 10482 346 3.19 0 7134 22 0 346
v_all_customers 38868 38868 0 0.00 0 21 1 0 0
v_all_suppliers 32130 32130 0 0.00 0 17 1 0 0
v_all_wares 42996 42996 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 1799 7 0 0
v_cancel_client_order 3764 3764 0 0.00 0 5391 8 0 0
v_cancel_customer_prepayment 1496 1496 0 0.00 0 4033 9 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2728 11 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 4547 12 0 0
v_cancel_supplier_invoice 896 862 34 3.79 0 4024 22 0 34
v_cancel_supplier_order 896 896 0 0.00 0 863 5 0 0
v_cancel_write_off 2164 2164 0 0.00 0 6364 20 0 0
v_max_id_clo_ord 5899 5899 0 0.00 0 116 1 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 2055 10 0 0
v_max_id_ord_sup 5730 5730 0 0.00 0 3927 4 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 1496 4 0 0
v_max_non_paid_realizn 4073 4073 0 0.00 0 3372 6 0 0
v_min_id_clo_ord 5899 5899 0 0.00 0 8306 7 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 3899 15 0 0
v_min_id_ord_sup 5730 5730 0 0.00 0 7498 7 0 0
v_min_non_paid_invoice 6092 6092 0 0.00 0 24306 46 0 0
v_min_non_paid_realizn 7364 7364 0 0.00 0 24286 52 0 0
v_our_firm 10710 10710 0 0.00 0 19 1 0 0
v_reserve_write_off 20798 20798 0 0.00 0 5402 7 0 0
x_lock_depdocs_on_canc_invoice 11800 11800 0 0.00 0 21257 106 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 11681 331 0 0
x_make_qstorno_1000_1200 5900 5900 0 0.00 1 46561 1112 0 0
x_make_qstorno_1200_2000 5737 5737 0 0.00 2 82724 2913 0 0
x_make_qstorno_2100_3300 479158 479158 0 0.00 0 82024 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2021 01:39:21 to 09-10-2021 01:39:25.

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
23:07:56 23 8192000000 8816806352 8834162688 18774960 0.228 7 3 5 0 19012672 88848 148864
23:08:57 32104 8192000000 9903935776 10218250240 989791504 10.779 100 86 172 1 1019236816 6325328 23119984
23:10:40 20254 8192000000 9963386368 10288173056 1043985712 11.303 100 87 174 0 1074087888 7727840 22374336
23:12:02 18679 8192000000 9992739888 10328936448 1078118912 11.630 100 83 166 0 1107632480 8716736 20796832
23:13:23 12346 8192000000 10048612512 10404847616 1134295024 12.162 100 69 138 3 1160116528 8751568 17069936
23:14:41 8532 8192000000 10096929536 10462339072 1185330512 12.640 100 72 144 0 1213402176 11464528 16607136
23:15:52 6303 8192000000 10127133456 10502701056 1224608000 13.004 100 78 156 1 1255197424 13451648 17137776
23:17:02 4665 8192000000 10171856544 10551730176 1259332992 13.324 100 78 155 0 1288782608 12246496 17203120
23:18:09 2148 8192000000 10199134672 10582405120 1284813840 13.557 100 88 176 2 1319050336 14716448 19520048
23:19:14 2148 8192000000 10224637936 10612482048 1306700624 13.756 100 92 186 0 1340677904 13536160 20441120
23:20:16 3581 8192000000 10204838240 10610208768 1309132736 13.778 100 53 107 1 1331736272 11463872 11139664
23:21:21 8716 8192000000 10207709392 10622210048 1317086784 13.850 100 28 56 0 1329185536 6211712 5887040
23:22:33 31839 8192000000 10223811344 10634604544 1327813232 13.947 100 50 101 2 1344372208 5132528 11426448
23:24:09 3891 8192000000 10226518832 10640916480 1330887792 13.975 100 28 55 0 1344297440 7388224 6021424
23:25:13 9965 8192000000 10224011600 10646884352 1334948896 14.012 100 23 46 0 1345938224 5507824 5481504
23:26:23 20936 8192000000 10213859744 10640142336 1319934816 13.876 99 22 44 0 1331031280 5909696 5186768
23:27:45 12414 8192000000 10215352112 10634579968 1306844320 13.757 98 23 46 1 1318110720 6381328 4885072
23:29:00 5321 8192000000 10199735008 10611892224 1305366496 13.744 100 31 61 1 1318607712 6456096 6785120
23:30:05 10187 8192000000 10155839840 10560692224 1224490208 13.003 96 26 52 0 1235992928 4884912 6617808
23:31:16 59231 8192000000 10121958384 10524504064 1166444848 12.464 93 22 45 0 1176630160 4489120 5696192
23:33:16 5154 8192000000 10137987504 10537091072 1245521168 13.197 100 18 36 0 1255717520 6308800 3887552
23:34:22 16303 8192000000 10172361056 10573877248 1266846096 13.393 99 29 55 2 1278771056 5163456 6761504
23:35:43 27440 8192000000 10189950848 10595856384 1296495392 13.663 100 28 56 1 1308270544 6037232 5737920
23:37:10 20292 8192000000 10198334352 10614079488 1309785888 13.784 100 20 40 0 1318856608 4401360 4669360
23:38:33 17547 8192000000 10258957616 10666827776 1343450944 14.089 100 61 122 0 1367738032 9865712 14421376
23:39:58 2285 8192000000 10273349088 10678747136 1354599248 14.189 100 76 152 1 1384199488 12623696 16976544
23:41:03 5561 8192000000 10279635872 10696720384 1364738016 14.280 100 60 120 0 1398667152 20287328 13641808
23:42:19 16141 8192000000 10291249088 10717212672 1378207744 14.401 100 42 84 1 1406604432 18555632 9841056
23:43:42 61115 8192000000 10300211312 10737811456 1390493136 14.510 100 26 49 1 1413247520 16516240 6238144
23:45:54 13570 8192000000 10311870944 10746515456 1403365568 14.625 100 31 62 0 1435483312 24271600 7846144
23:47:15 8456 8192000000 10349876384 10769264640 1416687232 14.743 100 70 140 1 1458508336 25250976 16570128
23:48:29 15860 8192000000 10350211808 10776223744 1418145168 14.756 100 50 96 0 1459422544 29474400 11802976
23:49:50 4802 8192000000 10353974176 10777886720 1419340896 14.767 100 68 135 1 1468486960 32632352 16513712
23:51:04 4377 8192000000 10356735840 10785529856 1426547392 14.831 100 51 102 1 1468419648 29563136 12309120
23:52:16 2118 8192000000 10389561200 10803097600 1432488784 14.883 100 90 180 0 1492091072 36633088 22969200
23:53:27 1546 8192000000 10382656080 10795651072 1421748704 14.788 100 88 176 1 1484806208 40206816 22850688
23:54:33 2468 8192000000 10382664144 10800029696 1423315696 14.802 100 83 163 3 1491671232 46854288 21501248
23:55:42 4627 8192000000 10360054544 10766344192 1382578240 14.440 99 79 156 0 1448446384 46001504 19866640
23:56:53 2399 8192000000 10354157216 10764185600 1395314272 14.553 100 82 164 1 1456544672 40494192 20736208
23:58:02 8457 8192000000 10345431728 10767380480 1386154944 14.472 100 56 110 1 1447425744 46958800 14312000
23:59:23 12433 8192000000 10342492368 10766503936 1376245840 14.383 99 42 84 0 1448403424 60173824 11983760
00:00:38 20067 8192000000 10302261776 10744766464 1364645088 14.279 99 26 52 1 1410346416 38484432 7216896
00:02:08 18931 8192000000 10301499072 10736545792 1342464960 14.080 97 36 72 0 1385891584 33884112 9542512
00:03:28 35542 8192000000 10265445200 10709102592 1307553024 13.764 97 14 26 1 1349061056 37708768 3799264
00:05:05 38123 8192000000 10265271248 10704359424 1354725104 14.190 100 27 54 0 1371875056 10592048 6557904
00:06:47 151861 8192000000 10275158336 10711982080 1333136432 13.995 97 38 76 1 1356402272 14625840 8640000
00:10:19 62080 8192000000 10301249600 10736467968 1367968848 14.309 99 33 66 1 1387496112 12212864 7314400
00:12:23 42731 8192000000 10273954112 10701520896 1325865200 13.930 97 35 69 2 1344789600 11082288 7842112
00:14:06 43228 8192000000 10292187712 10732150784 1361641264 14.252 99 25 50 1 1382882672 15022784 6218624
00:15:50 49434 8192000000 10278768208 10744864768 1373735936 14.361 100 26 52 0 1403407840 23285248 6386656
00:17:51 30840 8192000000 10280231696 10730455040 1371989728 14.345 99 31 62 0 1386262688 7425184 6847776
00:19:22 11000 8192000000 10285323024 10743939072 1383566960 14.448 100 4 8 1 1395785840 11561440 657440
00:20:35 80444 8192000000 10281961440 10751991808 1387153008 14.480 100 13 26 0 1399381120 9214144 3013968
00:22:56 49261 8192000000 10301223168 10765438976 1404016592 14.631 100 23 46 1 1418600032 9370464 5212976
00:24:50 36350 8192000000 10306959088 10763370496 1389839232 14.504 99 14 28 1 1403492304 10330720 3322352
00:26:44 51754 8192000000 10307502560 10779471872 1398823520 14.585 100 7 14 0 1417674864 17155712 1695632
00:28:58 38247 8192000000 10302340016 10761003008 1371497232 14.340 98 19 38 0 1393711664 17609424 4605008
00:30:39 72618 8192000000 10310747024 10779471872 1406210112 14.650 100 16 32 0 1424419712 15158672 3050928
00:32:52 50911 8192000000 10316910560 10767380480 1391835152 14.522 99 23 46 0 1410869840 14180608 4854080
00:34:45 74161 8192000000 10283940960 10736160768 1316516096 13.845 94 16 32 0 1332323536 12267760 3539680
00:37:03 34383 8192000000 10290008528 10741018624 1337816224 14.038 96 25 50 0 1359701984 16358528 5527232
00:38:38 79260 8192000000 10284151008 10732937216 1340287696 14.060 97 19 38 1 1356146480 11856064 4002720
00:41:37 17096 8192000000 10255846496 10694709248 1332100896 13.986 97 30 60 0 1350111648 11160400 6850352
00:42:55 59525 8192000000 10265896912 10728538112 1358284368 14.222 100 14 28 0 1372505440 11329056 2892016
00:45:06 44831 8192000000 10271690000 10727120896 1343299072 14.087 97 17 33 0 1355290928 8383136 3608720
00:46:51 39333 8192000000 10276997312 10722242560 1352650896 14.171 99 30 59 0 1366470864 6881296 6938672
00:48:33 46090 8192000000 10246632032 10695766016 1321684048 13.892 98 21 43 1 1335375088 8922864 4768176
00:50:22 55690 8192000000 10258045424 10709307392 1326303392 13.934 98 17 34 0 1339840736 9037648 4499696
00:52:29 57275 8192000000 10298863872 10749034496 1377036960 14.390 100 26 52 2 1399128896 16149552 5942384
00:54:40 74994 8192000000 10261350896 10705219584 1322682544 13.901 96 18 37 0 1335226240 8851216 3692480
00:57:00 112102 8192000000 10264538160 10715004928 1286796960 13.575 94 20 40 1 1302580224 11539760 4243504
00:59:53 69318 8192000000 10278116704 10738450432 1368181888 14.311 100 23 45 1 1386595984 12407376 6006720
01:02:14 90513 8192000000 10277945856 10749652992 1372569760 14.350 99 23 46 1 1394187792 16309168 5308864
01:04:55 30074 8192000000 10290168656 10737053696 1372949168 14.353 100 13 26 0 1399849920 23955152 2945600
01:06:26 41319 8192000000 10280091520 10736218112 1367502048 14.305 99 26 51 1 1388842992 15399136 5941808
01:08:13 66275 8192000000 10289207328 10758971392 1391869632 14.523 100 13 26 1 1404692816 10242416 2580768
01:10:37 63638 8192000000 10296024352 10759839744 1385487968 14.466 99 19 37 1 1401715616 12178896 4048752
01:12:44 70240 8192000000 10292961072 10755158016 1349488928 14.143 96 20 40 0 1363747072 9948544 4309600
01:14:54 122008 8192000000 10313281584 10778054656 1389386304 14.500 99 20 40 1 1404938432 11014240 4537888
01:17:56 19965 8192000000 10322180224 10763022336 1385286816 14.464 99 44 88 1 1413761504 18322784 10151904
01:19:17 93668 8192000000 10287409264 10739994624 1313393872 13.817 94 12 24 2 1324495792 8638064 2463856
01:21:55 136536 8192000000 10300084176 10745249792 1338095392 14.040 96 33 64 0 1354677344 9360192 7221760
01:26:27 59533 8192000000 10274468896 10744025088 1347176848 14.122 98 11 22 1 1367292000 17953184 2161968
01:28:38 61018 8192000000 10262850944 10719150080 1325643136 13.928 97 8 16 0 1337019136 9684128 1691872
01:30:41 68880 8192000000 10251912384 10719096832 1358626800 14.225 100 9 18 0 1370838512 10244272 1967440
01:32:50 72246 8192000000 10256478240 10708484096 1282648304 13.537 94 10 20 1 1292567408 8074528 1844576
01:35:05 70248 8192000000 10285167616 10728603648 1339836064 14.056 98 18 36 0 1357169168 13191456 4141648
01:37:30 32395 8192000000 9218142352 9484603392 375113408 4.378 27 9 16 2 389812064 12409440 2289216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-10-2021 01:39:27 to 09-10-2021 01:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 173 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2021 01:39:43 to 09-10-2021 01:39:45.

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          245605
MON$OLDEST_ACTIVE               245606
MON$OLDEST_SNAPSHOT             245606
MON$NEXT_TRANSACTION            245606
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                       2018816
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.33519 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33519 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33519 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33519 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 ---
--- end of diff output ---

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

09.10.21 01:39:46. 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-09 01:39:48 No severe PSQL-related problems occured
09.10.21 01:39:50. End of report.