Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.08.2021 13: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 15968212 kB
MemAvailable 31069244 kB
Buffers 0 kB
Cached 15747444 kB
SwapCached 2592 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-10 13:37:06
DTS_END                         2021-08-10 13:37:06.3980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40704
TRN_ID                          232254
ATT_ID                          684
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: 9253.84 19598 1110461
customer order: creation 171.51 380 20581
customer order: refuse 14.86 345 1783
order to supplier: creation 45.28 1554 5433
order to supplier: removal 3.39 120046 407
invoice (draft): creation 45.72 3530 5486
invoice (draft): removal 52.53 1084 6303
invoice accept: apply 41.72 16817 5006
invoice accept: cancel 47.55 870 5706
customer reserve: creation 3884.63 163 466156
customer reserve: removal 4651.72 67 558206
realization accept: apply 81.49 191 9779
realization accept: cancel 87.98 132 10557
payment from customer: creation 57.58 147 6909
payment from customer: removal 5.58 74 670
payment to supplier: creation 48.31 148 5797
payment to supplier: removal 3.50 127 420
service: total inventory turnovers 3.75 3877 450
service: total monetary turnovers 5.91 578 709
service: aggregate perf. data 0.83 18785 99
service: refresh index statistics 0.03 223037 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-08-2021 13:40:01 to 10-08-2021 13:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4981
TEST_TIME 2 4207
TEST_TIME 3 4254
TEST_TIME 4 4291
TEST_TIME 5 4450
TEST_TIME 6 4464
TEST_TIME 7 4573
TEST_TIME 8 4659
TEST_TIME 9 4894
TEST_TIME 10 5033
TEST_TIME 11 5338
TEST_TIME 12 5327
TEST_TIME 13 5759
TEST_TIME 14 6283
TEST_TIME 15 6465
TEST_TIME 16 6737
TEST_TIME 17 7140
TEST_TIME 18 7404
TEST_TIME 19 7662
TEST_TIME 20 7957
TEST_TIME 21 8015
TEST_TIME 22 8373
TEST_TIME 23 8617
TEST_TIME 24 8612
TEST_TIME 25 8675
TEST_TIME 26 8681
TEST_TIME 27 8834
TEST_TIME 28 9990
TEST_TIME 29 10949
TEST_TIME 30 10751
TEST_TIME 31 11044
TEST_TIME 32 10945
TEST_TIME 33 10795
TEST_TIME 34 10675
TEST_TIME 35 10709
TEST_TIME 36 10593
TEST_TIME 37 10455
TEST_TIME 38 10332
TEST_TIME 39 10381
TEST_TIME 40 10222
TEST_TIME 41 10363
TEST_TIME 42 10543
TEST_TIME 43 10485
TEST_TIME 44 10413
TEST_TIME 45 10356
TEST_TIME 46 10271
TEST_TIME 47 10127
TEST_TIME 48 10030
TEST_TIME 49 9938
TEST_TIME 50 9905
TEST_TIME 51 9922
TEST_TIME 52 9823
TEST_TIME 53 9727
TEST_TIME 54 9822
TEST_TIME 55 9745
TEST_TIME 56 9763
TEST_TIME 57 9698
TEST_TIME 58 9595
TEST_TIME 59 9606
TEST_TIME 60 9668
TEST_TIME 61 9648
TEST_TIME 62 9677
TEST_TIME 63 9683
TEST_TIME 64 9646
TEST_TIME 65 9618
TEST_TIME 66 9813
TEST_TIME 67 9770
TEST_TIME 68 9771
TEST_TIME 69 9789
TEST_TIME 70 9900
TEST_TIME 71 9871
TEST_TIME 72 10001
TEST_TIME 73 9903
TEST_TIME 74 9778
TEST_TIME 75 9807
TEST_TIME 76 9751
TEST_TIME 77 9679
TEST_TIME 78 9658
TEST_TIME 79 9673
TEST_TIME 80 9665
TEST_TIME 81 9591
TEST_TIME 82 9520
TEST_TIME 83 9520
TEST_TIME 84 9498
TEST_TIME 85 9468
TEST_TIME 86 9432
TEST_TIME 87 9402
TEST_TIME 88 9446
TEST_TIME 89 9420
TEST_TIME 90 9423
TEST_TIME 91 9614
TEST_TIME 92 9577
TEST_TIME 93 9600
TEST_TIME 94 9581
TEST_TIME 95 9656
TEST_TIME 96 9614
TEST_TIME 97 9573
TEST_TIME 98 9531
TEST_TIME 99 9453
TEST_TIME 100 9468
TEST_TIME 101 9448
TEST_TIME 102 9401
TEST_TIME 103 9364
TEST_TIME 104 9371
TEST_TIME 105 9380
TEST_TIME 106 9357
TEST_TIME 107 9328
TEST_TIME 108 9315
TEST_TIME 109 9306
TEST_TIME 110 9327
TEST_TIME 111 9386
TEST_TIME 112 9372
TEST_TIME 113 9351
TEST_TIME 114 9365
TEST_TIME 115 9417
TEST_TIME 116 9461
TEST_TIME 117 9387
TEST_TIME 118 9338
TEST_TIME 119 9326
TEST_TIME 120 9257

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-08-2021 13:40:05 to 10-08-2021 13: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 1540555 1540555 0 0.00 0 27497 7 0 0
doc_list_biud 1540555 1540555 0 0.00 0 62831 32 0 0
sp_add_invoice_to_stock 5098 5006 92 1.80 52 194288 16818 0 92
sp_cancel_adding_invoice 5706 5706 0 0.00 3 63504 871 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 16958 346 0 0
sp_cancel_customer_reserve 558206 558206 0 0.00 1 23762 68 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 6050 75 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 12031 128 0 0
sp_cancel_supplier_invoice 6319 6303 16 0.25 1 67456 1085 0 16
sp_cancel_supplier_order 407 407 0 0.00 6 1309266 120047 0 0
sp_cancel_write_off 10557 10557 0 0.00 1 23843 132 0 0
sp_client_order 20581 20581 0 0.00 4 22097 380 0 0
sp_customer_reserve 466156 466156 0 0.00 0 126584 163 0 0
sp_fill_shopping_cart 31486 31486 0 0.00 1 21784 52 0 0
sp_fill_shopping_cart_clo_res 466151 466151 0 0.00 0 13450 15 0 0
sp_get_clo_for_invoice 5001 5001 0 0.00 0 25747 388 0 0
sp_kill_cost_storno 17353 17353 0 0.00 0 23797 71 0 0
sp_kill_qstorno_ret_qs2qd 1133679 1133679 0 0.00 0 62829 38 0 0
sp_kill_qty_storno 597741 597741 0 0.00 0 62831 81 0 0
sp_lock_selected_doc 20548 20548 0 0.00 0 9134 4 0 0
sp_make_cost_storno 27485 27485 0 0.00 0 27053 68 0 0
sp_multiply_rows_for_pdistr 27485 27485 0 0.00 0 22861 36 0 0
sp_multiply_rows_for_qdistr 20581 20581 0 0.00 0 22063 211 0 0
sp_pay_from_customer 6909 6909 0 0.00 4 26337 147 0 0
sp_pay_to_supplier 5797 5797 0 0.00 4 28512 148 0 0
sp_payment_common 12706 12706 0 0.00 1 24637 45 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 16953 323 0 0
sp_qd_handle_on_invoice_upd_sts 10706 10706 0 0.00 0 20240 367 0 0
sp_qd_handle_on_reserve_upd_sts 9779 9779 0 0.00 0 16633 55 0 0
sp_reserve_write_off 9779 9779 0 0.00 6 23078 191 0 0
sp_supplier_invoice 5486 5486 0 0.00 7 92556 3531 0 0
sp_supplier_order 5433 5433 0 0.00 5 37339 1555 0 0
srv_aggregate_perf_data 100 99 1 1.00 2735 195456 18785 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1650 156205 48243 0 0
srv_find_qd_qs_mism 1062258 1062258 0 0.00 0 19980 5 0 0
srv_make_invnt_saldo 450 450 0 0.00 65 109896 3877 0 0
srv_make_money_saldo 709 709 0 0.00 1 13720 578 0 0
srv_recalc_idx_stat 4 4 0 0.00 199391 250552 223037 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5007 5007 0 0.00 0 19525 151 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 5616 120 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 2052 15 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1779 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4757 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 721 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 4531 28 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 19149 1608 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 3226 15 0 0
t$perf-norm:sp_client_order 20581 20581 0 0.00 0 19478 17 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 4527 13 0 0
t$perf-norm:sp_pay_from_customer 6909 6909 0 0.00 0 8271 11 0 0
t$perf-norm:sp_pay_to_supplier 5797 5797 0 0.00 0 5355 7 0 0
t$perf-norm:sp_reserve_write_off 9779 9779 0 0.00 0 5742 11 0 0
t$perf-norm:sp_supplier_invoice 5486 5486 0 0.00 0 7137 20 0 0
t$perf-norm:sp_supplier_order 5433 5433 0 0.00 0 3889 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 210 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 179 5 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 915 11 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 6114 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 4 0 0
v_add_invoice_to_stock 10184 10000 184 1.80 0 6344 15 0 184
v_all_customers 37756 37756 0 0.00 0 27 1 0 0
v_all_suppliers 30346 30346 0 0.00 0 19 1 0 0
v_all_wares 41162 41162 0 0.00 0 103 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 755 8 0 0
v_cancel_client_order 3566 3566 0 0.00 0 727 3 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2483 6 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 580 3 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1004 4 0 0
v_cancel_supplier_invoice 814 782 32 3.93 0 4563 19 0 32
v_cancel_supplier_order 814 814 0 0.00 0 3689 13 0 0
v_cancel_write_off 1974 1974 0 0.00 0 6241 15 0 0
v_max_id_clo_ord 5433 5433 0 0.00 0 915 1 0 0
v_max_id_clo_res 917 917 0 0.00 0 9780 32 0 0
v_max_id_ord_sup 5472 5472 0 0.00 0 4248 4 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 23652 17 0 0
v_max_non_paid_realizn 3523 3523 0 0.00 0 861 2 0 0
v_min_id_clo_ord 5433 5433 0 0.00 0 21650 25 0 0
v_min_id_clo_res 917 917 0 0.00 0 1748 4 0 0
v_min_id_ord_sup 5472 5472 0 0.00 0 12103 8 0 0
v_min_non_paid_invoice 5797 5797 0 0.00 0 23674 30 0 0
v_min_non_paid_realizn 6909 6909 0 0.00 0 22879 40 0 0
v_our_firm 10178 10178 0 0.00 0 13 1 0 0
v_reserve_write_off 19558 19558 0 0.00 0 3586 4 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 10184 108 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 6370 344 0 0
x_make_qstorno_1000_1200 5433 5433 0 0.00 2 37328 1452 0 0
x_make_qstorno_1200_2000 5486 5486 0 0.00 4 91867 3339 0 0
x_make_qstorno_2100_3300 466156 466156 0 0.00 0 126583 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2021 13:40:09 to 10-08-2021 13: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
11:07:10 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 31373 8192000000 9735998272 10038235136 812683168 9.025 100 83 166 0 839969920 7346816 19939936
11:09:57 20320 8192000000 9777316720 10088259584 850265840 9.403 100 88 176 0 885511856 13923936 21322080
11:11:26 19172 8192000000 9831270080 10152562688 882010448 9.720 100 89 178 1 936076464 33579584 20486432
11:12:51 16125 8192000000 9875823936 10213548032 926952928 10.165 100 78 156 0 988980496 45288672 16738896
11:14:12 7696 8192000000 9928606528 10272759808 967289920 10.560 100 83 164 0 1034938368 50912384 16736064
11:15:22 4480 8192000000 9981475664 10335158272 987420944 10.756 100 87 174 1 1094526608 89076800 18028864
11:16:30 2937 8192000000 10003340864 10361909248 1017435936 11.047 100 84 168 0 1118132944 83787040 16909968
11:17:34 3528 8192000000 10020597136 10381873152 1038450160 11.250 100 86 172 1 1131647920 75468304 17729456
11:18:40 1020 8192000000 10042428096 10412969984 1063702400 11.492 100 92 186 1 1154251248 71611104 18937744
11:19:42 12052 8192000000 10029185376 10404433920 1074910432 11.599 100 78 158 1 1143082416 51700560 16471424
11:20:55 8801 8192000000 9991955296 10387677184 1077596272 11.625 100 39 78 1 1099865200 13398816 8870112
11:22:06 6920 8192000000 10010410816 10410602496 1084516144 11.690 100 36 71 3 1128028704 35236064 8276496
11:23:14 8542 8192000000 10000436864 10403614720 1087365312 11.718 100 32 64 1 1113697312 19564736 6767264
11:24:23 40088 8192000000 10020523808 10415022080 1093237360 11.773 100 67 135 0 1122101552 14002928 14861264
11:26:08 4799 8192000000 10017808768 10422046720 1099544144 11.833 100 41 82 3 1131014208 22819664 8650400
11:27:19 5425 8192000000 10015060272 10418061312 1097654096 11.815 100 41 83 0 1125340240 18758976 8927168
11:28:26 6766 8192000000 9987182480 10396712960 1055254752 11.411 98 24 46 3 1076936256 16703888 4977616
11:29:38 37985 8192000000 9943061536 10342989824 994098768 10.821 94 15 30 2 1018895632 21743024 3053840
11:31:18 5735 8192000000 9930265504 10306813952 1008465760 10.961 97 49 97 2 1037234560 18278224 10490576
11:32:25 7128 8192000000 9943448992 10332008448 1015110848 11.025 99 36 72 1 1036637136 13180288 8346000
11:33:36 9107 8192000000 9967366032 10365296640 1047790144 11.339 100 35 69 0 1070952352 16100624 7061584
11:34:46 22864 8192000000 9974169168 10383257600 1055271792 11.411 100 20 40 0 1082774560 23333120 4169648
11:36:10 5767 8192000000 9989291312 10391183360 1065033824 11.505 100 50 95 1 1116631152 40481968 11115360
11:37:22 19526 8192000000 9993164448 10389061632 1083055632 11.677 100 55 109 2 1105411600 10039680 12316288
11:39:09 17247 8192000000 10064605024 10465910784 1107964752 11.913 100 40 80 1 1166545888 50264080 8317056
11:40:30 51337 8192000000 10124185152 10535665664 1125937040 12.083 100 42 84 1 1219803024 84607728 9258256
11:42:30 55209 8192000000 10169424624 10587987968 1136820128 12.186 100 29 58 1 1266949296 123713888 6415280
11:44:50 12294 8192000000 10202730192 10615721984 1151514880 12.324 100 56 109 0 1308742880 145697200 11530800
11:46:14 14806 8192000000 10248212496 10674728960 1158519280 12.389 100 47 94 1 1345131888 175825632 10786976
11:47:34 14832 8192000000 10255081392 10689212416 1170626864 12.503 100 54 108 1 1367559024 184634208 12297952
11:48:59 5534 8192000000 10293334352 10715922432 1184014784 12.628 100 57 114 2 1401126496 203644976 13466736
11:50:07 2521 8192000000 10370263136 10786607104 1190409840 12.687 100 79 159 1 1471880096 262972384 18497872
11:51:14 3286 8192000000 10352073872 10786717696 1189828304 12.682 100 73 142 1 1456984112 250980768 16175040
11:52:20 3164 8192000000 10365975456 10772590592 1159403808 12.398 99 86 168 2 1453791040 275333552 19053680
11:53:30 1647 8192000000 10403867360 10815782912 1186030752 12.646 100 80 160 2 1504366576 299816624 18519200
11:54:37 3311 8192000000 10435435152 10855206912 1194475232 12.725 100 79 158 0 1539724736 326643472 18606032
11:55:46 10450 8192000000 10389599392 10808729600 1158425040 12.389 99 48 95 0 1483660320 313646896 11588384
11:57:01 2956 8192000000 10430678512 10840104960 1175198992 12.545 100 88 173 3 1523903376 327975680 20728704
11:58:07 7936 8192000000 10442199792 10865401856 1150938496 12.318 98 48 96 1 1528041136 365241488 11861152
11:59:20 1983 8192000000 10427373920 10844430336 1154300816 12.350 100 74 148 0 1528496720 356532288 17663616
12:00:26 12962 8192000000 10395862464 10841202688 1140283200 12.218 99 31 62 1 1483162992 335124320 7755472
12:01:47 13133 8192000000 10432220688 10860556288 1128060864 12.103 99 31 62 3 1519611200 383468016 8082320
12:03:04 17863 8192000000 10460574736 10903683072 1113757632 11.968 99 35 70 0 1554034176 431157056 9119488
12:04:31 26281 8192000000 10463773584 10928058368 1098465040 11.823 98 25 50 0 1548585504 443114336 7006128
12:06:00 5825 8192000000 10200970784 10790309888 1123645904 12.061 100 41 82 1 1328824816 195934480 9244432
12:07:51 27217 8192000000 10123876576 10588327936 1104279376 11.878 99 22 44 0 1217290336 108431872 4579088
12:09:20 102173 8192000000 10083566608 10524733440 1088868448 11.732 96 25 50 3 1129200320 35311168 5020704
12:12:04 73332 8192000000 10057131008 10499637248 1101858096 11.855 97 5 10 0 1131925200 29096624 970480
12:14:18 125912 8192000000 10067384944 10544001024 1115685984 11.986 98 14 28 0 1161911040 43586256 2638800
12:17:38 17690 8192000000 10117723392 10539245568 1143423840 12.248 100 54 104 0 1200394704 45285632 11685232
12:19:00 111627 8192000000 10079125408 10540331008 1132365408 12.144 99 7 14 0 1191861984 58157536 1339040
12:21:54 93390 8192000000 10116838528 10594942976 1142930256 12.243 100 17 34 0 1216757936 70278704 3548976
12:24:40 70411 8192000000 10097263920 10554499072 1130366640 12.125 98 32 64 0 1186392784 49441856 6584288
12:27:07 23780 8192000000 10100110400 10559254528 1119408784 12.021 98 15 30 1 1194713456 71984032 3320640
12:28:32 35965 8192000000 10134798400 10602414080 1148033120 12.291 100 39 79 0 1231828576 75435488 8359968
12:30:15 156204 8192000000 10106110992 10566782976 1113863984 11.969 98 19 38 1 1224916016 106899408 4152624
12:33:52 47974 8192000000 10061733776 10526617600 1123972848 12.065 99 14 28 0 1170724496 44276576 2475072
12:35:41 106603 8192000000 10074134144 10538627072 1097840864 11.817 96 14 28 0 1177376352 76346336 3189152
12:38:50 46576 8192000000 10084098656 10536407040 1115522032 11.985 98 20 40 1 1184335888 64324224 4489632
12:40:37 20248 8192000000 10076594880 10510749696 1142533152 12.239 100 46 92 0 1166462336 14053664 9875520
12:42:03 67985 8192000000 10096078080 10610663424 1115284288 11.982 98 20 40 0 1237719856 117793088 4642480
12:44:20 47180 8192000000 10061531920 10511564800 1101634592 11.853 98 7 13 1 1144744496 42149696 960208
12:46:13 84865 8192000000 10050553440 10556149760 1058836704 11.445 95 11 22 0 1197293456 135456912 2999840
12:48:38 57026 8192000000 10082076816 10540470272 1071212640 11.564 96 17 34 0 1137867840 63165600 3489600
12:50:40 149858 8192000000 10039147840 10529198080 1079213472 11.640 97 24 48 1 1131095216 46991648 4890096
12:54:11 73085 8192000000 10066999008 10547826688 1082165616 11.668 97 16 31 1 1158758048 73356016 3236416
12:56:27 81518 8192000000 10027975440 10509869056 1090798768 11.750 98 13 26 0 1142452192 48995360 2658064
12:58:57 90476 8192000000 10061294912 10559270912 1106903504 11.903 98 28 56 0 1176455408 63870048 5681856
13:01:31 66892 8192000000 10043365744 10502504448 1110058288 11.933 99 10 20 1 1138374400 25947840 2368272
13:03:43 35055 8192000000 10103969808 10546950144 1127203904 12.095 100 27 53 2 1189166560 56551376 5411280
13:05:18 58194 8192000000 10145203616 10624348160 1131525456 12.136 99 28 56 1 1243278096 105359680 6392960
13:07:19 75132 8192000000 10179204352 10673639424 1127084224 12.094 99 19 38 0 1298394208 166994320 4315664
13:10:01 32803 8192000000 10127784256 10572337152 1119835504 12.025 98 42 84 0 1171872416 44224272 7812640
13:11:33 30739 8192000000 10152398320 10627424256 1147868080 12.289 100 35 71 1 1235723248 80396992 7458176
13:13:06 40202 8192000000 10073836240 10511376384 1076155328 11.611 94 21 42 0 1114490080 34364912 3969840
13:14:48 106435 8192000000 10045979552 10484101120 1109579712 11.928 98 32 64 1 1139273696 23083280 6610704
13:17:41 43482 8192000000 10056781520 10543271936 1112416160 11.955 98 8 16 1 1180374128 66228640 1729328
13:19:25 65407 8192000000 10086815696 10559242240 1135290320 12.171 100 12 24 1 1183580272 45399568 2890384
13:21:37 86282 8192000000 10066635952 10565427200 1128927248 12.111 99 12 24 1 1168737184 37494720 2315216
13:24:04 35798 8192000000 10082129728 10524241920 1132118928 12.141 100 24 48 0 1165080992 27946512 5015552
13:25:46 75183 8192000000 10106964112 10567344128 1074236080 11.593 95 16 32 0 1157547712 79456864 3854768
13:28:08 58647 8192000000 10078167936 10534490112 1118671392 12.014 100 22 44 0 1155091568 31467728 4952448
13:30:19 31507 8192000000 10090953760 10523860992 1110783888 11.940 98 37 74 1 1184334928 66148064 7402976
13:31:51 66935 8192000000 10085301424 10580684800 1078199200 11.630 96 20 40 0 1161835408 79614720 4021488
13:34:19 78500 8192000000 10034492864 10474999808 1094605632 11.786 98 28 56 1 1126759776 25376144 6778000
13:36:38 36893 8192000000 9143882512 9411788800 297373376 3.502 27 3 6 0 314298992 16570624 354992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 92 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232188
MON$OLDEST_ACTIVE               232622
MON$OLDEST_SNAPSHOT             232622
MON$NEXT_TRANSACTION            232622
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2077856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2567 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2567 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Aug 10 13:37:24 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Tue Aug 10 13:37:47 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

10.08.21 13:40:34. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-10 13:40:36 No severe PSQL-related problems occured
10.08.21 13:40:38. End of report.