Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2020 07:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16659700 kB
MemAvailable 31340736 kB
Buffers 0 kB
Cached 14889296 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-26 07:37:08
DTS_END                         2020-12-26 07:37:08.6900
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/38914
TRN_ID                          233132
ATT_ID                          642
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: 8938.02 18607 1072562
customer order: creation 176.83 443 21220
customer order: refuse 14.57 311 1748
order to supplier: creation 46.69 1456 5603
order to supplier: removal 3.33 138309 400
invoice (draft): creation 46.73 3496 5607
invoice (draft): removal 49.45 1124 5934
invoice accept: apply 41.72 16845 5006
invoice accept: cancel 44.12 1053 5294
customer reserve: creation 3842.02 165 461042
customer reserve: removal 4379.52 83 525542
realization accept: apply 83.16 169 9979
realization accept: cancel 81.72 145 9807
payment from customer: creation 58.86 113 7063
payment from customer: removal 5.56 94 667
payment to supplier: creation 49.15 122 5898
payment to supplier: removal 3.34 95 401
service: total inventory turnovers 4.27 3076 512
service: total monetary turnovers 6.09 592 731
service: aggregate perf. data 0.86 16925 103
service: refresh index statistics 0.04 187516 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2020 07:37:49 to 26-12-2020 07:37:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6172
TEST_TIME 2 4829
TEST_TIME 3 4171
TEST_TIME 4 4655
TEST_TIME 5 4717
TEST_TIME 6 4800
TEST_TIME 7 4918
TEST_TIME 8 5056
TEST_TIME 9 4960
TEST_TIME 10 4796
TEST_TIME 11 4821
TEST_TIME 12 4865
TEST_TIME 13 5447
TEST_TIME 14 5763
TEST_TIME 15 5711
TEST_TIME 16 5751
TEST_TIME 17 5781
TEST_TIME 18 6107
TEST_TIME 19 6662
TEST_TIME 20 6513
TEST_TIME 21 7022
TEST_TIME 22 7084
TEST_TIME 23 7349
TEST_TIME 24 7951
TEST_TIME 25 8225
TEST_TIME 26 8281
TEST_TIME 27 8059
TEST_TIME 28 8710
TEST_TIME 29 9452
TEST_TIME 30 10959
TEST_TIME 31 10765
TEST_TIME 32 10824
TEST_TIME 33 10883
TEST_TIME 34 10776
TEST_TIME 35 10780
TEST_TIME 36 10553
TEST_TIME 37 10397
TEST_TIME 38 10380
TEST_TIME 39 10456
TEST_TIME 40 10225
TEST_TIME 41 10102
TEST_TIME 42 9984
TEST_TIME 43 9879
TEST_TIME 44 9742
TEST_TIME 45 9784
TEST_TIME 46 9844
TEST_TIME 47 9814
TEST_TIME 48 9750
TEST_TIME 49 9706
TEST_TIME 50 9636
TEST_TIME 51 9546
TEST_TIME 52 9558
TEST_TIME 53 9578
TEST_TIME 54 9540
TEST_TIME 55 9451
TEST_TIME 56 9458
TEST_TIME 57 9422
TEST_TIME 58 9440
TEST_TIME 59 9419
TEST_TIME 60 9419
TEST_TIME 61 9383
TEST_TIME 62 9432
TEST_TIME 63 9470
TEST_TIME 64 9488
TEST_TIME 65 9596
TEST_TIME 66 9569
TEST_TIME 67 9553
TEST_TIME 68 9612
TEST_TIME 69 9608
TEST_TIME 70 9606
TEST_TIME 71 9500
TEST_TIME 72 9394
TEST_TIME 73 9361
TEST_TIME 74 9306
TEST_TIME 75 9241
TEST_TIME 76 9197
TEST_TIME 77 9194
TEST_TIME 78 9184
TEST_TIME 79 9162
TEST_TIME 80 9184
TEST_TIME 81 9232
TEST_TIME 82 9218
TEST_TIME 83 9208
TEST_TIME 84 9216
TEST_TIME 85 9281
TEST_TIME 86 9320
TEST_TIME 87 9340
TEST_TIME 88 9324
TEST_TIME 89 9276
TEST_TIME 90 9243
TEST_TIME 91 9180
TEST_TIME 92 9146
TEST_TIME 93 9121
TEST_TIME 94 9099
TEST_TIME 95 9103
TEST_TIME 96 9141
TEST_TIME 97 9120
TEST_TIME 98 9171
TEST_TIME 99 9155
TEST_TIME 100 9147
TEST_TIME 101 9153
TEST_TIME 102 9199
TEST_TIME 103 9198
TEST_TIME 104 9202
TEST_TIME 105 9203
TEST_TIME 106 9247
TEST_TIME 107 9210
TEST_TIME 108 9129
TEST_TIME 109 9084
TEST_TIME 110 9066
TEST_TIME 111 9031
TEST_TIME 112 8996
TEST_TIME 113 8987
TEST_TIME 114 8994
TEST_TIME 115 9017
TEST_TIME 116 8989
TEST_TIME 117 8994
TEST_TIME 118 9022
TEST_TIME 119 9009
TEST_TIME 120 8944

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2020 07:37:53 to 26-12-2020 07:37:56.

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 1499923 1499923 0 0.00 0 14681 7 0 0
doc_list_biud 1499922 1499922 0 0.00 0 78737 36 0 0
sp_add_invoice_to_stock 5059 5006 53 1.04 34 208759 16845 0 53
sp_cancel_adding_invoice 5294 5294 0 0.00 4 146116 1053 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 10754 312 0 0
sp_cancel_customer_reserve 525542 525542 0 0.00 1 19806 83 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 9135 95 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 5690 96 0 0
sp_cancel_supplier_invoice 5937 5934 3 0.05 2 78742 1124 0 3
sp_cancel_supplier_order 400 400 0 0.00 14 1336335 138310 0 0
sp_cancel_write_off 9807 9807 0 0.00 1 14586 146 0 0
sp_client_order 21220 21220 0 0.00 4 22632 443 0 0
sp_customer_reserve 461042 461042 0 0.00 0 121463 165 0 0
sp_fill_shopping_cart 32394 32394 0 0.00 1 18688 40 0 0
sp_fill_shopping_cart_clo_res 461032 461032 0 0.00 0 16400 16 0 0
sp_get_clo_for_invoice 4993 4993 0 0.00 0 32537 402 0 0
sp_kill_cost_storno 16169 16169 0 0.00 0 9298 72 0 0
sp_kill_qstorno_ret_qs2qd 1067225 1067225 0 0.00 0 78733 45 0 0
sp_kill_qty_storno 563697 563697 0 0.00 0 78735 96 0 0
sp_lock_selected_doc 20602 20602 0 0.00 0 8378 6 0 0
sp_make_cost_storno 27933 27933 0 0.00 0 14560 48 0 0
sp_multiply_rows_for_pdistr 27933 27933 0 0.00 0 10060 32 0 0
sp_multiply_rows_for_qdistr 21220 21220 0 0.00 0 17015 245 0 0
sp_pay_from_customer 7063 7063 0 0.00 4 10137 113 0 0
sp_pay_to_supplier 5898 5898 0 0.00 5 29167 122 0 0
sp_payment_common 12961 12961 0 0.00 1 7520 19 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 10748 285 0 0
sp_qd_handle_on_invoice_upd_sts 10287 10287 0 0.00 0 31083 437 0 0
sp_qd_handle_on_reserve_upd_sts 9979 9979 0 0.00 0 30398 47 0 0
sp_reserve_write_off 9979 9979 0 0.00 6 30450 170 0 0
sp_supplier_invoice 5607 5607 0 0.00 6 83806 3497 0 0
sp_supplier_order 5603 5603 0 0.00 5 44801 1457 0 0
srv_aggregate_perf_data 103 103 0 0.00 2592 235421 16926 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2018 172323 42957 0 0
srv_find_qd_qs_mism 1024817 1024817 0 0.00 0 10176 6 0 0
srv_make_invnt_saldo 512 512 0 0.00 61 136570 3076 0 0
srv_make_money_saldo 731 731 0 0.00 0 19258 592 0 0
srv_recalc_idx_stat 5 5 0 0.00 142619 214785 187517 0 0
t$perf-abend:sp_add_invoice_to_stock 53 53 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5006 5006 0 0.00 0 12411 145 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 0 8066 110 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 4359 12 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 3109 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 2738 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 2102 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 270 6 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 30672 1860 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 7949 16 0 0
t$perf-norm:sp_client_order 21220 21220 0 0.00 0 5789 18 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 3850 8 0 0
t$perf-norm:sp_pay_from_customer 7063 7063 0 0.00 0 6733 10 0 0
t$perf-norm:sp_pay_to_supplier 5898 5898 0 0.00 0 4245 10 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 5709 11 0 0
t$perf-norm:sp_supplier_invoice 5607 5607 0 0.00 0 4986 14 0 0
t$perf-norm:sp_supplier_order 5603 5603 0 0.00 0 5115 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 200 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1668 27 0 0
t$perf-norm:srv_make_invnt_saldo 512 512 0 0.00 0 1278 15 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 2673 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 202 42 0 0
v_add_invoice_to_stock 10092 9986 106 1.05 0 4966 19 0 106
v_all_customers 38894 38894 0 0.00 0 86 1 0 0
v_all_suppliers 30948 30948 0 0.00 0 86 1 0 0
v_all_wares 42440 42440 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 5090 18 0 0
v_cancel_client_order 3496 3496 0 0.00 0 3288 6 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 1702 9 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2737 8 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 2960 10 0 0
v_cancel_supplier_invoice 792 786 6 0.75 0 226 11 0 6
v_cancel_supplier_order 800 800 0 0.00 0 3184 9 0 0
v_cancel_write_off 1916 1916 0 0.00 0 2597 18 0 0
v_max_id_clo_ord 5583 5583 0 0.00 0 2690 2 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 2428 17 0 0
v_max_id_ord_sup 5591 5591 0 0.00 0 8251 5 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 174 2 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 6699 5 0 0
v_min_id_clo_ord 5583 5583 0 0.00 0 2836 5 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 6153 17 0 0
v_min_id_ord_sup 5591 5591 0 0.00 0 6274 10 0 0
v_min_non_paid_invoice 5898 5898 0 0.00 0 4636 10 0 0
v_min_non_paid_realizn 7063 7063 0 0.00 0 6328 12 0 0
v_our_firm 10578 10578 0 0.00 0 17 1 0 0
v_reserve_write_off 19958 19958 0 0.00 0 3969 7 0 0
x_lock_depdocs_on_canc_invoice 10835 10835 0 0.00 0 25187 122 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 22284 456 0 0
x_make_qstorno_1000_1200 5603 5603 0 0.00 2 44782 1386 0 0
x_make_qstorno_1200_2000 5607 5607 0 0.00 3 83792 3340 0 0
x_make_qstorno_2100_3300 461042 461042 0 0.00 0 121461 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2020 07:37:57 to 26-12-2020 07:38:01.

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
05:07:12 4 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
05:08:12 40515 8192000000 9730068720 10023682048 796070736 8.856 100 87 174 1 825229376 8261824 20896816
05:09:58 18432 8192000000 9766405888 10071851008 839234784 9.292 100 88 176 2 873770816 13586240 20949792
05:11:24 20471 8192000000 9813729264 10129346560 867764592 9.578 100 88 176 2 911698016 23754560 20178864
05:12:50 18683 8192000000 9868457168 10198831104 910120112 9.998 100 78 157 1 971378576 44533424 16725040
05:14:11 7474 8192000000 9916486704 10258468864 956927168 10.459 100 72 144 1 1028587760 56585568 15075024
05:15:20 7111 8192000000 9955985888 10310877184 996317888 10.843 100 74 148 0 1074337392 63215168 14804336
05:16:28 3397 8192000000 9998484624 10363142144 1027664848 11.146 100 79 158 1 1109362688 65355888 16341952
05:17:34 1414 8192000000 10034375312 10398814208 1048615568 11.347 100 94 188 2 1142853616 75424528 18813520
05:18:36 7385 8192000000 10018116000 10422652928 1062560848 11.481 100 74 149 0 1145115008 66919088 15635072
05:19:44 14585 8192000000 9967142976 10361696256 1063178544 11.487 100 29 59 2 1089344496 20085808 6080144
05:21:01 4588 8192000000 9981928896 10375831552 1078730480 11.635 100 35 71 2 1105169968 18913424 7526064
05:22:07 4822 8192000000 10011696544 10410778624 1086393664 11.708 100 23 47 2 1113738448 22591760 4753024
05:23:14 7974 8192000000 10005094576 10405224448 1092963648 11.771 100 42 83 0 1126567392 25503728 8100016
05:24:26 31378 8192000000 10025896656 10409521152 1098022160 11.819 100 67 136 4 1126364464 13728912 14613392
05:25:57 6267 8192000000 10028719536 10428661760 1099460144 11.833 100 40 80 2 1138232080 30572336 8199600
05:27:06 8231 8192000000 10014913056 10423951360 1097745840 11.816 100 31 62 2 1126768496 21532816 7489840
05:28:15 8729 8192000000 10007035696 10399809536 1080200640 11.649 99 21 42 1 1107934480 23290128 4443712
05:29:24 24225 8192000000 9970097184 10378424320 1035061072 11.217 96 23 45 1 1061933424 21825264 5047088
05:30:53 6193 8192000000 9940363024 10321870848 1008551120 10.961 98 40 78 2 1026691456 9163344 8976992
05:32:02 7231 8192000000 9940999936 10328506368 1005415744 10.931 99 48 96 3 1030558544 13552288 11590512
05:33:11 9958 8192000000 9919712256 10301931520 1009939600 10.975 99 40 79 2 1032619632 13878912 8801120
05:34:22 29955 8192000000 9942932880 10332758016 1023434944 11.105 99 22 44 3 1044116880 15862288 4819648
05:35:55 6195 8192000000 9972500368 10363822080 1053235648 11.392 100 48 92 4 1075929104 12426544 10266912
05:37:03 7058 8192000000 9984337280 10378629120 1069440416 11.547 100 31 62 6 1088062080 11969936 6651728
05:38:21 85075 8192000000 10032698384 10434396160 1094374144 11.784 100 32 64 1 1142296288 41217920 6704224
05:40:52 16239 8192000000 10062054032 10469965824 1112056288 11.952 100 36 71 0 1174037840 55066896 6914656
05:42:18 14277 8192000000 10127655184 10531684352 1130834864 12.129 100 51 102 3 1218336224 76712560 10788800
05:43:39 14627 8192000000 10167083536 10565824512 1142476144 12.239 100 57 114 2 1270076512 115352032 12248336
05:44:58 17610 8192000000 10215087552 10622132224 1155340944 12.360 100 47 94 2 1317641728 151555296 10745488
05:46:42 19000 8192000000 10247423488 10659442688 1168868352 12.486 100 61 122 2 1352491600 169451296 14171952
05:48:03 8627 8192000000 10294649472 10712002560 1179144864 12.582 100 53 106 0 1402208560 210019248 13044448
05:49:16 2820 8192000000 10350113648 10758615040 1176466912 12.557 100 70 140 1 1454360864 261580656 16313296
05:50:24 8395 8192000000 10343203744 10767572992 1172689952 12.522 99 71 141 2 1440066672 250282560 17094160
05:51:38 13181 8192000000 10331676336 10752237568 1186212800 12.648 100 53 104 3 1448021936 249422112 12387024
05:52:58 4193 8192000000 10396427616 10802311168 1189099536 12.675 100 78 155 1 1497549824 290825424 17624864
05:54:06 2672 8192000000 10426195440 10834919424 1191566688 12.698 100 75 146 1 1526791520 318264304 16960528
05:55:16 2522 8192000000 10477329312 10893717504 1180130352 12.591 100 86 173 0 1577273760 376169504 20973904
05:56:24 3825 8192000000 10479986256 10882920448 1161483424 12.417 98 87 175 2 1569714976 387739840 20491712
05:57:32 2018 8192000000 10487664608 10898112512 1166212560 12.461 100 71 142 1 1582400496 399423104 16764832
05:58:43 3176 8192000000 10538953872 10955595776 1169808352 12.495 100 70 135 0 1636539760 449875648 16855760
05:59:52 4370 8192000000 10539761104 10963251200 1170215840 12.499 100 56 112 0 1636934112 452024640 14693632
06:01:00 3419 8192000000 10531618176 10962685952 1166676048 12.466 99 68 137 1 1631393872 446945632 17772192
06:02:06 20321 8192000000 10451086848 10904633344 1178218336 12.574 100 37 74 1 1565484640 377933056 9333248
06:03:39 19281 8192000000 10499077936 10950447104 1129006320 12.112 98 33 66 0 1588042256 449890848 9145088
06:05:18 18145 8192000000 10520704176 11051929600 1118384928 12.012 99 23 46 4 1622555120 497239072 6931120
06:06:40 26325 8192000000 10309766576 10843619328 1115110544 11.981 99 25 50 0 1500023984 378360768 6552672
06:08:25 172323 8192000000 10058400976 10542039040 993989488 10.820 88 12 24 0 1054260464 57811152 2459824
06:12:25 61496 8192000000 10081543232 10542387200 1111505952 11.947 99 14 28 2 1163806976 49244128 3056896
06:14:32 9028 8192000000 10115947504 10534117376 1125208400 12.076 100 60 119 1 1210940032 71790528 13941104
06:15:41 71633 8192000000 10082198464 10566950912 1100256768 11.840 98 15 29 1 1157648144 54012016 3379360
06:18:07 94025 8192000000 10059800240 10493206528 1094461952 11.785 98 22 44 1 1120891680 21888656 4541072
06:20:48 46444 8192000000 10117809376 10546401280 1125371584 12.078 100 25 50 0 1206016224 75247936 5396704
06:22:41 59546 8192000000 10057836064 10538115072 1109981968 11.932 99 33 66 3 1182636896 64473712 8181216
06:24:41 116788 8192000000 10061553008 10518941696 1109391856 11.927 99 14 28 0 1156869568 44326928 3150784
06:27:40 75633 8192000000 10132341424 10577498112 1122356896 12.049 99 17 33 1 1232803776 106277568 4169312
06:30:03 52855 8192000000 10048543680 10514481152 1115517344 11.985 99 20 40 0 1143636928 23138624 4980960
06:32:02 65331 8192000000 10084909712 10543206400 1124375360 12.068 99 15 30 0 1179605104 52069824 3159920
06:34:19 45627 8192000000 10062912832 10520596480 1100676000 11.844 97 20 39 1 1137508784 32471872 4360912
06:36:04 40753 8192000000 10068379072 10508541952 1109465840 11.927 98 17 34 1 1143805456 30444912 3894704
06:37:49 18173 8192000000 10092639840 10522656768 1124417792 12.069 99 43 85 2 1176876784 43587920 8871072
06:39:11 29793 8192000000 10115268832 10552328192 1123816640 12.063 98 27 54 0 1186366768 57208048 5342080
06:41:36 67865 8192000000 10058269056 10538020864 1101071008 11.848 98 15 30 1 1166022816 61584784 3367024
06:43:45 24924 8192000000 10063072784 10490507264 1094655728 11.787 98 35 70 1 1132109936 29877360 7576848
06:45:14 44597 8192000000 10063227872 10543742976 1089361760 11.737 97 22 44 1 1128766960 34963968 4441232
06:46:59 109540 8192000000 10056542288 10491633664 1111978512 11.951 98 11 22 0 1147336032 33285808 2071712
06:50:36 61684 8192000000 10044741168 10497921024 1112815456 11.959 98 7 14 0 1145642576 31519040 1308080
06:52:48 54997 8192000000 10050739504 10500616192 1085519312 11.700 96 11 23 2 1117684944 29893824 2271808
06:54:44 46089 8192000000 10059676336 10525900800 1076302688 11.612 96 20 40 0 1136349216 56044752 4001776
06:56:30 34905 8192000000 10082575584 10510278656 1083304416 11.679 97 29 58 2 1152245568 63249584 5691568
06:58:07 68768 8192000000 10044525792 10483417088 1082706720 11.673 97 25 49 0 1140018768 51879664 5432384
07:00:16 65179 8192000000 10081016032 10560245760 1112438752 11.956 99 17 34 0 1189776048 73785360 3551936
07:02:30 88272 8192000000 10084811488 10579988480 1106365088 11.898 98 16 32 1 1189391264 79653376 3372800
07:04:58 34537 8192000000 10080979920 10522906624 1093221888 11.773 97 18 36 0 1133947200 36998736 3726576
07:06:38 81188 8192000000 10042158160 10502729728 1120793232 12.034 100 28 53 4 1172038464 44306256 6938976
07:09:10 39385 8192000000 10045512192 10524688384 1117110288 12.000 99 5 10 1 1144244320 26315392 818640
07:10:50 39795 8192000000 10065178624 10504056832 1114605024 11.976 98 19 33 2 1154378416 36388928 3384464
07:12:37 70718 8192000000 10094561472 10571722752 1129308720 12.115 99 19 38 0 1201329024 67695136 4325168
07:14:54 76241 8192000000 10069422720 10591584256 1125521824 12.079 99 14 29 0 1197945392 69096832 3326736
07:17:10 40478 8192000000 10089143360 10542985216 1124266256 12.067 99 33 64 3 1173271840 41582384 7423200
07:18:58 53214 8192000000 10101649696 10581495808 1131973376 12.140 99 30 59 2 1210205360 72183008 6048976
07:20:58 40979 8192000000 10051502880 10499407872 1092944032 11.771 96 27 54 2 1116155136 17383680 5827424
07:22:44 40368 8192000000 10088298912 10555961344 1101710272 11.854 97 15 30 0 1154236912 49548912 2977728
07:24:34 84431 8192000000 10066205600 10527870976 1137025088 12.188 100 16 32 0 1167224464 26921616 3277760
07:27:10 64762 8192000000 10072885136 10564980736 1129265280 12.114 99 4 8 0 1179590032 49516224 808528
07:29:15 23206 8192000000 10070443200 10533847040 1125632640 12.080 99 21 42 3 1157532032 27226336 4673056
07:30:43 41732 8192000000 10082053712 10527444992 1114173200 11.972 99 33 66 1 1154817296 33240752 7403344
07:32:32 52435 8192000000 10055897136 10494861312 1099837200 11.836 98 18 36 0 1141064064 37321504 3905360
07:34:25 85641 8192000000 10053736752 10515189760 1074352592 11.594 95 25 50 0 1121815504 42052384 5410528
07:36:51 22558 8192000000 9007769088 9230450688 160459536 1.921 15 11 19 1 171251008 8952848 1838624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2020 07:38:03 to 26-12-2020 07:38:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 53 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-12-2020 07:38:19 to 26-12-2020 07:38:21.

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          233439
MON$OLDEST_ACTIVE               233440
MON$OLDEST_SNAPSHOT             233440
MON$NEXT_TRANSACTION            233440
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                       2101760
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             798
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2307 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.2307 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Dec 26 05:16:20 2020
:8:     INET/inet_error: read errno = 104
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Dec 26 07:37:18 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Dec 26 07:37:19 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

26.12.20 07:38:22. 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-26 07:38:25 No severe PSQL-related problems occured
26.12.20 07:38:26. End of report.