Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.02.2021 04:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 1440.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 16554384 kB
MemAvailable 31108960 kB
Buffers 20 kB
Cached 15099588 kB
SwapCached 3892 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.0
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-02-24 04:26:57
DTS_END                         2021-02-24 04:26:57.2600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59216
TRN_ID                          225682
ATT_ID                          674
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8644.58 21791 1037350
customer order: creation 158.84 430 19061
customer order: refuse 13.63 309 1635
order to supplier: creation 42.54 1595 5105
order to supplier: removal 3.39 116773 407
invoice (draft): creation 42.22 3880 5066
invoice (draft): removal 50.83 1045 6099
invoice accept: apply 37.92 18348 4551
invoice accept: cancel 45.98 889 5518
customer reserve: creation 3505.30 179 420636
customer reserve: removal 4466.10 69 535932
realization accept: apply 77.70 196 9324
realization accept: cancel 81.71 116 9805
payment from customer: creation 54.15 113 6498
payment from customer: removal 5.34 82 641
payment to supplier: creation 45.03 151 5403
payment to supplier: removal 3.58 76 430
service: total inventory turnovers 3.70 3299 444
service: total monetary turnovers 5.80 528 696
service: aggregate perf. data 0.79 16545 95
service: refresh index statistics 0.03 271202 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-02-2021 04:28:30 to 24-02-2021 04:28:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4020
TEST_TIME 2 4473
TEST_TIME 3 4853
TEST_TIME 4 5406
TEST_TIME 5 4839
TEST_TIME 6 4989
TEST_TIME 7 5083
TEST_TIME 8 5321
TEST_TIME 9 5546
TEST_TIME 10 5636
TEST_TIME 11 6172
TEST_TIME 12 6462
TEST_TIME 13 6523
TEST_TIME 14 6812
TEST_TIME 15 6975
TEST_TIME 16 7140
TEST_TIME 17 7304
TEST_TIME 18 7991
TEST_TIME 19 8267
TEST_TIME 20 8474
TEST_TIME 21 8514
TEST_TIME 22 8734
TEST_TIME 23 8668
TEST_TIME 24 9108
TEST_TIME 25 9465
TEST_TIME 26 9829
TEST_TIME 27 10318
TEST_TIME 28 11268
TEST_TIME 29 11057
TEST_TIME 30 10779
TEST_TIME 31 10548
TEST_TIME 32 10396
TEST_TIME 33 10351
TEST_TIME 34 10210
TEST_TIME 35 10200
TEST_TIME 36 10063
TEST_TIME 37 9894
TEST_TIME 38 9812
TEST_TIME 39 9635
TEST_TIME 40 9643
TEST_TIME 41 9652
TEST_TIME 42 9511
TEST_TIME 43 9420
TEST_TIME 44 9484
TEST_TIME 45 9347
TEST_TIME 46 9264
TEST_TIME 47 9345
TEST_TIME 48 9393
TEST_TIME 49 9338
TEST_TIME 50 9337
TEST_TIME 51 9302
TEST_TIME 52 9296
TEST_TIME 53 9364
TEST_TIME 54 9301
TEST_TIME 55 9374
TEST_TIME 56 9266
TEST_TIME 57 9392
TEST_TIME 58 9471
TEST_TIME 59 9487
TEST_TIME 60 9430
TEST_TIME 61 9289
TEST_TIME 62 9222
TEST_TIME 63 9109
TEST_TIME 64 9080
TEST_TIME 65 9095
TEST_TIME 66 9091
TEST_TIME 67 9059
TEST_TIME 68 9048
TEST_TIME 69 9091
TEST_TIME 70 9057
TEST_TIME 71 9006
TEST_TIME 72 9003
TEST_TIME 73 9049
TEST_TIME 74 9044
TEST_TIME 75 9048
TEST_TIME 76 9020
TEST_TIME 77 9027
TEST_TIME 78 9018
TEST_TIME 79 9143
TEST_TIME 80 9083
TEST_TIME 81 8987
TEST_TIME 82 8927
TEST_TIME 83 8887
TEST_TIME 84 8856
TEST_TIME 85 8797
TEST_TIME 86 8768
TEST_TIME 87 8752
TEST_TIME 88 8772
TEST_TIME 89 8810
TEST_TIME 90 8786
TEST_TIME 91 8808
TEST_TIME 92 8855
TEST_TIME 93 8905
TEST_TIME 94 8879
TEST_TIME 95 8870
TEST_TIME 96 9021
TEST_TIME 97 9005
TEST_TIME 98 8919
TEST_TIME 99 8869
TEST_TIME 100 8849
TEST_TIME 101 8802
TEST_TIME 102 8750
TEST_TIME 103 8709
TEST_TIME 104 8687
TEST_TIME 105 8732
TEST_TIME 106 8714
TEST_TIME 107 8692
TEST_TIME 108 8677
TEST_TIME 109 8715
TEST_TIME 110 8708
TEST_TIME 111 8694
TEST_TIME 112 8788
TEST_TIME 113 8801
TEST_TIME 114 8850
TEST_TIME 115 8812
TEST_TIME 116 8781
TEST_TIME 117 8722
TEST_TIME 118 8709
TEST_TIME 119 8669
TEST_TIME 120 8645

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-02-2021 04:28:34 to 24-02-2021 04:28:37.

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 1426520 1426520 0 0.00 0 15670 6 0 0
doc_list_biud 1426520 1426520 0 0.00 0 24836 33 0 0
sp_add_invoice_to_stock 4623 4551 72 1.55 35 179581 18349 0 72
sp_cancel_adding_invoice 5518 5518 0 0.00 5 69730 889 0 0
sp_cancel_client_order 1635 1635 0 0.00 4 8001 309 0 0
sp_cancel_customer_reserve 535932 535932 0 0.00 1 24841 69 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 5104 82 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 5853 77 0 0
sp_cancel_supplier_invoice 6103 6099 4 0.06 1 25108 1045 0 4
sp_cancel_supplier_order 407 407 0 0.00 5 1052085 116774 0 0
sp_cancel_write_off 9805 9805 0 0.00 1 8615 116 0 0
sp_client_order 19061 19061 0 0.00 4 21989 430 0 0
sp_customer_reserve 420636 420636 0 0.00 0 94967 179 0 0
sp_fill_shopping_cart 29214 29214 0 0.00 1 16287 53 0 0
sp_fill_shopping_cart_clo_res 420636 420636 0 0.00 0 25532 20 0 0
sp_get_clo_for_invoice 4550 4550 0 0.00 0 45919 439 0 0
sp_kill_cost_storno 16394 16394 0 0.00 0 12433 60 0 0
sp_kill_qstorno_ret_qs2qd 1088175 1088175 0 0.00 0 24832 38 0 0
sp_kill_qty_storno 573270 573270 0 0.00 0 24835 81 0 0
sp_lock_selected_doc 19349 19349 0 0.00 0 6887 5 0 0
sp_make_cost_storno 25775 25775 0 0.00 0 15571 62 0 0
sp_multiply_rows_for_pdistr 25775 25775 0 0.00 0 8299 30 0 0
sp_multiply_rows_for_qdistr 19061 19061 0 0.00 0 21978 235 0 0
sp_pay_from_customer 6498 6498 0 0.00 4 11963 114 0 0
sp_pay_to_supplier 5403 5403 0 0.00 5 14917 151 0 0
sp_payment_common 11901 11901 0 0.00 1 11765 25 0 0
sp_qd_handle_on_cancel_clo 1635 1635 0 0.00 0 7995 286 0 0
sp_qd_handle_on_invoice_upd_sts 10068 10068 0 0.00 0 15018 392 0 0
sp_qd_handle_on_reserve_upd_sts 9324 9324 0 0.00 0 13548 58 0 0
sp_reserve_write_off 9324 9324 0 0.00 7 14194 197 0 0
sp_supplier_invoice 5066 5066 0 0.00 7 79437 3881 0 0
sp_supplier_order 5105 5105 0 0.00 6 96165 1595 0 0
srv_aggregate_perf_data 96 95 1 1.04 2842 253797 16546 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2231 135546 47273 0 0
srv_find_qd_qs_mism 993418 993418 0 0.00 0 13218 5 0 0
srv_make_invnt_saldo 444 444 0 0.00 45 96738 3299 0 0
srv_make_money_saldo 696 696 0 0.00 1 11082 528 0 0
srv_recalc_idx_stat 4 4 0 0.00 156465 378970 271202 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 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 4552 4552 0 0.00 0 9487 159 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 9959 104 0 0
t$perf-norm:sp_cancel_client_order 1635 1635 0 0.00 0 3306 12 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1182 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 255 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 5002 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 531 11 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 17003 1496 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 2115 5 0 0
t$perf-norm:sp_client_order 19061 19061 0 0.00 0 14723 19 0 0
t$perf-norm:sp_customer_reserve 874 874 0 0.00 0 1117 4 0 0
t$perf-norm:sp_pay_from_customer 6498 6498 0 0.00 0 9352 9 0 0
t$perf-norm:sp_pay_to_supplier 5403 5403 0 0.00 0 4779 10 0 0
t$perf-norm:sp_reserve_write_off 9324 9324 0 0.00 0 6082 10 0 0
t$perf-norm:sp_supplier_invoice 5066 5066 0 0.00 0 6197 24 0 0
t$perf-norm:sp_supplier_order 5105 5105 0 0.00 0 5537 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 226 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 31 1 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 939 7 0 0
t$perf-norm:srv_make_money_saldo 696 696 0 0.00 0 5563 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 9242 9098 144 1.55 0 6687 17 0 144
v_all_customers 34526 34526 0 0.00 0 20 1 0 0
v_all_suppliers 28198 28198 0 0.00 0 20 1 0 0
v_all_wares 38122 38122 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2226 9 0 0
v_cancel_client_order 3270 3270 0 0.00 0 2116 4 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 5051 9 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 1987 7 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 5841 12 0 0
v_cancel_supplier_invoice 748 740 8 1.06 0 392 11 0 8
v_cancel_supplier_order 814 814 0 0.00 0 847 4 0 0
v_cancel_write_off 1824 1824 0 0.00 0 1882 14 0 0
v_max_id_clo_ord 5105 5105 0 0.00 0 1650 2 0 0
v_max_id_clo_res 874 874 0 0.00 0 4600 20 0 0
v_max_id_ord_sup 5047 5047 0 0.00 0 7879 6 0 0
v_max_non_paid_invoice 1456 1456 0 0.00 0 1079 2 0 0
v_max_non_paid_realizn 3509 3509 0 0.00 0 1155 3 0 0
v_min_id_clo_ord 5105 5105 0 0.00 0 7609 10 0 0
v_min_id_clo_res 874 874 0 0.00 0 5532 21 0 0
v_min_id_ord_sup 5047 5047 0 0.00 0 9190 14 0 0
v_min_non_paid_invoice 5403 5403 0 0.00 0 8049 19 0 0
v_min_non_paid_realizn 6498 6498 0 0.00 0 6792 18 0 0
v_our_firm 9574 9574 0 0.00 0 14 1 0 0
v_reserve_write_off 18648 18648 0 0.00 0 7518 9 0 0
x_lock_depdocs_on_canc_invoice 11247 11247 0 0.00 0 13968 114 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 14222 318 0 0
x_make_qstorno_1000_1200 5105 5105 0 0.00 2 96155 1508 0 0
x_make_qstorno_1200_2000 5066 5066 0 0.00 3 79432 3664 0 0
x_make_qstorno_2100_3300 420636 420636 0 0.00 0 94963 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-02-2021 04:28:38 to 24-02-2021 04:28:42.

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
01:57:00 4 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 16296 8192000000 9713100848 10009628672 793878288 8.834 100 81 162 1 821504640 7591072 20035280
01:59:27 15786 8192000000 9767339712 10074165248 841666336 9.316 100 83 166 0 872207040 10701184 19839520
02:00:46 14693 8192000000 9790378112 10103664640 860694448 9.507 100 87 174 0 899407632 18041808 20671376
02:02:03 12795 8192000000 9853624480 10185412608 905063904 9.948 100 71 142 0 957202144 36109936 16028304
02:03:19 8551 8192000000 9910622368 10250412032 958789360 10.477 100 86 172 0 1020962480 44065968 18107152
02:04:33 4734 8192000000 9976216400 10327330816 997188928 10.851 100 87 174 0 1087297712 72523776 17585008
02:05:40 3031 8192000000 10009482576 10371104768 1025855728 11.129 100 87 172 1 1125277248 82256880 17164640
02:06:45 1792 8192000000 10036295424 10402439168 1049588160 11.357 100 88 176 0 1155629504 87862080 18179264
02:07:49 1587 8192000000 10029028512 10398720000 1064741008 11.502 100 93 182 2 1147204480 62896256 19567216
02:08:51 7060 8192000000 9976412320 10374107136 1069849472 11.551 100 27 53 3 1102125968 26126960 6149536
02:10:00 68436 8192000000 9998896592 10405871616 1078770416 11.636 100 31 62 0 1095382176 10393248 6218512
02:12:21 4913 8192000000 10033411152 10427912192 1096035680 11.800 100 55 111 1 1134014464 27095712 10883072
02:13:29 8177 8192000000 10016363904 10424311808 1105602240 11.891 100 24 47 0 1132494000 21811840 5079920
02:14:39 12545 8192000000 10029180384 10446225408 1109937168 11.932 100 19 37 5 1136733968 23179424 3617376
02:15:54 12735 8192000000 10029100368 10434265088 1086584496 11.710 98 38 78 1 1118668896 23352688 8731712
02:17:11 7900 8192000000 10005477072 10416119808 1073265104 11.583 98 31 62 0 1097914112 17708112 6940896
02:18:20 4882 8192000000 9971687808 10352472064 1051527392 11.375 99 37 74 3 1076945840 16280336 9138112
02:19:28 8349 8192000000 9935924384 10319015936 1002013280 10.898 96 32 64 1 1027951664 19297344 6641040
02:20:38 32432 8192000000 9930306464 10326454272 957315872 10.463 93 29 58 0 983683328 19993472 6373984
02:22:11 6997 8192000000 9943031392 10335895552 1030392672 11.172 100 44 86 2 1054066768 14141792 9532304
02:23:23 5887 8192000000 9975821936 10378694656 1057532016 11.433 100 48 94 1 1085782576 17414640 10835920
02:24:31 56697 8192000000 9965956208 10366423040 1065638416 11.510 100 21 42 1 1088185664 17536320 5010928
02:26:27 6994 8192000000 9998196352 10402607104 1074827200 11.598 100 33 66 0 1112006672 29228528 7950944
02:27:39 19173 8192000000 10041612656 10437931008 1102970160 11.866 100 62 122 1 1142482992 26587328 12925504
02:29:15 4072 8192000000 10084303728 10485772288 1129558640 12.117 100 56 112 1 1195854432 54972864 11322928
02:30:24 41538 8192000000 10117950384 10544582656 1141002848 12.225 100 39 78 0 1216400688 66864384 8533456
02:32:32 10343 8192000000 10166994048 10583875584 1151607456 12.325 100 51 101 3 1273697968 110831952 11258560
02:33:57 2229 8192000000 10208488464 10618904576 1160851760 12.411 100 63 121 2 1312976288 139159568 12964960
02:35:04 3413 8192000000 10237254320 10652942336 1172045040 12.516 100 73 146 1 1338659280 149894864 16719376
02:36:13 12294 8192000000 10268225840 10683375616 1170031424 12.497 100 48 96 0 1370026816 188314928 11680464
02:37:28 7910 8192000000 10277012464 10702516224 1175774512 12.551 100 55 111 0 1391755200 202778272 13202416
02:38:42 3736 8192000000 10293647472 10707017728 1187248880 12.658 100 72 143 2 1397472048 193879856 16343312
02:39:52 2498 8192000000 10311285520 10720301056 1168733472 12.485 100 72 144 1 1414076624 228709008 16634144
02:40:58 3160 8192000000 10303017392 10720411648 1174335408 12.537 99 77 150 1 1421635888 230579664 16720816
02:42:05 2423 8192000000 10387443664 10799763456 1194933120 12.729 100 84 168 0 1494841840 280331888 19576832
02:43:13 2421 8192000000 10436004672 10842308608 1177216384 12.564 100 86 169 2 1532102432 335661136 19224912
02:44:21 4629 8192000000 10429918608 10856550400 1175535456 12.549 100 48 96 0 1538904400 351385856 11983088
02:45:33 13102 8192000000 10401136048 10824019968 1169524976 12.492 100 38 76 0 1487581152 308503568 9552608
02:46:49 19748 8192000000 10407176992 10839060480 1160092496 12.404 99 39 78 0 1491169088 320928400 10148192
02:48:13 18121 8192000000 10417839856 10856587264 1140718592 12.222 99 39 78 1 1513422048 361923504 10779952
02:49:45 15362 8192000000 10430150816 10896797696 1133174064 12.151 99 33 66 0 1539612288 397461808 8976416
02:51:04 22786 8192000000 10383634624 10837635072 1146785248 12.279 100 29 58 1 1508410096 354148928 7475920
02:52:27 30022 8192000000 10331990384 10826129408 1119318176 12.021 99 21 42 0 1458704752 333327024 6059552
02:54:23 39820 8192000000 10082454064 10565017600 1120858256 12.035 99 12 24 2 1202523616 79215088 2450272
02:56:07 22651 8192000000 10053644304 10517876736 1119272496 12.020 99 31 60 1 1156387712 30116672 6998544
02:57:34 27258 8192000000 10071879920 10500329472 1112708432 11.958 98 33 67 1 1150566848 31393456 6464960
02:59:06 80049 8192000000 10078342064 10589839360 1107454208 11.908 98 9 18 0 1171504352 62173712 1876432
03:01:26 59440 8192000000 10070911168 10508013568 1096636896 11.806 97 32 64 1 1142735088 39186976 6911216
03:03:33 108893 8192000000 10128716448 10605547520 1119668672 12.024 99 15 30 0 1252652528 129168304 3815552
03:07:10 69235 8192000000 10079193872 10530820096 1116664688 11.995 99 16 32 2 1180610768 59994336 3951744
03:09:30 50982 8192000000 10087614640 10580127744 1124870496 12.073 99 16 31 2 1241114000 112448128 3795376
03:11:25 41990 8192000000 10090594736 10549256192 1129832608 12.120 100 23 46 1 1181320688 46323904 5164176
03:13:19 74429 8192000000 10080768080 10624188416 1117211600 12.001 99 10 19 0 1206437728 87240096 1986032
03:15:36 30871 8192000000 10068164368 10499252224 1111300032 11.945 98 30 59 2 1148186608 30441360 6445216
03:17:10 49929 8192000000 10103576176 10567225344 1132742352 12.147 100 36 72 2 1175359072 35055264 7561456
03:19:00 66111 8192000000 10067591664 10534473728 1139029696 12.206 100 12 24 0 1208052064 66667632 2354736
03:21:08 18241 8192000000 10122799760 10548740096 1148007024 12.291 100 53 106 1 1209931968 51564256 10360688
03:22:31 40140 8192000000 10162903456 10604654592 1142266672 12.237 98 34 66 3 1226082192 76652784 7162736
03:24:16 103115 8192000000 10096437008 10553917440 1100550352 11.843 96 21 42 1 1125207552 20477984 4179216
03:27:00 87997 8192000000 10069261680 10515976192 1127720384 12.100 99 15 30 1 1170999552 40387152 2892016
03:29:41 48622 8192000000 10092263632 10569408512 1134780928 12.166 99 7 14 0 1201428928 65331664 1316336
03:31:30 51688 8192000000 10055947984 10538713088 1098394224 11.822 97 17 32 1 1132031440 30760080 2877136
03:33:22 83078 8192000000 10115344880 10569236480 1108727024 11.920 97 19 38 1 1157556672 44719664 4109984
03:36:00 86289 8192000000 10102773072 10580307968 1119032000 12.018 98 21 42 1 1214119184 90014784 5072400
03:38:29 117003 8192000000 10048148048 10511618048 1076367232 11.613 95 16 32 0 1133713104 54064624 3281248
03:41:26 74461 8192000000 10072062416 10511020032 1091498896 11.757 97 27 54 1 1166489408 69380128 5610384
03:43:47 43734 8192000000 10109825024 10536161280 1101410832 11.851 99 36 72 0 1171365584 62444960 7509792
03:45:37 85662 8192000000 10047017840 10483560448 1061968288 11.475 95 11 20 1 1109177824 44705456 2504080
03:48:34 66290 8192000000 10052823248 10501189632 1096424336 11.804 98 9 18 0 1141250688 42929216 1897136
03:50:40 40893 8192000000 10036032656 10514608128 1110390880 11.936 100 6 12 0 1134475792 22813312 1271600
03:52:43 55040 8192000000 10097340608 10535043072 1104294672 11.878 98 29 58 0 1175619760 65290144 6034944
03:54:39 56485 8192000000 10098275744 10557181952 1096999632 11.809 98 31 62 0 1198295392 94470848 6824912
03:56:44 35429 8192000000 10088132992 10509611008 1106667168 11.901 98 32 62 2 1153786640 40979216 6140256
03:58:26 135546 8192000000 10084736400 10534785024 1086302560 11.707 95 14 28 0 1162858160 73337376 3218224
04:01:42 46811 8192000000 10134778544 10660409344 1132029024 12.140 100 17 34 1 1281177392 145111296 4037072
04:03:39 128128 8192000000 10068553280 10512961536 1119826352 12.025 99 20 40 1 1167758240 43941008 3990880
04:06:52 68368 8192000000 10067365760 10515656704 1107485264 11.909 97 9 18 1 1135618144 25894768 2238112
04:09:01 54669 8192000000 10087101104 10550157312 1100676096 11.844 97 12 24 0 1152930976 49641136 2613744
04:10:58 111982 8192000000 10046019792 10501586944 1118218192 12.010 99 14 28 1 1183577232 62141088 3217952
04:13:57 34141 8192000000 10106977936 10537844736 1126272592 12.086 98 33 66 3 1166894624 32569056 8052976
04:15:36 52342 8192000000 10112918720 10575020032 1142519056 12.239 100 38 76 1 1216469344 65676352 8273936
04:17:43 107466 8192000000 10111760144 10591592448 1142971408 12.243 99 17 32 2 1249589216 102698208 3919600
04:20:35 41908 8192000000 10079942240 10566303744 1125975136 12.083 98 10 19 1 1190740736 62273392 2492208
04:22:17 93313 8192000000 10112239680 10565943296 1141050496 12.225 100 51 101 0 1221922464 70803840 10068128
04:25:04 29582 8192000000 10070321744 10553262080 1135009744 12.169 99 6 12 0 1165724432 29691424 1023264
04:26:45 19092 8192000000 9492423200 9866813440 614922176 6.982 54 2 4 0 634668416 19480944 265296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-02-2021 04:28:44 to 24-02-2021 04:28:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 72 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-02-2021 04:29:00 to 24-02-2021 04:29:02.

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          226036
MON$OLDEST_ACTIVE               226037
MON$OLDEST_SNAPSHOT             226037
MON$NEXT_TRANSACTION            226037
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                       2082784
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/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 ---
:157: 
:158: mscs-test-161.budzdorov.ru    Wed Feb 24 04:27:13 2021
:159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:160: 
:161: 
:162: mscs-test-161.budzdorov.ru    Wed Feb 24 04:27:15 2021
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:164: 
--- end of diff output ---

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

24.02.21 04:29:03. 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-02-24 04:29:05 No severe PSQL-related problems occured
24.02.21 04:29:07. End of report.