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

Server and database settinfs

SERVER_MODE                     SuperServer 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 2020-12-09 13:26:54
DTS_END                         2020-12-09 13:26:54.5210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56132
TRN_ID                          238019
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: 9223.89 18730 1106867
customer order: creation 176.69 365 21203
customer order: refuse 15.50 335 1860
order to supplier: creation 45.89 1299 5507
order to supplier: removal 3.51 100325 421
invoice (draft): creation 46.65 3377 5598
invoice (draft): removal 51.50 782 6180
invoice accept: apply 42.21 15911 5065
invoice accept: cancel 46.10 815 5532
customer reserve: creation 3996.34 152 479561
customer reserve: removal 4503.39 62 540407
realization accept: apply 84.82 164 10178
realization accept: cancel 81.06 126 9727
payment from customer: creation 60.48 103 7257
payment from customer: removal 6.09 66 731
payment to supplier: creation 49.50 124 5940
payment to supplier: removal 3.33 69 399
service: total inventory turnovers 3.88 3089 466
service: total monetary turnovers 5.95 483 714
service: aggregate perf. data 0.98 12386 117
service: refresh index statistics 0.03 234565 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2020 13:27:41 to 09-12-2020 13:27:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2035
TEST_TIME 2 3777
TEST_TIME 3 5469
TEST_TIME 4 5090
TEST_TIME 5 5030
TEST_TIME 6 4816
TEST_TIME 7 4930
TEST_TIME 8 4901
TEST_TIME 9 5114
TEST_TIME 10 5694
TEST_TIME 11 5627
TEST_TIME 12 6154
TEST_TIME 13 6418
TEST_TIME 14 6764
TEST_TIME 15 7193
TEST_TIME 16 7580
TEST_TIME 17 7888
TEST_TIME 18 8947
TEST_TIME 19 9040
TEST_TIME 20 9125
TEST_TIME 21 9286
TEST_TIME 22 9431
TEST_TIME 23 9487
TEST_TIME 24 9784
TEST_TIME 25 9803
TEST_TIME 26 11222
TEST_TIME 27 11149
TEST_TIME 28 11004
TEST_TIME 29 10861
TEST_TIME 30 10851
TEST_TIME 31 10688
TEST_TIME 32 10784
TEST_TIME 33 10697
TEST_TIME 34 10735
TEST_TIME 35 10587
TEST_TIME 36 10523
TEST_TIME 37 10426
TEST_TIME 38 10356
TEST_TIME 39 10314
TEST_TIME 40 10398
TEST_TIME 41 10459
TEST_TIME 42 10312
TEST_TIME 43 10083
TEST_TIME 44 9989
TEST_TIME 45 9896
TEST_TIME 46 9856
TEST_TIME 47 9886
TEST_TIME 48 9883
TEST_TIME 49 9836
TEST_TIME 50 9751
TEST_TIME 51 9724
TEST_TIME 52 9693
TEST_TIME 53 9591
TEST_TIME 54 9476
TEST_TIME 55 9704
TEST_TIME 56 9681
TEST_TIME 57 9672
TEST_TIME 58 9640
TEST_TIME 59 9706
TEST_TIME 60 9768
TEST_TIME 61 9811
TEST_TIME 62 9807
TEST_TIME 63 9795
TEST_TIME 64 9806
TEST_TIME 65 9825
TEST_TIME 66 9837
TEST_TIME 67 9799
TEST_TIME 68 9825
TEST_TIME 69 9719
TEST_TIME 70 9628
TEST_TIME 71 9594
TEST_TIME 72 9523
TEST_TIME 73 9484
TEST_TIME 74 9443
TEST_TIME 75 9364
TEST_TIME 76 9396
TEST_TIME 77 9388
TEST_TIME 78 9416
TEST_TIME 79 9420
TEST_TIME 80 9381
TEST_TIME 81 9429
TEST_TIME 82 9475
TEST_TIME 83 9420
TEST_TIME 84 9459
TEST_TIME 85 9446
TEST_TIME 86 9520
TEST_TIME 87 9487
TEST_TIME 88 9413
TEST_TIME 89 9371
TEST_TIME 90 9294
TEST_TIME 91 9266
TEST_TIME 92 9311
TEST_TIME 93 9265
TEST_TIME 94 9247
TEST_TIME 95 9239
TEST_TIME 96 9236
TEST_TIME 97 9216
TEST_TIME 98 9195
TEST_TIME 99 9205
TEST_TIME 100 9252
TEST_TIME 101 9262
TEST_TIME 102 9256
TEST_TIME 103 9254
TEST_TIME 104 9244
TEST_TIME 105 9266
TEST_TIME 106 9301
TEST_TIME 107 9315
TEST_TIME 108 9231
TEST_TIME 109 9192
TEST_TIME 110 9192
TEST_TIME 111 9158
TEST_TIME 112 9142
TEST_TIME 113 9119
TEST_TIME 114 9093
TEST_TIME 115 9066
TEST_TIME 116 9076
TEST_TIME 117 9113
TEST_TIME 118 9123
TEST_TIME 119 9166
TEST_TIME 120 9227

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2020 13:27:45 to 09-12-2020 13:27:48.

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 1548423 1548423 0 0.00 0 16978 6 0 0
doc_list_biud 1548423 1548423 0 0.00 0 46389 27 0 0
sp_add_invoice_to_stock 5170 5065 105 2.03 38 198068 15911 0 105
sp_cancel_adding_invoice 5532 5532 0 0.00 6 79867 816 0 0
sp_cancel_client_order 1860 1860 0 0.00 5 38973 335 0 0
sp_cancel_customer_reserve 540407 540407 0 0.00 1 28939 63 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 2157 66 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 4701 69 0 0
sp_cancel_supplier_invoice 6190 6180 10 0.16 2 21496 783 0 10
sp_cancel_supplier_order 421 421 0 0.00 10 1154241 100325 0 0
sp_cancel_write_off 9727 9727 0 0.00 1 28483 126 0 0
sp_client_order 21203 21203 0 0.00 4 31129 365 0 0
sp_customer_reserve 479561 479561 0 0.00 0 57009 152 0 0
sp_fill_shopping_cart 32282 32282 0 0.00 1 15083 41 0 0
sp_fill_shopping_cart_clo_res 479553 479553 0 0.00 0 20379 15 0 0
sp_get_clo_for_invoice 5055 5055 0 0.00 0 38053 365 0 0
sp_kill_cost_storno 16389 16389 0 0.00 0 8298 58 0 0
sp_kill_qstorno_ret_qs2qd 1097142 1097142 0 0.00 0 28310 34 0 0
sp_kill_qty_storno 579360 579360 0 0.00 0 46389 73 0 0
sp_lock_selected_doc 21234 21234 0 0.00 0 9507 4 0 0
sp_make_cost_storno 28430 28430 0 0.00 0 14743 48 0 0
sp_multiply_rows_for_pdistr 28430 28430 0 0.00 0 14930 32 0 0
sp_multiply_rows_for_qdistr 21203 21203 0 0.00 0 22858 195 0 0
sp_pay_from_customer 7257 7257 0 0.00 4 14932 104 0 0
sp_pay_to_supplier 5940 5940 0 0.00 5 14981 124 0 0
sp_payment_common 13197 13197 0 0.00 1 14912 27 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 38966 309 0 0
sp_qd_handle_on_invoice_upd_sts 10587 10587 0 0.00 0 46387 347 0 0
sp_qd_handle_on_reserve_upd_sts 10178 10178 0 0.00 0 20133 47 0 0
sp_reserve_write_off 10178 10178 0 0.00 7 20144 164 0 0
sp_supplier_invoice 5598 5598 0 0.00 7 112155 3378 0 0
sp_supplier_order 5507 5507 0 0.00 5 18675 1300 0 0
srv_aggregate_perf_data 118 117 1 0.84 2497 171313 12386 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1363 192881 49801 0 0
srv_find_qd_qs_mism 1056742 1056742 0 0.00 0 9486 5 0 0
srv_make_invnt_saldo 466 466 0 0.00 61 109488 3089 0 0
srv_make_money_saldo 714 714 0 0.00 1 14941 483 0 0
srv_recalc_idx_stat 4 4 0 0.00 172649 311398 234566 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 5065 5065 0 0.00 0 13836 155 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 2337 72 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 2694 12 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2546 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 1451 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 639 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2351 17 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 29977 1571 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 2851 11 0 0
t$perf-norm:sp_client_order 21203 21203 0 0.00 0 20730 15 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 3045 6 0 0
t$perf-norm:sp_pay_from_customer 7257 7257 0 0.00 0 8143 6 0 0
t$perf-norm:sp_pay_to_supplier 5940 5940 0 0.00 0 8712 12 0 0
t$perf-norm:sp_reserve_write_off 10178 10178 0 0.00 0 4091 6 0 0
t$perf-norm:sp_supplier_invoice 5598 5598 0 0.00 0 6395 14 0 0
t$perf-norm:sp_supplier_order 5507 5507 0 0.00 0 4872 13 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 481 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 500 4 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 280 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10320 10110 210 2.03 0 7555 13 0 210
v_all_customers 38708 38708 0 0.00 0 32 1 0 0
v_all_suppliers 30822 30822 0 0.00 0 48 1 0 0
v_all_wares 42406 42406 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 475 6 0 0
v_cancel_client_order 3720 3720 0 0.00 0 5339 6 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 2009 6 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 3913 6 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 459 3 0 0
v_cancel_supplier_invoice 862 842 20 2.32 0 189 10 0 20
v_cancel_supplier_order 842 842 0 0.00 0 3121 14 0 0
v_cancel_write_off 2122 2122 0 0.00 0 5754 16 0 0
v_max_id_clo_ord 5507 5507 0 0.00 0 1070 1 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 1586 11 0 0
v_max_id_ord_sup 5571 5571 0 0.00 0 6344 10 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 201 2 0 0
v_max_non_paid_realizn 3818 3818 0 0.00 0 11799 6 0 0
v_min_id_clo_ord 5507 5507 0 0.00 0 2996 5 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 2526 10 0 0
v_min_id_ord_sup 5571 5571 0 0.00 0 4031 8 0 0
v_min_non_paid_invoice 5940 5940 0 0.00 0 14894 22 0 0
v_min_non_paid_realizn 7257 7257 0 0.00 0 14705 18 0 0
v_our_firm 10576 10576 0 0.00 0 16 1 0 0
v_reserve_write_off 20356 20356 0 0.00 0 9958 7 0 0
x_lock_depdocs_on_canc_invoice 11291 11291 0 0.00 0 16311 111 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 22851 337 0 0
x_make_qstorno_1000_1200 5507 5507 0 0.00 2 18667 1234 0 0
x_make_qstorno_1200_2000 5598 5598 0 0.00 2 100269 3215 0 0
x_make_qstorno_2100_3300 479561 479561 0 0.00 0 57006 135 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2020 13:27:49 to 09-12-2020 13:27:53.

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
10:56:58 6 8192000000 8796641776 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:59 31061 8192000000 9723207056 10017189888 799892064 8.895 100 93 186 0 830404992 7806256 22706672
10:59:34 11910 8192000000 9752304016 10054934528 831134416 9.211 100 84 168 0 862702416 11452256 20115744
11:00:54 16006 8192000000 9789662912 10104840192 858637200 9.487 100 84 168 0 894960448 17019616 19303632
11:02:15 10892 8192000000 9851064160 10181054464 909369312 9.991 100 78 158 0 961213264 34825216 17018736
11:03:30 8008 8192000000 9909790592 10250399744 956579920 10.456 100 82 163 2 1018524336 44660416 17284000
11:04:40 4556 8192000000 9957609152 10311282688 992306016 10.804 100 82 163 2 1080759312 71977536 16475760
11:05:47 2361 8192000000 10003884752 10364268544 1021355936 11.085 100 84 167 1 1114415888 75879472 17180480
11:06:52 2709 8192000000 10039121504 10405421056 1045927344 11.322 100 89 173 1 1145743104 82933600 16882160
11:07:57 2268 8192000000 10030470384 10402439168 1054951520 11.408 100 84 168 2 1138769056 67166592 16650944
11:09:02 1287 8192000000 10043159376 10418966528 1062431136 11.480 100 84 168 2 1159186928 79708752 17047040
11:10:07 5662 8192000000 10010235504 10400374784 1067340000 11.527 100 62 121 1 1130208064 50152800 12715264
11:11:15 58005 8192000000 10002020672 10394206208 1069476368 11.547 100 39 77 1 1091540816 13786896 8277552
11:13:13 5558 8192000000 10037608528 10431397888 1076448448 11.614 100 40 80 1 1131272816 46567296 8257072
11:14:20 7882 8192000000 10020896896 10420604928 1085242768 11.697 100 37 74 2 1122780480 29698032 7839680
11:15:35 40517 8192000000 10008526736 10423721984 1068358096 11.536 98 17 34 1 1095265216 22745872 4161248
11:17:39 4549 8192000000 9992690368 10392727552 1069656144 11.549 99 41 82 0 1102486288 24230624 8599520
11:18:44 7575 8192000000 9990476192 10385424384 1043301872 11.296 98 49 100 2 1082620544 28003264 11315408
11:19:52 49357 8192000000 9926252768 10309132288 988336992 10.765 95 30 60 1 1018167152 23633840 6196320
11:21:48 10650 8192000000 9930689184 10312974336 988969536 10.771 97 36 72 4 1018950752 21929952 8051264
11:22:59 11021 8192000000 9955749808 10340958208 1037431424 11.240 100 37 74 2 1069669344 23675392 8562528
11:24:12 8115 8192000000 9982043952 10372431872 1054938224 11.408 100 36 71 3 1085910048 23579040 7392784
11:25:20 18966 8192000000 9993068560 10377588736 1063038256 11.486 100 70 144 2 1095444976 16349312 16057408
11:26:43 10755 8192000000 10007255744 10401710080 1085861360 11.703 100 45 88 1 1120803408 24795568 10146480
11:28:00 27498 8192000000 10060573280 10458861568 1110524528 11.937 100 54 108 2 1158087296 36475664 11087104
11:29:37 3110 8192000000 10101555840 10501140480 1130699216 12.128 100 68 134 1 1206426288 61866992 13860080
11:30:45 43314 8192000000 10150576608 10565492736 1148393264 12.294 100 48 97 0 1266302000 107734048 10174688
11:32:33 10362 8192000000 10182913920 10593382400 1151076416 12.320 100 39 78 2 1281417328 121723536 8617376
11:33:47 8916 8192000000 10213914928 10631909376 1163679200 12.438 100 54 108 3 1327072416 150989936 12403280
11:35:02 11201 8192000000 10251511200 10663448576 1164841488 12.449 100 54 106 0 1348743312 171692224 12209600
11:36:21 10321 8192000000 10269789440 10686181376 1179223040 12.583 100 58 113 0 1362279712 170411360 12645312
11:37:35 5216 8192000000 10319158672 10738806784 1178617728 12.577 100 69 137 0 1427509696 233033680 15858288
11:38:47 4720 8192000000 10312210304 10727862272 1185912608 12.645 100 73 144 0 1414060736 210683584 17464544
11:39:56 1361 8192000000 10363513952 10773434368 1183256816 12.621 100 94 186 4 1461868096 256724944 21886336
11:41:02 5428 8192000000 10380275824 10799030272 1186210944 12.648 100 86 171 0 1486502912 280678176 19613792
11:42:16 2717 8192000000 10370300736 10789208064 1182970704 12.618 100 90 179 0 1469829744 265959072 20899968
11:43:24 1599 8192000000 10366809872 10795892736 1174239152 12.536 99 85 170 1 1460646576 265931168 20476256
11:44:29 8140 8192000000 10287117440 10723917824 1165401808 12.454 100 47 93 2 1400163760 223202480 11559472
11:45:41 18858 8192000000 10290415856 10738163712 1145904592 12.271 98 29 58 0 1365513456 212166128 7442736
11:47:04 13419 8192000000 10292626832 10754908160 1160455456 12.408 100 29 57 5 1405052736 237519536 7077744
11:48:18 16877 8192000000 10288847840 10733707264 1110029216 11.933 97 30 60 3 1379901600 262492272 7380112
11:49:39 21189 8192000000 10292190976 10741747712 1114936848 11.979 98 22 44 5 1399147376 278085264 6125264
11:51:21 38567 8192000000 10282082080 10778492928 1076128080 11.611 97 15 30 1 1389211504 308851280 4232144
11:53:02 48920 8192000000 10053522272 10508070912 1089889792 11.742 99 14 28 2 1158515248 65697376 2928080
11:54:52 67761 8192000000 10070510160 10542252032 1102593376 11.862 99 7 14 0 1152656352 48800368 1262608
11:57:09 136990 8192000000 10063448992 10497044480 1042768944 11.291 92 22 45 2 1089281808 42117408 4395456
12:00:29 192881 8192000000 10041238192 10473267200 1089298560 11.736 98 11 22 0 1119713648 28101392 2313696
12:04:48 67022 8192000000 10103084560 10560532480 1102630608 11.863 98 14 26 1 1157124064 51370336 3123120
12:07:00 109318 8192000000 10061649824 10542661632 1091213632 11.754 97 4 8 0 1111253664 19426992 613040
12:10:41 58854 8192000000 10078200800 10501820416 1120375216 12.031 99 42 84 1 1164793888 35155360 9263312
12:12:41 33859 8192000000 10106532304 10561011712 1133439552 12.154 100 38 76 0 1168635344 27091120 8104672
12:14:26 31605 8192000000 10067442400 10526347264 1129269680 12.114 99 20 40 0 1166783152 33267408 4246064
12:15:57 33870 8192000000 10096967312 10542411776 1135617728 12.174 99 20 40 3 1178134048 37993696 4522624
12:17:34 40810 8192000000 10111193200 10594242560 1134760432 12.166 99 14 28 1 1227469632 89294384 3414816
12:19:15 68781 8192000000 10145318048 10603728896 1150806592 12.317 100 16 31 2 1243279440 88654528 3818320
12:21:35 95262 8192000000 10100213488 10587680768 1139391712 12.210 100 6 12 1 1238403856 97507632 1504512
12:24:10 33926 8192000000 10081495136 10523451392 1133192112 12.151 99 25 49 1 1179370160 40627088 5550960
12:25:48 73310 8192000000 10103936864 10592509952 1130363856 12.125 98 21 40 0 1211688592 76536608 4788128
12:28:05 64302 8192000000 10058754320 10502410240 1067414208 11.527 93 17 34 0 1108537568 37357008 3766352
12:30:17 30698 8192000000 10053589248 10484842496 1106111392 11.896 99 39 77 2 1142027856 27807376 8109088
12:31:52 82151 8192000000 10063985200 10516197376 1019635568 11.068 90 18 36 1 1075045408 52010800 3399040
12:34:18 87597 8192000000 10076894656 10532581376 1072506080 11.576 96 21 42 4 1108450896 31942672 4002144
12:36:54 70184 8192000000 10052015328 10522365952 1052873168 11.388 95 16 32 1 1097110768 40573120 3664480
12:39:16 70109 8192000000 10058660720 10548633600 1065487584 11.509 96 11 22 0 1125497520 56994304 3015632
12:41:26 57328 8192000000 10103213248 10563280896 1114909264 11.979 100 10 20 1 1186411280 69313520 2188496
12:43:37 41962 8192000000 10065214592 10509328384 1086197264 11.706 98 23 46 0 1149244544 57978960 5068320
12:45:21 36602 8192000000 10054768432 10488274944 1090689360 11.749 98 23 46 1 1124393888 28343952 5360576
12:47:01 70431 8192000000 10087499824 10560544768 1106255168 11.897 99 24 48 1 1201459216 90377600 4826448
12:49:13 15025 8192000000 10048580672 10470866944 1100190304 11.839 99 51 103 0 1136406816 25403344 10813168
12:50:32 47105 8192000000 10084380448 10526003200 1094869328 11.789 98 25 50 1 1165545984 65184576 5492080
12:52:25 41078 8192000000 10052757024 10524758016 1078573600 11.634 97 37 73 5 1147005392 60880272 7551520
12:54:10 106536 8192000000 10042460608 10501234688 1095714832 11.797 99 20 39 2 1148453344 48449952 4288560
12:56:57 38340 8192000000 10045976384 10505158656 1108118320 11.915 99 9 18 0 1150587216 40972720 1496176
12:58:37 53711 8192000000 10042823424 10487418880 1123648352 12.061 100 10 20 0 1174740064 49055632 2036080
13:00:31 48179 8192000000 10077542528 10516992000 1126166592 12.085 99 20 38 1 1152937728 22607680 4163456
13:02:20 58863 8192000000 10081721488 10536579072 1145784848 12.270 100 21 42 0 1203173344 52929408 4459088
13:04:19 41552 8192000000 10108536416 10550669312 1145234304 12.265 100 37 74 0 1210767568 58167184 7366080
13:06:07 98591 8192000000 10077267120 10534010880 1128177664 12.104 98 23 46 0 1185964336 52995280 4791392
13:09:08 45001 8192000000 10089580640 10542706688 1132270816 12.143 98 23 46 2 1172588832 35542176 4775840
13:10:55 77444 8192000000 10094909616 10570301440 1129993088 12.121 97 16 32 2 1179371232 46029872 3348272
13:13:13 107855 8192000000 10097555952 10580467712 1120518944 12.032 98 21 42 0 1160851328 35935360 4397024
13:16:04 86481 8192000000 10123057216 10627932160 1149905824 12.309 100 9 18 0 1228950544 77103888 1940832
13:18:49 78479 8192000000 10095735808 10542379008 1094676800 11.787 95 22 44 0 1141434880 42386064 4372016
13:21:10 46367 8192000000 10180938960 10615455744 1145706784 12.269 99 29 58 2 1244941200 92444368 6790048
13:23:20 87468 8192000000 10175552112 10704371712 1126121616 12.085 98 15 30 0 1247361712 117785104 3454992
13:26:09 52184 8192000000 9052683360 9300787200 198105248 2.361 18 2 4 0 230191168 31713456 372464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2020 13:27:55 to 09-12-2020 13:28:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 105 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2020 13:28:11 to 09-12-2020 13:28:13.

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          238327
MON$OLDEST_ACTIVE               238328
MON$OLDEST_SNAPSHOT             238328
MON$NEXT_TRANSACTION            238328
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                       2123776
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             802
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2293 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2293 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2293 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.2293 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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Wed Dec  9 13:27:06 2020
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Wed Dec  9 13:27:06 2020
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Wed Dec  9 13:27:06 2020
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Wed Dec  9 13:27:06 2020
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
--- end of diff output ---

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

09.12.20 13:28:14. 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
2020-12-09 13:28:16 No severe PSQL-related problems occured
09.12.20 13:28:18. End of report.