Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.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 16860072 kB
MemAvailable 31394560 kB
Buffers 0 kB
Cached 14723448 kB
SwapCached 3696 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-22 13:26:55
DTS_END                         2020-12-22 13:26:55.0960
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/35358
TRN_ID                          237258
ATT_ID                          669
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: 8822.66 23755 1058719
customer order: creation 175.80 423 21096
customer order: refuse 14.65 341 1758
order to supplier: creation 46.07 1347 5528
order to supplier: removal 3.14 125826 377
invoice (draft): creation 46.60 3388 5592
invoice (draft): removal 48.48 945 5817
invoice accept: apply 41.91 16300 5029
invoice accept: cancel 44.07 929 5288
customer reserve: creation 3815.83 160 457899
customer reserve: removal 4287.88 74 514546
realization accept: apply 84.57 162 10148
realization accept: cancel 83.39 116 10007
payment from customer: creation 60.40 101 7248
payment from customer: removal 6.24 65 749
payment to supplier: creation 49.57 144 5948
payment to supplier: removal 3.34 59 401
service: total inventory turnovers 3.64 3213 437
service: total monetary turnovers 6.09 577 731
service: aggregate perf. data 0.97 13522 116
service: refresh index statistics 0.03 307408 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2020 13:28:20 to 22-12-2020 13:28:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5056
TEST_TIME 2 3352
TEST_TIME 3 3395
TEST_TIME 4 4003
TEST_TIME 5 4312
TEST_TIME 6 4505
TEST_TIME 7 5245
TEST_TIME 8 5181
TEST_TIME 9 5636
TEST_TIME 10 5844
TEST_TIME 11 6032
TEST_TIME 12 5948
TEST_TIME 13 6383
TEST_TIME 14 6523
TEST_TIME 15 6986
TEST_TIME 16 7395
TEST_TIME 17 7797
TEST_TIME 18 8514
TEST_TIME 19 8348
TEST_TIME 20 8481
TEST_TIME 21 8398
TEST_TIME 22 8476
TEST_TIME 23 9311
TEST_TIME 24 9342
TEST_TIME 25 9972
TEST_TIME 26 10899
TEST_TIME 27 11590
TEST_TIME 28 11357
TEST_TIME 29 11127
TEST_TIME 30 10876
TEST_TIME 31 10838
TEST_TIME 32 10826
TEST_TIME 33 10577
TEST_TIME 34 10323
TEST_TIME 35 10404
TEST_TIME 36 10212
TEST_TIME 37 10253
TEST_TIME 38 10086
TEST_TIME 39 10061
TEST_TIME 40 9927
TEST_TIME 41 9854
TEST_TIME 42 9861
TEST_TIME 43 9866
TEST_TIME 44 9862
TEST_TIME 45 9826
TEST_TIME 46 9718
TEST_TIME 47 9773
TEST_TIME 48 9871
TEST_TIME 49 9792
TEST_TIME 50 9756
TEST_TIME 51 9838
TEST_TIME 52 9838
TEST_TIME 53 9872
TEST_TIME 54 9813
TEST_TIME 55 9702
TEST_TIME 56 9562
TEST_TIME 57 9450
TEST_TIME 58 9512
TEST_TIME 59 9429
TEST_TIME 60 9349
TEST_TIME 61 9256
TEST_TIME 62 9189
TEST_TIME 63 9174
TEST_TIME 64 9112
TEST_TIME 65 9155
TEST_TIME 66 9285
TEST_TIME 67 9276
TEST_TIME 68 9308
TEST_TIME 69 9272
TEST_TIME 70 9246
TEST_TIME 71 9206
TEST_TIME 72 9305
TEST_TIME 73 9328
TEST_TIME 74 9415
TEST_TIME 75 9401
TEST_TIME 76 9316
TEST_TIME 77 9248
TEST_TIME 78 9218
TEST_TIME 79 9129
TEST_TIME 80 9139
TEST_TIME 81 9079
TEST_TIME 82 9125
TEST_TIME 83 9082
TEST_TIME 84 9172
TEST_TIME 85 9116
TEST_TIME 86 9167
TEST_TIME 87 9155
TEST_TIME 88 9134
TEST_TIME 89 9101
TEST_TIME 90 9212
TEST_TIME 91 9192
TEST_TIME 92 9180
TEST_TIME 93 9089
TEST_TIME 94 9047
TEST_TIME 95 9074
TEST_TIME 96 9050
TEST_TIME 97 9027
TEST_TIME 98 9067
TEST_TIME 99 9039
TEST_TIME 100 9024
TEST_TIME 101 9041
TEST_TIME 102 9005
TEST_TIME 103 9024
TEST_TIME 104 9088
TEST_TIME 105 9066
TEST_TIME 106 9074
TEST_TIME 107 9092
TEST_TIME 108 9055
TEST_TIME 109 8981
TEST_TIME 110 8957
TEST_TIME 111 8912
TEST_TIME 112 8869
TEST_TIME 113 8885
TEST_TIME 114 8857
TEST_TIME 115 8854
TEST_TIME 116 8866
TEST_TIME 117 8857
TEST_TIME 118 8879
TEST_TIME 119 8862
TEST_TIME 120 8829

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-12-2020 13:28:23 to 22-12-2020 13:28:27.

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 1482439 1482439 0 0.00 0 16526 6 0 0
doc_list_biud 1482434 1482434 0 0.00 0 34185 32 0 0
sp_add_invoice_to_stock 5130 5029 101 1.96 34 165277 16300 0 101
sp_cancel_adding_invoice 5288 5288 0 0.00 5 78209 929 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 15633 341 0 0
sp_cancel_customer_reserve 514546 514546 0 0.00 1 28823 74 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 3 3115 66 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 2 2616 60 0 0
sp_cancel_supplier_invoice 5827 5817 10 0.17 2 28205 945 0 10
sp_cancel_supplier_order 377 377 0 0.00 6 1228555 125826 0 0
sp_cancel_write_off 10007 10007 0 0.00 1 30896 116 0 0
sp_client_order 21096 21096 0 0.00 4 32842 423 0 0
sp_customer_reserve 457899 457899 0 0.00 0 77601 161 0 0
sp_fill_shopping_cart 32202 32202 0 0.00 1 11002 42 0 0
sp_fill_shopping_cart_clo_res 457894 457894 0 0.00 0 29021 18 0 0
sp_get_clo_for_invoice 5013 5013 0 0.00 0 29038 426 0 0
sp_kill_cost_storno 16445 16445 0 0.00 0 16515 57 0 0
sp_kill_qstorno_ret_qs2qd 1045293 1045293 0 0.00 0 34183 40 0 0
sp_kill_qty_storno 552951 552951 0 0.00 0 34185 85 0 0
sp_lock_selected_doc 20977 20977 0 0.00 0 7363 5 0 0
sp_make_cost_storno 28356 28356 0 0.00 0 11452 53 0 0
sp_multiply_rows_for_pdistr 28355 28355 0 0.00 0 10629 30 0 0
sp_multiply_rows_for_qdistr 21095 21095 0 0.00 0 16207 243 0 0
sp_pay_from_customer 7248 7248 0 0.00 4 16275 101 0 0
sp_pay_to_supplier 5948 5948 0 0.00 5 36783 144 0 0
sp_payment_common 13195 13195 0 0.00 1 10579 20 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 15626 317 0 0
sp_qd_handle_on_invoice_upd_sts 10298 10298 0 0.00 0 33058 417 0 0
sp_qd_handle_on_reserve_upd_sts 10148 10148 0 0.00 0 11569 48 0 0
sp_reserve_write_off 10148 10148 0 0.00 7 18760 163 0 0
sp_supplier_invoice 5592 5592 0 0.00 6 76981 3389 0 0
sp_supplier_order 5528 5528 0 0.00 5 36181 1347 0 0
srv_aggregate_perf_data 116 116 0 0.00 2609 131613 13522 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1479 133226 47685 0 0
srv_find_qd_qs_mism 1010526 1010526 0 0.00 0 9533 5 0 0
srv_make_invnt_saldo 437 437 0 0.00 30 104398 3213 0 0
srv_make_money_saldo 731 731 0 0.00 1 16701 577 0 0
srv_recalc_idx_stat 4 4 0 0.00 209700 546440 307408 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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-norm:sp_add_invoice_to_stock 5029 5029 0 0.00 0 7951 131 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 5256 89 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 3063 11 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 725 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 1170 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1380 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 466 11 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 40384 1555 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 930 6 0 0
t$perf-norm:sp_client_order 21097 21097 0 0.00 0 12957 15 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 1411 7 0 0
t$perf-norm:sp_pay_from_customer 7248 7248 0 0.00 0 4953 7 0 0
t$perf-norm:sp_pay_to_supplier 5948 5948 0 0.00 0 4038 7 0 0
t$perf-norm:sp_reserve_write_off 10148 10148 0 0.00 0 5226 9 0 0
t$perf-norm:sp_supplier_invoice 5592 5592 0 0.00 0 5951 13 0 0
t$perf-norm:sp_supplier_order 5528 5528 0 0.00 0 5792 16 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 505 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3044 53 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 1543 13 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 3231 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10220 10018 202 1.97 0 4741 16 0 202
v_all_customers 37994 37994 0 0.00 0 156 1 0 0
v_all_suppliers 30836 30836 0 0.00 0 155 1 0 0
v_all_wares 42190 42190 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1101 6 0 0
v_cancel_client_order 3516 3516 0 0.00 0 4196 4 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 1121 4 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 844 3 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1041 6 0 0
v_cancel_supplier_invoice 814 794 20 2.45 0 1596 16 0 20
v_cancel_supplier_order 754 754 0 0.00 0 4220 8 0 0
v_cancel_write_off 2006 2006 0 0.00 0 2686 13 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 2426 2 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 2058 12 0 0
v_max_id_ord_sup 5588 5588 0 0.00 0 2951 5 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 99 2 0 0
v_max_non_paid_realizn 3995 3995 0 0.00 0 653 2 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 2997 7 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 6874 31 0 0
v_min_id_ord_sup 5588 5588 0 0.00 0 6249 10 0 0
v_min_non_paid_invoice 5947 5947 0 0.00 0 10575 16 0 0
v_min_non_paid_realizn 7248 7248 0 0.00 0 6759 13 0 0
v_our_firm 10702 10702 0 0.00 0 12 1 0 0
v_reserve_write_off 20294 20294 0 0.00 0 4474 4 0 0
x_lock_depdocs_on_canc_invoice 10708 10708 0 0.00 0 13786 108 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 28150 386 0 0
x_make_qstorno_1000_1200 5528 5528 0 0.00 2 36149 1271 0 0
x_make_qstorno_1200_2000 5592 5592 0 0.00 2 76575 3230 0 0
x_make_qstorno_2100_3300 457899 457899 0 0.00 0 68524 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2020 13:28:28 to 22-12-2020 13:28:32.

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 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:58:00 29648 8192000000 9726560832 10023284736 800678656 8.903 100 88 176 1 830851904 7991264 22181984
10:59:32 24650 8192000000 9763711040 10069737472 836084928 9.260 100 85 170 1 869552544 12842000 20625616
11:00:59 14484 8192000000 9809723936 10127613952 877501264 9.675 100 84 168 1 917742080 21631728 18609088
11:02:20 15845 8192000000 9879793776 10213212160 921458016 10.110 100 84 168 2 987763680 47886592 18419072
11:03:45 7178 8192000000 9925443888 10270756864 970607424 10.593 100 81 162 0 1039890336 52626112 16656800
11:04:56 5191 8192000000 9975349344 10330353664 996352608 10.843 100 85 169 0 1091600800 78098592 17149600
11:06:04 2560 8192000000 9990133664 10352484352 1020412720 11.076 100 81 161 0 1111421568 74935296 16073552
11:07:08 2748 8192000000 10002340336 10368974848 1047008592 11.332 100 90 181 3 1123162016 58233856 17919568
11:08:13 2371 8192000000 10054828992 10423418880 1068808528 11.541 100 96 192 1 1162076128 73044576 20223024
11:09:17 9415 8192000000 10007305840 10392383488 1073825056 11.589 100 60 120 2 1133143088 46543056 12774976
11:10:28 5193 8192000000 10008238992 10399379456 1080106832 11.648 100 59 115 3 1125299360 32217568 12974960
11:11:38 3673 8192000000 10005708816 10395815936 1086661568 11.711 100 56 112 2 1126579824 28447344 11470912
11:12:45 5539 8192000000 10003429360 10412269568 1092923920 11.770 100 36 73 3 1127021440 26979616 7117904
11:13:50 42803 8192000000 10003812848 10417229824 1098096080 11.820 100 35 69 2 1123559088 18099008 7364000
11:15:35 5724 8192000000 10026361888 10429104128 1105527040 11.890 100 44 83 3 1145798896 31643792 8628064
11:16:42 7021 8192000000 10005612720 10413346816 1089130384 11.734 99 38 75 4 1118076560 19905280 9040896
11:17:51 10747 8192000000 9974438160 10374184960 1024316448 11.114 95 22 45 2 1062617344 33859152 4441744
11:19:03 21010 8192000000 9937808496 10334957568 982923600 10.713 93 15 30 1 1008263920 22489248 2851072
11:20:25 6322 8192000000 9917843104 10302500864 979740368 10.682 97 21 42 3 1020158512 35962864 4455280
11:21:32 13727 8192000000 9933979792 10312572928 982738448 10.711 96 37 74 0 1007194240 15400016 9055776
11:22:47 37170 8192000000 9943103152 10337382400 1039946432 11.264 100 32 63 2 1076163440 29324144 6892864
11:24:36 13866 8192000000 9963506096 10365243392 1054081344 11.400 100 32 62 6 1077254112 15737200 7435568
11:25:51 4162 8192000000 9982733024 10367528960 1064720320 11.502 100 48 97 0 1093514800 17200192 11594288
11:26:57 7468 8192000000 10019279856 10411999232 1089581312 11.739 100 48 96 0 1126894256 27877616 9435328
11:28:20 83836 8192000000 10066339344 10470395904 1112134240 11.953 100 38 76 1 1159675120 40061696 7479184
11:31:00 3530 8192000000 10134159360 10536468480 1137313392 12.190 100 59 114 1 1244350416 94409200 12627824
11:32:08 4296 8192000000 10180847520 10592731136 1148358576 12.294 100 50 100 0 1292334240 132978752 10996912
11:33:15 14422 8192000000 10229856592 10652446720 1159660288 12.400 100 57 114 1 1341941232 169811072 12469872
11:34:49 7306 8192000000 10256446576 10667077632 1170755952 12.504 100 66 131 2 1354334096 169784496 13793648
11:36:02 4180 8192000000 10283225808 10691993600 1159121536 12.395 99 65 130 0 1380720848 207328928 14270384
11:37:10 3077 8192000000 10331949792 10734084096 1169107216 12.488 100 88 176 0 1434316864 245339408 19870240
11:38:21 10316 8192000000 10354315776 10773639168 1174900816 12.543 100 54 108 0 1459619872 272100864 12618192
11:39:35 1470 8192000000 10370162272 10795679744 1172956256 12.524 100 83 162 0 1472238800 279710512 19572032
11:40:41 3260 8192000000 10418343632 10824638464 1173151488 12.526 100 75 146 2 1517871840 327785184 16935168
11:41:47 2131 8192000000 10402962768 10808856576 1168937072 12.487 100 71 141 0 1503264240 318295232 16031936
11:42:53 7896 8192000000 10388370816 10836721664 1154434096 12.351 100 53 102 1 1515698624 348725600 12538928
11:44:12 5444 8192000000 10390434464 10811744256 1145578000 12.268 99 80 158 1 1486221648 322578656 18064992
11:45:21 3162 8192000000 10352751792 10750988288 1131042064 12.131 99 72 141 2 1444545168 297603424 15899680
11:46:29 8813 8192000000 10395734016 10808950784 1128392352 12.106 100 45 89 1 1491086464 351512224 11181888
11:47:43 10950 8192000000 10459440016 10878722048 1142545344 12.239 100 43 85 1 1563095120 409378944 11170832
11:48:56 9615 8192000000 10488202976 10963521536 1163837072 12.439 100 49 97 0 1610183216 433485072 12861072
11:50:15 11316 8192000000 10453894848 10887360512 1156831808 12.374 100 45 90 0 1544078368 375289856 11956704
11:51:49 21029 8192000000 10338748960 10841276416 1134068512 12.160 100 20 40 0 1468528304 328957280 5502512
11:53:17 47039 8192000000 10058011776 10537250816 1081875488 11.665 96 12 24 1 1155267376 71136928 2254960
11:55:57 85855 8192000000 10099584624 10554941440 1123112016 12.056 100 36 70 0 1212817360 83108752 6596592
11:58:27 37589 8192000000 10069911856 10556067840 1123403856 12.059 99 19 38 2 1193720416 66502880 3813680
12:00:05 84703 8192000000 10096359312 10612555776 1141615712 12.231 100 17 34 1 1225375712 80146592 3613408
12:02:38 58298 8192000000 10102919216 10602872832 1144554992 12.258 100 18 36 2 1245479984 96873488 4051504
12:04:59 54557 8192000000 10093990864 10528948224 1136393904 12.182 99 35 69 1 1185186368 41073776 7718688
12:06:59 50071 8192000000 10103520896 10578808832 1118751088 12.015 98 11 22 0 1193649872 72635632 2263152
12:09:32 26085 8192000000 10070914704 10501136384 1127166784 12.095 99 49 97 2 1165727440 28488656 10072000
12:11:03 67071 8192000000 10100043808 10563928064 1094815264 11.788 96 17 35 3 1145710944 47104416 3791264
12:13:11 54979 8192000000 10125059824 10604302336 1128563584 12.108 99 24 48 1 1218525504 84711296 5250624
12:15:20 49709 8192000000 10110753264 10551787520 1126427408 12.088 99 13 26 1 1172591168 43484400 2679360
12:17:17 101444 8192000000 10081810016 10550243328 1132505728 12.145 99 11 22 1 1198073024 63215456 2351840
12:19:59 61060 8192000000 10100487904 10549022720 1136283184 12.181 99 17 34 1 1176578064 36842896 3451984
12:22:04 88341 8192000000 10100164880 10552975360 1141772528 12.232 99 21 42 0 1189528016 43433456 4322032
12:24:33 18642 8192000000 10071997808 10571354112 1151961936 12.328 100 13 26 0 1206356528 51699920 2694672
12:25:52 39247 8192000000 10062437920 10513137664 1130285440 12.124 98 8 16 0 1151061536 19554736 1221360
12:27:33 46360 8192000000 10067933520 10520125440 1119519744 12.022 98 12 24 0 1151732688 29992096 2220848
12:29:32 97572 8192000000 10139576416 10584870912 1090714048 11.749 96 11 22 1 1173625568 80279504 2632016
12:32:15 65950 8192000000 10017665440 10473816064 1014982672 11.024 90 20 40 1 1074969984 55183216 4804096
12:34:22 37760 8192000000 9989335600 10414206976 1029432848 11.163 94 15 30 2 1067637456 35453296 2751312
12:36:00 36181 8192000000 10054275296 10478960640 1062394608 11.479 97 28 57 2 1125698416 57029520 6274288
12:37:36 74012 8192000000 10081284672 10530820096 1049348960 11.354 95 17 34 1 1136966848 84060560 3557328
12:39:51 97925 8192000000 10023787856 10474971136 1060184304 11.458 97 13 26 0 1153033536 90309792 2539440
12:42:41 107430 8192000000 10062151632 10515705856 1099801264 11.836 99 16 31 0 1159341776 55476544 4063968
12:45:34 66058 8192000000 10077885168 10562019328 1100377984 11.841 99 9 18 0 1181887472 79668256 1841232
12:48:07 72361 8192000000 10075261088 10500210688 1103362384 11.870 99 17 34 1 1197023072 90065840 3594848
12:50:21 59201 8192000000 10057849024 10527809536 1108868560 11.922 99 20 40 0 1179238544 65894672 4475312
12:52:20 57012 8192000000 10047002416 10497560576 1096684240 11.806 98 21 42 2 1133316240 32891536 3740464
12:54:17 52612 8192000000 10121841728 10571022336 1124041808 12.065 100 29 57 2 1189230672 59525312 5663552
12:56:10 73269 8192000000 10054666816 10478882816 1100696016 11.844 97 17 34 0 1136420288 32482208 3242064
12:58:24 116869 8192000000 10121429856 10581278720 1142879888 12.243 100 17 34 0 1215406784 69083408 3443488
13:01:42 62923 8192000000 10083391584 10539548672 1133512272 12.154 99 7 14 0 1177517216 42880864 1124080
13:04:14 92665 8192000000 10091792768 10526695424 1130790928 12.129 98 10 20 0 1171191936 38512400 1888608
13:06:53 46903 8192000000 10108712064 10541158400 1123325184 12.058 98 27 54 2 1194413808 65670736 5417888
13:08:41 66198 8192000000 10119723344 10581921792 1112998848 11.961 97 26 52 1 1203433680 84923248 5511584
13:10:51 92478 8192000000 10088173280 10559582208 1130069728 12.122 99 9 18 1 1168936128 36760080 2106320
13:13:34 133221 8192000000 10071143808 10539175936 1115781104 11.987 98 16 32 0 1193616128 74749472 3085552
13:16:50 32041 8192000000 10048824912 10522275840 1110351280 11.936 98 7 14 0 1137908384 26364080 1193024
13:18:22 59506 8192000000 10094562112 10545250304 1109589152 11.929 98 7 13 1 1156635552 45413920 1632480
13:20:26 27997 8192000000 10052808016 10495143936 1102144592 11.858 98 23 46 1 1144694432 37348720 5201120
13:21:55 63176 8192000000 10095554512 10570592256 1084322704 11.689 97 26 51 1 1183700432 93398912 5978816
13:23:59 65032 8192000000 10114494144 10559111168 1097928416 11.818 98 26 52 3 1183244288 79400112 5915760
13:26:04 53112 8192000000 9903466784 10292150272 841067392 9.310 75 23 46 2 970149648 123908032 5174224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2020 13:28:34 to 22-12-2020 13:28:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 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 22-12-2020 13:28:50 to 22-12-2020 13:28:52.

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          233633
MON$OLDEST_ACTIVE               239931
MON$OLDEST_SNAPSHOT             239931
MON$NEXT_TRANSACTION            239931
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                       2087008
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             817
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:200: 
:201: mscs-test-161.budzdorov.ru    Tue Dec 22 13:27:14 2020
:202:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:203: 
:204: 
:205: mscs-test-161.budzdorov.ru    Tue Dec 22 13:27:19 2020
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:207: 
:208: 
:209: mscs-test-161.budzdorov.ru    Tue Dec 22 13:27:20 2020
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:211: 
--- end of diff output ---

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

22.12.20 13:28:53. 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-22 13:28:55 No severe PSQL-related problems occured
22.12.20 13:28:56. End of report.