Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2021 13:37
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 15967660 kB
MemAvailable 30971448 kB
Buffers 0 kB
Cached 15701120 kB
SwapCached 2584 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-19 13:37:07
DTS_END                         2021-08-19 13:37:07.0250
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/41516
TRN_ID                          227026
ATT_ID                          650
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: 9130.59 25495 1095671
customer order: creation 165.55 406 19866
customer order: refuse 14.21 391 1705
order to supplier: creation 43.65 1509 5238
order to supplier: removal 3.38 136311 406
invoice (draft): creation 44.26 3636 5311
invoice (draft): removal 51.98 1080 6238
invoice accept: apply 40.16 19735 4819
invoice accept: cancel 47.13 926 5655
customer reserve: creation 3788.93 190 454671
customer reserve: removal 4653.27 77 558393
realization accept: apply 80.78 198 9694
realization accept: cancel 72.02 135 8642
payment from customer: creation 58.59 118 7031
payment from customer: removal 5.56 121 667
payment to supplier: creation 47.73 132 5728
payment to supplier: removal 3.19 114 383
service: total inventory turnovers 3.52 4413 422
service: total monetary turnovers 5.85 746 702
service: aggregate perf. data 0.80 17211 96
service: refresh index statistics 0.03 322446 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-08-2021 13:37:56 to 19-08-2021 13:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5946
TEST_TIME 2 6751
TEST_TIME 3 6328
TEST_TIME 4 6583
TEST_TIME 5 6323
TEST_TIME 6 6180
TEST_TIME 7 5900
TEST_TIME 8 5991
TEST_TIME 9 6294
TEST_TIME 10 6416
TEST_TIME 11 6752
TEST_TIME 12 6717
TEST_TIME 13 6611
TEST_TIME 14 6887
TEST_TIME 15 7068
TEST_TIME 16 7357
TEST_TIME 17 7540
TEST_TIME 18 7702
TEST_TIME 19 7765
TEST_TIME 20 7933
TEST_TIME 21 8081
TEST_TIME 22 8299
TEST_TIME 23 8183
TEST_TIME 24 8218
TEST_TIME 25 8362
TEST_TIME 26 8540
TEST_TIME 27 8930
TEST_TIME 28 9115
TEST_TIME 29 9791
TEST_TIME 30 10860
TEST_TIME 31 11103
TEST_TIME 32 11051
TEST_TIME 33 10991
TEST_TIME 34 10856
TEST_TIME 35 10618
TEST_TIME 36 10494
TEST_TIME 37 10379
TEST_TIME 38 10225
TEST_TIME 39 10146
TEST_TIME 40 10104
TEST_TIME 41 9954
TEST_TIME 42 10005
TEST_TIME 43 10085
TEST_TIME 44 10049
TEST_TIME 45 10127
TEST_TIME 46 9994
TEST_TIME 47 9991
TEST_TIME 48 9940
TEST_TIME 49 10038
TEST_TIME 50 10039
TEST_TIME 51 9930
TEST_TIME 52 9783
TEST_TIME 53 9706
TEST_TIME 54 9668
TEST_TIME 55 9583
TEST_TIME 56 9583
TEST_TIME 57 9551
TEST_TIME 58 9492
TEST_TIME 59 9515
TEST_TIME 60 9471
TEST_TIME 61 9368
TEST_TIME 62 9329
TEST_TIME 63 9225
TEST_TIME 64 9260
TEST_TIME 65 9399
TEST_TIME 66 9408
TEST_TIME 67 9459
TEST_TIME 68 9433
TEST_TIME 69 9420
TEST_TIME 70 9378
TEST_TIME 71 9452
TEST_TIME 72 9516
TEST_TIME 73 9514
TEST_TIME 74 9521
TEST_TIME 75 9522
TEST_TIME 76 9534
TEST_TIME 77 9559
TEST_TIME 78 9510
TEST_TIME 79 9420
TEST_TIME 80 9379
TEST_TIME 81 9325
TEST_TIME 82 9276
TEST_TIME 83 9242
TEST_TIME 84 9214
TEST_TIME 85 9203
TEST_TIME 86 9221
TEST_TIME 87 9152
TEST_TIME 88 9172
TEST_TIME 89 9134
TEST_TIME 90 9136
TEST_TIME 91 9186
TEST_TIME 92 9186
TEST_TIME 93 9169
TEST_TIME 94 9225
TEST_TIME 95 9187
TEST_TIME 96 9215
TEST_TIME 97 9219
TEST_TIME 98 9180
TEST_TIME 99 9291
TEST_TIME 100 9298
TEST_TIME 101 9341
TEST_TIME 102 9294
TEST_TIME 103 9226
TEST_TIME 104 9197
TEST_TIME 105 9151
TEST_TIME 106 9142
TEST_TIME 107 9102
TEST_TIME 108 9067
TEST_TIME 109 9040
TEST_TIME 110 9062
TEST_TIME 111 9017
TEST_TIME 112 9004
TEST_TIME 113 9059
TEST_TIME 114 9045
TEST_TIME 115 9120
TEST_TIME 116 9161
TEST_TIME 117 9147
TEST_TIME 118 9164
TEST_TIME 119 9211
TEST_TIME 120 9135

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-08-2021 13:38:00 to 19-08-2021 13:38:03.

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 1515362 1515362 0 0.00 0 33545 7 0 0
doc_list_biud 1515361 1515361 0 0.00 0 38519 36 0 0
sp_add_invoice_to_stock 4927 4819 108 2.19 45 193047 19736 0 108
sp_cancel_adding_invoice 5655 5655 0 0.00 4 51897 927 0 0
sp_cancel_client_order 1705 1705 0 0.00 4 10286 391 0 0
sp_cancel_customer_reserve 558393 558393 0 0.00 1 38528 78 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 8973 121 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 5108 114 0 0
sp_cancel_supplier_invoice 6244 6238 6 0.09 2 27331 1080 0 6
sp_cancel_supplier_order 406 406 0 0.00 7 1122046 136311 0 0
sp_cancel_write_off 8642 8642 0 0.00 1 21569 136 0 0
sp_client_order 19866 19866 0 0.00 4 40632 406 0 0
sp_customer_reserve 454671 454671 0 0.00 0 119295 191 0 0
sp_fill_shopping_cart 30401 30401 0 0.00 1 21350 45 0 0
sp_fill_shopping_cart_clo_res 454666 454666 0 0.00 0 18667 15 0 0
sp_get_clo_for_invoice 4807 4807 0 0.00 0 20298 366 0 0
sp_kill_cost_storno 15347 15347 0 0.00 0 8966 74 0 0
sp_kill_qstorno_ret_qs2qd 1132072 1132072 0 0.00 0 38505 42 0 0
sp_kill_qty_storno 595537 595537 0 0.00 0 38519 90 0 0
sp_lock_selected_doc 20073 20073 0 0.00 0 10213 6 0 0
sp_make_cost_storno 27257 27257 0 0.00 0 14522 62 0 0
sp_multiply_rows_for_pdistr 27257 27257 0 0.00 0 9579 33 0 0
sp_multiply_rows_for_qdistr 19866 19866 0 0.00 0 20898 195 0 0
sp_pay_from_customer 7031 7031 0 0.00 4 18770 118 0 0
sp_pay_to_supplier 5728 5728 0 0.00 5 16804 133 0 0
sp_payment_common 12758 12758 0 0.00 1 8305 22 0 0
sp_qd_handle_on_cancel_clo 1705 1705 0 0.00 0 9599 351 0 0
sp_qd_handle_on_invoice_upd_sts 10459 10459 0 0.00 0 14935 399 0 0
sp_qd_handle_on_reserve_upd_sts 9694 9694 0 0.00 0 18794 60 0 0
sp_reserve_write_off 9694 9694 0 0.00 7 18872 199 0 0
sp_supplier_invoice 5311 5311 0 0.00 6 98950 3636 0 0
sp_supplier_order 5238 5238 0 0.00 6 41248 1509 0 0
srv_aggregate_perf_data 97 96 1 1.03 2380 181179 17212 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1863 162899 47883 0 0
srv_find_qd_qs_mism 1048554 1048554 0 0.00 0 33544 6 0 0
srv_make_invnt_saldo 422 422 0 0.00 30 166807 4414 0 0
srv_make_money_saldo 702 702 0 0.00 1 17462 746 0 0
srv_recalc_idx_stat 4 4 0 0.00 184000 506363 322446 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4819 4819 0 0.00 0 34953 176 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 4507 115 0 0
t$perf-norm:sp_cancel_client_order 1705 1705 0 0.00 0 6517 17 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 2726 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 3337 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 6072 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 2013 16 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 20695 1701 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 2172 9 0 0
t$perf-norm:sp_client_order 19866 19866 0 0.00 0 17868 20 0 0
t$perf-norm:sp_customer_reserve 955 955 0 0.00 0 8939 20 0 0
t$perf-norm:sp_pay_from_customer 7031 7031 0 0.00 0 25612 14 0 0
t$perf-norm:sp_pay_to_supplier 5728 5728 0 0.00 0 8539 13 0 0
t$perf-norm:sp_reserve_write_off 9694 9694 0 0.00 0 15575 11 0 0
t$perf-norm:sp_supplier_invoice 5311 5311 0 0.00 0 4270 18 0 0
t$perf-norm:sp_supplier_order 5238 5238 0 0.00 0 4001 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 435 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 14 1 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 422 5 0 0
t$perf-norm:srv_make_money_saldo 702 702 0 0.00 0 756 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9818 9602 216 2.20 0 7408 19 0 216
v_all_customers 36430 36430 0 0.00 0 62 1 0 0
v_all_suppliers 29848 29848 0 0.00 0 19 1 0 0
v_all_wares 39730 39730 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 3888 15 0 0
v_cancel_client_order 3410 3410 0 0.00 0 5705 7 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 462 4 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 479 3 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 4960 10 0 0
v_cancel_supplier_invoice 732 720 12 1.63 0 5144 23 0 12
v_cancel_supplier_order 812 812 0 0.00 0 7824 13 0 0
v_cancel_write_off 1954 1954 0 0.00 0 1199 12 0 0
v_max_id_clo_ord 5237 5237 0 0.00 0 1063 1 0 0
v_max_id_clo_res 955 955 0 0.00 0 1229 8 0 0
v_max_id_ord_sup 5298 5298 0 0.00 0 5201 7 0 0
v_max_non_paid_invoice 1338 1338 0 0.00 0 207 2 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 784 2 0 0
v_min_id_clo_ord 5237 5237 0 0.00 0 4931 7 0 0
v_min_id_clo_res 955 955 0 0.00 0 5116 19 0 0
v_min_id_ord_sup 5298 5298 0 0.00 0 5724 7 0 0
v_min_non_paid_invoice 5727 5727 0 0.00 0 8235 14 0 0
v_min_non_paid_realizn 7031 7031 0 0.00 0 8303 17 0 0
v_our_firm 10068 10068 0 0.00 0 11 1 0 0
v_reserve_write_off 19388 19388 0 0.00 0 7837 7 0 0
x_lock_depdocs_on_canc_invoice 11533 11533 0 0.00 0 22707 137 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 4892 351 0 0
x_make_qstorno_1000_1200 5238 5238 0 0.00 2 41241 1425 0 0
x_make_qstorno_1200_2000 5311 5311 0 0.00 3 95545 3461 0 0
x_make_qstorno_2100_3300 454671 454671 0 0.00 0 119293 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2021 13:38:04 to 19-08-2021 13:38:08.

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 8743 8192000000 9721762384 10015690752 802713600 8.924 100 93 186 0 833006976 7746768 22546608
11:09:29 34421 8192000000 9759946480 10069913600 833027824 9.230 100 88 176 1 865121920 11405472 20688624
11:11:11 15905 8192000000 9795837440 10119389184 875944736 9.659 100 81 162 0 911113984 16818736 18350512
11:12:32 13415 8192000000 9860324256 10194284544 919710368 10.093 100 85 170 0 976696624 38395152 18591104
11:13:51 9857 8192000000 9924045776 10264715264 953004864 10.421 100 82 163 1 1040935792 71033136 16897792
11:15:16 5080 8192000000 9942955456 10301599744 994481632 10.825 100 72 144 1 1055619504 46395632 14742240
11:16:24 3621 8192000000 10006628816 10367873024 1023478480 11.106 100 89 178 1 1115740992 74274944 17987568
11:17:32 2306 8192000000 10027966320 10396876800 1049318512 11.354 100 92 183 1 1137203216 69870992 18013712
11:18:37 2305 8192000000 10031055104 10400460800 1064922512 11.504 100 88 174 0 1135373504 53081984 17369008
11:19:41 2057 8192000000 10045976752 10417639424 1077264752 11.621 100 96 191 2 1157798976 59944112 20590112
11:20:44 2806 8192000000 10000950304 10394771456 1078477040 11.633 100 51 99 2 1132006624 43164384 10365200
11:21:51 31259 8192000000 10006025296 10417086464 1079817440 11.646 100 26 53 2 1132177152 47447856 4911856
11:23:22 4802 8192000000 10008296704 10416562176 1089546240 11.738 100 31 62 0 1139086800 43031824 6508736
11:24:31 6956 8192000000 10001429168 10402603008 1094137072 11.782 100 28 55 0 1127447328 27164688 6145568
11:25:39 9240 8192000000 10008957488 10413113344 1100536432 11.843 100 45 91 0 1123022080 11588416 10897232
11:26:51 5555 8192000000 10026063536 10417463296 1104229312 11.878 100 70 139 0 1134381536 15666208 14486016
11:27:57 7444 8192000000 10023577376 10424426496 1103052032 11.867 100 33 65 3 1131809696 22068624 6689040
11:29:06 21373 8192000000 9955908080 10356076544 965834592 10.546 89 18 34 0 995853376 26491856 3526928
11:30:28 4428 8192000000 9959645056 10331918336 1025955872 11.129 98 52 103 3 1057448784 20014896 11478016
11:31:34 6729 8192000000 9942656960 10314260480 995924192 10.839 97 37 72 1 1016017920 12013264 8080464
11:32:41 32782 8192000000 9935396096 10331521024 1010054656 10.976 97 32 62 2 1037277632 21073408 6149568
11:34:15 6295 8192000000 9946743584 10345050112 1035790320 11.224 99 33 62 1 1054016256 11111952 7113984
11:35:24 5081 8192000000 9973307904 10370809856 1056194400 11.420 100 45 90 2 1090175872 23948176 10033296
11:36:29 57956 8192000000 10003791600 10397310976 1067937376 11.532 100 37 72 1 1096930880 21421328 7572176
11:38:35 47150 8192000000 10071858896 10475634688 1093717200 11.778 100 55 108 1 1158249024 53216768 11315056
11:40:30 22202 8192000000 10074492944 10483253248 1117888672 12.007 100 46 92 1 1188221280 60423344 9909264
11:41:59 28496 8192000000 10146779520 10559537152 1138213664 12.199 100 56 112 0 1245187392 94961824 12011904
11:43:51 4286 8192000000 10157428896 10567348224 1149236816 12.302 100 54 108 0 1263172496 101685296 12250384
11:44:58 8484 8192000000 10225896288 10639147008 1149985600 12.309 100 55 110 0 1326481024 164860496 11634928
11:46:14 4746 8192000000 10213899920 10637287424 1163795664 12.439 100 69 137 1 1322702176 143390432 15516080
11:47:29 5741 8192000000 10245268288 10660184064 1168067840 12.479 100 53 106 0 1347499920 167753168 11678912
11:48:45 2056 8192000000 10271427072 10680049664 1180502704 12.595 100 71 138 1 1372252960 176516288 15233968
11:49:53 9187 8192000000 10296750688 10716491776 1179271568 12.583 100 65 128 0 1407841072 213761408 14808096
11:51:08 5437 8192000000 10312663264 10734104576 1170048048 12.497 99 56 111 0 1404855744 221683424 13124272
11:52:18 2539 8192000000 10334564352 10755776512 1174839184 12.542 100 82 163 2 1430161376 235872208 19449984
11:53:27 1860 8192000000 10395544640 10817560576 1190384816 12.687 100 94 187 0 1488637168 276979152 21273200
11:54:32 4021 8192000000 10389614560 10800345088 1182814192 12.616 100 78 156 1 1488567408 287208608 18544608
11:55:43 2830 8192000000 10377450800 10787057664 1173590032 12.530 100 79 156 0 1476929936 284964560 18375344
11:56:55 3172 8192000000 10422806864 10840584192 1173935472 12.534 100 78 155 0 1528022304 336249024 17837808
11:58:02 12812 8192000000 10414159056 10843746304 1179885184 12.589 100 44 84 1 1514709680 323523056 11301440
11:59:20 10056 8192000000 10420306896 10842570752 1171768256 12.513 99 36 73 0 1517550208 336349280 9432672
12:00:33 2466 8192000000 10482532240 10891661312 1162199168 12.424 99 72 143 0 1571223232 391191104 17832960
12:01:42 11407 8192000000 10539494816 10960629760 1161272320 12.415 100 48 94 0 1622085152 448697568 12115264
12:03:00 7886 8192000000 10560909744 10999152640 1153522688 12.343 100 45 89 0 1657675536 491811712 12341136
12:04:19 12141 8192000000 10504474256 10994855936 1140286176 12.218 100 34 68 3 1640216240 489822096 10107968
12:06:25 24029 8192000000 10428414480 10975678464 1134427232 12.163 100 35 70 0 1619932848 475587968 9917648
12:08:20 59688 8192000000 10080508576 10507288576 1094173104 11.782 96 38 72 1 1155420400 54798960 6448336
12:10:22 38577 8192000000 10054599792 10520027136 1098754032 11.826 99 18 34 1 1162044944 59590288 3700624
12:12:17 42374 8192000000 10062650048 10493067264 1114493184 11.975 99 40 80 0 1158016448 34786304 8736960
12:15:32 54451 8192000000 10151483856 10597511168 1135748928 12.176 100 24 48 1 1215319664 74266944 5303792
12:18:23 70780 8192000000 10134531168 10582429696 1147947168 12.290 100 27 54 0 1226771552 72831568 5992816
12:20:36 103523 8192000000 10093545728 10599518208 1137536896 12.192 100 13 26 0 1198176848 57904352 2735600
12:23:22 30261 8192000000 10078530464 10516447232 1129802336 12.119 99 55 110 2 1153062080 11498016 11761728
12:24:53 81190 8192000000 10100969872 10551738368 1111210112 11.944 98 8 16 0 1136015520 23293456 1511952
12:27:15 162899 8192000000 10077981584 10547126272 1108150384 11.915 97 16 32 1 1169543568 58151088 3242096
12:31:05 87458 8192000000 10118435952 10567872512 1135462016 12.173 99 43 86 2 1208217904 64057856 8698032
12:33:37 69797 8192000000 10114625328 10539106304 1101132944 11.848 96 53 104 2 1143304864 31109632 11062288
12:35:50 33092 8192000000 10118873904 10570616832 1150503136 12.314 100 38 76 2 1221609504 62613232 8493136
12:37:24 47751 8192000000 10125812080 10624847872 1153936272 12.346 100 9 18 0 1230178496 74365072 1877152
12:39:15 74162 8192000000 10184818704 10640216064 1149311392 12.303 99 21 42 1 1257438752 103519424 4607936
12:41:46 50862 8192000000 10073340608 10564308992 1124027744 12.065 98 11 22 0 1189709840 63526576 2155520
12:43:41 113765 8192000000 10076492368 10525757440 1117717312 12.005 97 8 16 0 1153872224 34725008 1429904
12:46:36 39056 8192000000 10113175424 10559688704 1138851296 12.205 99 22 44 0 1210605504 66765600 4988608
12:48:17 34408 8192000000 10103711392 10563125248 1107073040 11.905 97 29 58 1 1209325696 96183120 6069536
12:50:04 61337 8192000000 10058888976 10505043968 1074975344 11.600 95 14 28 0 1101873264 23830368 3067552
12:52:08 93272 8192000000 10059393296 10511093760 1054105024 11.400 93 21 43 0 1106828496 48630880 4092592
12:54:48 92343 8192000000 10063440416 10523279360 1085500016 11.700 96 35 70 2 1150840032 58210112 7129904
12:57:26 59075 8192000000 10049267184 10488053760 1114933664 11.979 99 17 34 0 1153238864 34645120 3660080
12:59:57 49863 8192000000 10068057232 10542891008 1089593632 11.739 97 13 26 1 1152702032 60245488 2862912
13:01:50 74134 8192000000 10074931104 10533490688 1076706096 11.616 97 17 35 0 1154275536 73556144 4013296
13:04:10 51072 8192000000 10051032112 10490122240 1099965040 11.837 99 17 34 0 1155859152 52020352 3873760
13:06:09 65237 8192000000 10087952208 10534387712 1098633568 11.825 99 31 62 0 1170422512 64222320 7566624
13:08:18 73054 8192000000 10078292384 10556596224 1129547248 12.117 100 26 53 1 1197993568 63468912 4977408
13:10:33 90678 8192000000 10076903984 10541342720 1118453488 12.012 99 30 60 0 1183200928 58753296 5994144
13:13:07 64336 8192000000 10078984528 10539089920 1119326848 12.021 99 25 50 0 1152690384 28127280 5236256
13:15:13 18995 8192000000 10119223152 10554347520 1127015552 12.093 99 30 60 0 1187567984 54696240 5856192
13:16:33 64639 8192000000 10088447440 10589528064 1129079776 12.113 99 12 24 3 1219441472 87758720 2602976
13:18:46 115015 8192000000 10063795680 10510184448 1125497808 12.079 99 21 41 2 1151360176 21384096 4478272
13:21:53 25988 8192000000 10075521648 10504282112 1127585056 12.099 99 63 121 2 1160076480 19721696 12769728
13:23:40 110236 8192000000 10093835632 10558283776 1122461792 12.050 99 11 22 0 1178141328 53147200 2532336
13:26:34 79406 8192000000 10099229648 10620342272 1134362400 12.162 99 13 27 0 1210681600 73253376 3065824
13:28:59 35890 8192000000 10129912176 10603401216 1141804992 12.233 100 23 47 1 1249801760 102369296 5627472
13:30:36 70904 8192000000 10129092192 10593964032 1104457296 11.880 96 19 38 1 1189186176 80649280 4079600
13:32:48 100877 8192000000 10074420704 10522656768 1128017664 12.103 99 11 22 0 1170100832 39943456 2139712
13:35:34 87198 8192000000 10075119520 10575798272 1103559232 11.871 97 19 38 0 1155034512 47821664 3653616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 108 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-08-2021 13:38:26 to 19-08-2021 13:38:28.

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          227383
MON$OLDEST_ACTIVE               227384
MON$OLDEST_SNAPSHOT             227384
MON$NEXT_TRANSACTION            227384
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                       2067680
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:276: 
:277: mscs-test-161.budzdorov.ru    Thu Aug 19 13:37:21 2021
:278:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:279: 
--- end of diff output ---

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

19.08.21 13:38:29. 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-19 13:38:31 No severe PSQL-related problems occured
19.08.21 13:38:32. End of report.