Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.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 16539620 kB
MemAvailable 31321952 kB
Buffers 0 kB
Cached 15012920 kB
SwapCached 4820 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-29 13:26:51
DTS_END                         2020-12-29 13:26:51.3230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33230
TRN_ID                          226492
ATT_ID                          632
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: 9036.79 19713 1084415
customer order: creation 163.30 455 19596
customer order: refuse 14.30 390 1716
order to supplier: creation 42.88 1444 5145
order to supplier: removal 3.38 122466 405
invoice (draft): creation 42.76 3952 5131
invoice (draft): removal 52.73 1016 6327
invoice accept: apply 39.48 18148 4738
invoice accept: cancel 48.26 1023 5791
customer reserve: creation 3619.03 177 434284
customer reserve: removal 4727.23 70 567267
realization accept: apply 78.41 200 9409
realization accept: cancel 84.84 128 10181
payment from customer: creation 56.49 126 6779
payment from customer: removal 5.23 90 627
payment to supplier: creation 45.68 148 5482
payment to supplier: removal 3.23 129 387
service: total inventory turnovers 3.29 4348 395
service: total monetary turnovers 5.30 701 636
service: aggregate perf. data 0.96 14490 115
service: refresh index statistics 0.03 224759 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2020 13:27:29 to 29-12-2020 13:27:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3480
TEST_TIME 2 2992
TEST_TIME 3 3432
TEST_TIME 4 3686
TEST_TIME 5 3828
TEST_TIME 6 3789
TEST_TIME 7 3916
TEST_TIME 8 3847
TEST_TIME 9 4100
TEST_TIME 10 4361
TEST_TIME 11 4663
TEST_TIME 12 5117
TEST_TIME 13 5317
TEST_TIME 14 5714
TEST_TIME 15 5881
TEST_TIME 16 6023
TEST_TIME 17 6211
TEST_TIME 18 6545
TEST_TIME 19 6646
TEST_TIME 20 7076
TEST_TIME 21 7302
TEST_TIME 22 7453
TEST_TIME 23 7499
TEST_TIME 24 7553
TEST_TIME 25 7778
TEST_TIME 26 7912
TEST_TIME 27 8279
TEST_TIME 28 8337
TEST_TIME 29 8389
TEST_TIME 30 8881
TEST_TIME 31 9445
TEST_TIME 32 10029
TEST_TIME 33 10243
TEST_TIME 34 10324
TEST_TIME 35 10202
TEST_TIME 36 10163
TEST_TIME 37 10178
TEST_TIME 38 10101
TEST_TIME 39 10091
TEST_TIME 40 10159
TEST_TIME 41 9930
TEST_TIME 42 9779
TEST_TIME 43 9688
TEST_TIME 44 9563
TEST_TIME 45 9545
TEST_TIME 46 9499
TEST_TIME 47 9607
TEST_TIME 48 9527
TEST_TIME 49 9482
TEST_TIME 50 9433
TEST_TIME 51 9333
TEST_TIME 52 9204
TEST_TIME 53 9244
TEST_TIME 54 9163
TEST_TIME 55 9175
TEST_TIME 56 9227
TEST_TIME 57 9246
TEST_TIME 58 9236
TEST_TIME 59 9260
TEST_TIME 60 9432
TEST_TIME 61 9500
TEST_TIME 62 9600
TEST_TIME 63 9572
TEST_TIME 64 9557
TEST_TIME 65 9631
TEST_TIME 66 9520
TEST_TIME 67 9499
TEST_TIME 68 9417
TEST_TIME 69 9398
TEST_TIME 70 9316
TEST_TIME 71 9286
TEST_TIME 72 9205
TEST_TIME 73 9126
TEST_TIME 74 9144
TEST_TIME 75 9081
TEST_TIME 76 9059
TEST_TIME 77 9023
TEST_TIME 78 9050
TEST_TIME 79 9087
TEST_TIME 80 9095
TEST_TIME 81 9138
TEST_TIME 82 9189
TEST_TIME 83 9224
TEST_TIME 84 9203
TEST_TIME 85 9258
TEST_TIME 86 9346
TEST_TIME 87 9286
TEST_TIME 88 9197
TEST_TIME 89 9144
TEST_TIME 90 9086
TEST_TIME 91 9031
TEST_TIME 92 9080
TEST_TIME 93 9064
TEST_TIME 94 9040
TEST_TIME 95 9020
TEST_TIME 96 9021
TEST_TIME 97 9042
TEST_TIME 98 9034
TEST_TIME 99 9084
TEST_TIME 100 9048
TEST_TIME 101 9085
TEST_TIME 102 9074
TEST_TIME 103 9168
TEST_TIME 104 9199
TEST_TIME 105 9201
TEST_TIME 106 9156
TEST_TIME 107 9086
TEST_TIME 108 9051
TEST_TIME 109 8991
TEST_TIME 110 8947
TEST_TIME 111 8937
TEST_TIME 112 8923
TEST_TIME 113 8958
TEST_TIME 114 8956
TEST_TIME 115 8940
TEST_TIME 116 8975
TEST_TIME 117 8943
TEST_TIME 118 8956
TEST_TIME 119 9016
TEST_TIME 120 9043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-12-2020 13:27:33 to 29-12-2020 13:27:36.

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 1488661 1488661 0 0.00 0 18782 7 0 0
doc_list_biud 1488657 1488657 0 0.00 0 74057 34 0 0
sp_add_invoice_to_stock 4867 4738 129 2.65 35 161615 18148 0 129
sp_cancel_adding_invoice 5791 5791 0 0.00 5 85666 1024 0 0
sp_cancel_client_order 1716 1716 0 0.00 5 11156 391 0 0
sp_cancel_customer_reserve 567267 567267 0 0.00 1 74061 70 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 3 5633 90 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 8118 130 0 0
sp_cancel_supplier_invoice 6341 6327 14 0.22 2 19870 1016 0 14
sp_cancel_supplier_order 405 405 0 0.00 20 1234737 122466 0 0
sp_cancel_write_off 10181 10181 0 0.00 1 8883 129 0 0
sp_client_order 19596 19596 0 0.00 4 21767 456 0 0
sp_customer_reserve 434284 434284 0 0.00 0 105924 178 0 0
sp_fill_shopping_cart 29860 29860 0 0.00 1 17548 43 0 0
sp_fill_shopping_cart_clo_res 434278 434278 0 0.00 0 27466 19 0 0
sp_get_clo_for_invoice 4727 4727 0 0.00 0 29040 380 0 0
sp_kill_cost_storno 16986 16986 0 0.00 0 11490 67 0 0
sp_kill_qstorno_ret_qs2qd 1151447 1151447 0 0.00 0 67285 38 0 0
sp_kill_qty_storno 605821 605821 0 0.00 0 74057 82 0 0
sp_lock_selected_doc 19643 19643 0 0.00 0 23278 7 0 0
sp_make_cost_storno 26396 26396 0 0.00 0 17139 66 0 0
sp_multiply_rows_for_pdistr 26395 26395 0 0.00 0 5926 34 0 0
sp_multiply_rows_for_qdistr 19596 19596 0 0.00 0 21544 279 0 0
sp_pay_from_customer 6779 6779 0 0.00 4 12939 126 0 0
sp_pay_to_supplier 5482 5482 0 0.00 4 33601 148 0 0
sp_payment_common 12261 12261 0 0.00 1 8533 26 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 11150 353 0 0
sp_qd_handle_on_invoice_upd_sts 10516 10516 0 0.00 0 36261 398 0 0
sp_qd_handle_on_reserve_upd_sts 9409 9409 0 0.00 0 17802 62 0 0
sp_reserve_write_off 9409 9409 0 0.00 7 23299 200 0 0
sp_supplier_invoice 5131 5131 0 0.00 6 138895 3953 0 0
sp_supplier_order 5145 5145 0 0.00 5 21954 1445 0 0
srv_aggregate_perf_data 116 115 1 0.86 2724 143004 14491 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1154 111006 43607 0 0
srv_find_qd_qs_mism 1040610 1040610 0 0.00 0 18782 5 0 0
srv_make_invnt_saldo 395 395 0 0.00 41 126797 4349 0 0
srv_make_money_saldo 636 636 0 0.00 1 15315 702 0 0
srv_recalc_idx_stat 4 4 0 0.00 89528 328089 224759 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 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 4738 4738 0 0.00 0 9759 178 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 1732 89 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 2325 12 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 1380 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 3136 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 1659 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1389 17 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 52954 1887 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 1304 7 0 0
t$perf-norm:sp_client_order 19596 19596 0 0.00 0 8682 21 0 0
t$perf-norm:sp_customer_reserve 898 898 0 0.00 0 7677 16 0 0
t$perf-norm:sp_pay_from_customer 6779 6779 0 0.00 0 6615 10 0 0
t$perf-norm:sp_pay_to_supplier 5482 5482 0 0.00 0 7820 10 0 0
t$perf-norm:sp_reserve_write_off 9409 9409 0 0.00 0 5594 10 0 0
t$perf-norm:sp_supplier_invoice 5131 5131 0 0.00 0 7262 17 0 0
t$perf-norm:sp_supplier_order 5145 5145 0 0.00 0 5315 19 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 130 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 395 395 0 0.00 0 477 8 0 0
t$perf-norm:srv_make_money_saldo 636 636 0 0.00 0 861 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9708 9450 258 2.65 0 10981 18 0 258
v_all_customers 36102 36102 0 0.00 0 18 1 0 0
v_all_suppliers 28566 28566 0 0.00 0 22 1 0 0
v_all_wares 39192 39192 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 1052 7 0 0
v_cancel_client_order 3432 3432 0 0.00 0 2798 5 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 5211 9 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 4238 8 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 1399 8 0 0
v_cancel_supplier_invoice 772 744 28 3.62 0 2400 17 0 28
v_cancel_supplier_order 810 810 0 0.00 0 374 4 0 0
v_cancel_write_off 1908 1908 0 0.00 0 1563 13 0 0
v_max_id_clo_ord 5141 5141 0 0.00 0 1676 2 0 0
v_max_id_clo_res 898 898 0 0.00 0 1329 11 0 0
v_max_id_ord_sup 5122 5122 0 0.00 0 3195 3 0 0
v_max_non_paid_invoice 1462 1462 0 0.00 0 3963 6 0 0
v_max_non_paid_realizn 3453 3453 0 0.00 0 3875 4 0 0
v_min_id_clo_ord 5141 5141 0 0.00 0 17231 16 0 0
v_min_id_clo_res 898 898 0 0.00 0 3058 8 0 0
v_min_id_ord_sup 5122 5122 0 0.00 0 5210 10 0 0
v_min_non_paid_invoice 5482 5482 0 0.00 0 5642 16 0 0
v_min_non_paid_realizn 6779 6779 0 0.00 0 8529 20 0 0
v_our_firm 9742 9742 0 0.00 0 14 1 0 0
v_reserve_write_off 18818 18818 0 0.00 0 5135 5 0 0
x_lock_depdocs_on_canc_invoice 11746 11746 0 0.00 0 11681 108 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 4856 352 0 0
x_make_qstorno_1000_1200 5145 5145 0 0.00 2 21326 1349 0 0
x_make_qstorno_1200_2000 5131 5131 0 0.00 2 132967 3801 0 0
x_make_qstorno_2100_3300 434284 434284 0 0.00 0 105894 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2020 13:27:37 to 29-12-2020 13:27:41.

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:55 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:56 29422 8192000000 9727535888 10024964096 803482944 8.932 100 84 168 0 831954832 7484192 20987696
10:59:28 12746 8192000000 9762379616 10067120128 833352272 9.233 100 89 178 1 867516272 12511312 21652688
11:00:52 18123 8192000000 9804083088 10119704576 859348864 9.494 100 89 178 0 908080128 27982768 20748496
11:02:13 12682 8192000000 9845780080 10173878272 898836288 9.887 100 76 151 0 954525424 39028016 16661120
11:03:31 8242 8192000000 9899451168 10236166144 942655936 10.319 100 82 164 1 1016370880 55701584 18013360
11:04:41 6564 8192000000 9943140400 10291601408 979168832 10.676 100 80 158 1 1059466320 63757872 16539616
11:05:50 4279 8192000000 9984790912 10341511168 1012483904 10.999 100 88 176 1 1091947456 61710688 17752864
11:06:57 2583 8192000000 10007359824 10375122944 1040057872 11.265 100 90 175 0 1116771552 58398576 18315104
11:08:01 2404 8192000000 10037016512 10405658624 1059085744 11.448 100 85 170 0 1143380672 66713424 17581504
11:09:07 8125 8192000000 10027791024 10406240256 1069760128 11.550 100 70 138 1 1143882688 58861920 15260640
11:10:17 3159 8192000000 10015672832 10410188800 1076735120 11.616 100 37 74 1 1138761088 54223760 7802208
11:11:22 6024 8192000000 10012224016 10406805504 1086839312 11.713 100 51 100 5 1135905808 37090048 11976448
11:12:28 7271 8192000000 9993079968 10397224960 1092244992 11.764 100 46 92 1 1126661648 24956752 9459904
11:13:43 17516 8192000000 10017198288 10426834944 1093627776 11.777 100 24 48 2 1131925920 33094192 5203952
11:15:01 5319 8192000000 10022038608 10422665216 1100753312 11.845 100 62 124 2 1142004256 27780752 13470192
11:16:08 6687 8192000000 10016740816 10430009344 1098307408 11.822 100 43 86 1 1136948560 29213840 9427312
11:17:15 54302 8192000000 10010105776 10406088704 1066029984 11.514 97 43 86 0 1097518752 22466768 9022000
11:19:13 4185 8192000000 9982205680 10383093760 1051342656 11.374 98 34 66 5 1080647296 22470544 6834096
11:20:19 8725 8192000000 9958223008 10344058880 994435664 10.825 95 40 81 4 1017929424 14370768 9122992
11:21:32 8126 8192000000 9928526160 10303848448 995698928 10.837 98 45 90 1 1019723360 13327040 10697392
11:22:43 44125 8192000000 9928340672 10297864192 976755376 10.653 95 44 86 2 998765680 13327360 8682944
11:24:29 5455 8192000000 9955004400 10340515840 1044968624 11.312 100 46 91 2 1071812464 16468480 10375360
11:25:36 6663 8192000000 9976390208 10378956800 1056474416 11.423 100 55 110 1 1095940400 26734608 12731376
11:26:46 16902 8192000000 9998087488 10402930688 1082505344 11.671 100 33 66 1 1116415040 26768016 7141680
11:28:32 101698 8192000000 10052627264 10462052352 1103940048 11.875 100 38 75 0 1191462592 79414064 8108480
11:31:23 14097 8192000000 10100917344 10506878976 1122125824 12.047 100 38 75 1 1206965888 76409072 8430992
11:32:57 11796 8192000000 10128107456 10530615296 1137298080 12.190 100 35 70 3 1230541216 85658352 7584784
11:34:12 5349 8192000000 10181584688 10581094400 1145561792 12.268 100 66 131 0 1285836080 126564048 13710240
11:35:23 3513 8192000000 10234594576 10640936960 1151770320 12.326 100 72 144 1 1341334496 173320528 16243648
11:36:36 9427 8192000000 10224795296 10635124736 1160729472 12.410 100 52 104 1 1331233840 157757280 12747088
11:37:52 13819 8192000000 10267015760 10688212992 1170311648 12.500 100 62 126 0 1376080736 192039088 13730000
11:39:13 12935 8192000000 10248188448 10663661568 1168459648 12.482 100 50 99 1 1350081136 170104080 11517408
11:40:36 9535 8192000000 10233712544 10646990848 1159060656 12.394 99 58 116 0 1334656864 162256512 13339696
11:41:51 2177 8192000000 10272105248 10684805120 1162681808 12.428 100 65 129 1 1366445648 189542384 14221456
11:43:01 3277 8192000000 10261774000 10679795712 1152114240 12.329 99 65 130 0 1362198896 195922624 14162032
11:44:13 3658 8192000000 10235165056 10644959232 1149738912 12.307 100 61 122 0 1338955696 175815792 13400992
11:45:29 4344 8192000000 10278692576 10682490880 1150398752 12.313 100 87 172 1 1374224928 204384208 19441968
11:46:42 18289 8192000000 10278653168 10696327168 1143956576 12.253 100 35 69 1 1389707600 236900160 8850864
11:48:05 12211 8192000000 10284132816 10714271744 1145917504 12.271 100 44 89 2 1392119424 236204704 9997216
11:49:22 3792 8192000000 10325939136 10736533504 1140350656 12.219 100 58 113 2 1424806688 270322848 14133184
11:50:33 7601 8192000000 10344026768 10758639616 1127539184 12.098 99 42 83 1 1438239328 300250128 10450016
11:51:46 11787 8192000000 10399170512 10832433152 1155799808 12.364 100 43 83 1 1508796448 342375056 10621584
11:53:10 14815 8192000000 10427795504 10867400704 1152072848 12.329 100 29 57 0 1517270288 357511392 7686048
11:54:32 1154 8192000000 10438178768 10847043584 1154204848 12.349 100 79 157 3 1526161744 353867280 18089616
11:55:37 17183 8192000000 10398270032 10888163328 1106731040 11.901 98 31 61 1 1515500608 400158320 8611248
11:57:05 13290 8192000000 10302826896 10741669888 1111623392 11.948 99 22 42 1 1406708416 289584080 5500944
11:58:21 39392 8192000000 10105751392 10528292864 1129528512 12.117 100 21 42 2 1211821744 77548352 4744880
12:00:53 67081 8192000000 10091670576 10526191616 1125939744 12.083 99 22 44 1 1168400336 37471184 4989408
12:03:07 97526 8192000000 10142630672 10619305984 1111246832 11.944 98 26 52 1 1226878800 110583872 5048096
12:05:57 60615 8192000000 10093341632 10585956352 1130247168 12.124 100 23 46 1 1219325136 83932736 5145232
12:08:39 27578 8192000000 10065580960 10510262272 1140875056 12.224 100 42 86 1 1167270960 17770416 8625488
12:10:08 27611 8192000000 10121041152 10559098880 1135606080 12.174 99 33 65 2 1199387472 56947904 6833488
12:12:11 47882 8192000000 10118126208 10594168832 1130191296 12.123 99 14 28 2 1233869312 99935200 3742816
12:13:59 21020 8192000000 10074185088 10529210368 1144133056 12.254 100 10 21 2 1190516432 44500352 1883024
12:15:24 80101 8192000000 10080915296 10534289408 1107926576 11.913 96 18 34 1 1144580128 32801344 3852208
12:17:46 66775 8192000000 10099185328 10564030464 1131331168 12.134 99 17 33 1 1196717792 62172784 3213840
12:19:54 54191 8192000000 10111472288 10557739008 1109935856 11.932 97 11 22 3 1156117792 43982816 2199120
12:21:58 79033 8192000000 10123182320 10594242560 1135543392 12.174 99 21 42 0 1240234304 100032016 4658896
12:24:44 99995 8192000000 10197108928 10641231872 1136551200 12.183 98 25 50 1 1270257792 128297984 5408608
12:27:25 52420 8192000000 10140558880 10628173824 1114649264 11.976 97 17 34 0 1197307536 79254688 3403584
12:29:20 70265 8192000000 10133747888 10576478208 1129148224 12.113 98 15 30 1 1184836800 52538064 3150512
12:31:39 106656 8192000000 10091041168 10558984192 1119333056 12.021 97 13 25 2 1169497744 47204864 2959824
12:34:34 89099 8192000000 10072649536 10594770944 1124693552 12.071 98 6 12 0 1185493088 59583888 1215648
12:37:03 34859 8192000000 10077354160 10573246464 1141051072 12.225 100 10 20 2 1197400016 54143504 2205440
12:38:39 97693 8192000000 10088304768 10536501248 1115102704 11.981 98 16 32 0 1161040384 42883936 3053744
12:41:17 34835 8192000000 10041797520 10487619584 1079727040 11.645 95 17 33 1 1101366288 18259888 3379360
12:42:53 55116 8192000000 10105822000 10545168384 1088576064 11.729 96 27 54 0 1175711504 80976832 6158608
12:44:52 80015 8192000000 10087842112 10572959744 1104894624 11.884 99 25 50 1 1239706784 128993344 5818816
12:47:23 42829 8192000000 10116146112 10614312960 1104969920 11.885 99 28 56 1 1205916144 95197184 5749040
12:49:13 32198 8192000000 10075112640 10539143168 1091128240 11.753 97 40 80 0 1157439088 57948288 8362560
12:50:50 29667 8192000000 10073281776 10526670848 1087153136 11.716 97 31 62 1 1142159472 48717744 6288592
12:52:23 65722 8192000000 10052062368 10563809280 1079191232 11.640 98 9 18 1 1158951664 78270448 1489984
12:54:28 111006 8192000000 10060360224 10510086144 1090501664 11.747 98 24 48 1 1139664416 43710704 5452048
12:57:22 33258 8192000000 10087525888 10553511936 1094166016 11.782 98 6 12 0 1155783056 60378048 1238992
12:58:56 48880 8192000000 10050203968 10482987008 1109605136 11.929 99 20 38 1 1133479328 20070576 3803616
13:00:50 43562 8192000000 10086862544 10524057600 1120629632 12.033 99 35 70 0 1154857968 26814752 7413584
13:02:35 46547 8192000000 10116462208 10551668736 1138510288 12.202 100 18 36 1 1212156576 69768304 3877984
13:04:32 89711 8192000000 10098282400 10547261440 1123707488 12.062 98 27 54 1 1192238352 63002288 5528576
13:07:02 44840 8192000000 10119829216 10578554880 1131068640 12.131 99 37 74 3 1206726720 67730768 7927312
13:09:02 38782 8192000000 10110219168 10610151424 1143160144 12.245 100 22 43 3 1212550208 64322288 5067776
13:10:41 69212 8192000000 10081231136 10537742336 1131666784 12.137 99 17 34 1 1166120016 31480512 2972720
13:12:54 97703 8192000000 10052802480 10505809920 1115287520 11.982 98 22 44 0 1151347552 31736048 4323984
13:15:38 63763 8192000000 10087779472 10543431680 1132088464 12.141 99 12 25 0 1186685376 51962336 2634576
13:18:11 69726 8192000000 10137677712 10608132096 1124491104 12.069 98 14 28 1 1185393056 57811696 3090256
13:20:26 53283 8192000000 10101021632 10541404160 1121349056 12.040 97 20 39 2 1164831280 39322608 4159616
13:22:21 55833 8192000000 10118508336 10572886016 1125323344 12.077 98 20 40 1 1187688672 57814624 4550704
13:24:17 72283 8192000000 10148763712 10615259136 1143578368 12.249 99 28 56 0 1229692464 79761040 6353056
13:26:34 23205 8192000000 9032898096 9287286784 178860448 2.136 16 2 4 0 191291072 12359344 71280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-12-2020 13:27:43 to 29-12-2020 13:27:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 129 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2020 13:27:59 to 29-12-2020 13:28:01.

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          227131
MON$OLDEST_ACTIVE               227132
MON$OLDEST_SNAPSHOT             227132
MON$NEXT_TRANSACTION            227132
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                       2114560
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             778
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2308 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2308 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2308 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.2308 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Dec 29 13:26:59 2020
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Dec 29 13:26:59 2020
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

29.12.20 13:28:02. 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-29 13:28:05 No severe PSQL-related problems occured
29.12.20 13:28:06. End of report.