Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.01.2021 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 16874196 kB
MemAvailable 31251084 kB
Buffers 12 kB
Cached 14707344 kB
SwapCached 4864 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 2021-01-14 13:26:51
DTS_END                         2021-01-14 13:26:51.5610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51856
TRN_ID                          219268
ATT_ID                          592
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: 8615.04 23426 1033805
customer order: creation 157.24 439 18869
customer order: refuse 13.20 306 1584
order to supplier: creation 41.79 1362 5015
order to supplier: removal 3.21 115450 385
invoice (draft): creation 43.08 3535 5170
invoice (draft): removal 49.36 852 5923
invoice accept: apply 37.85 16956 4542
invoice accept: cancel 45.15 768 5418
customer reserve: creation 3525.15 165 423018
customer reserve: removal 4425.06 67 531007
realization accept: apply 76.93 159 9231
realization accept: cancel 79.02 118 9482
payment from customer: creation 53.11 101 6373
payment from customer: removal 5.24 71 629
payment to supplier: creation 45.60 133 5472
payment to supplier: removal 3.55 75 426
service: total inventory turnovers 3.84 2558 461
service: total monetary turnovers 5.84 450 701
service: aggregate perf. data 0.79 12692 95
service: refresh index statistics 0.03 312255 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-01-2021 13:28:12 to 14-01-2021 13:28:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2000
TEST_TIME 2 2717
TEST_TIME 3 3287
TEST_TIME 4 3431
TEST_TIME 5 4095
TEST_TIME 6 4357
TEST_TIME 7 4546
TEST_TIME 8 4696
TEST_TIME 9 4656
TEST_TIME 10 4713
TEST_TIME 11 5033
TEST_TIME 12 5098
TEST_TIME 13 5417
TEST_TIME 14 5348
TEST_TIME 15 5637
TEST_TIME 16 7481
TEST_TIME 17 7619
TEST_TIME 18 8171
TEST_TIME 19 8040
TEST_TIME 20 8078
TEST_TIME 21 8681
TEST_TIME 22 9137
TEST_TIME 23 9336
TEST_TIME 24 10554
TEST_TIME 25 10510
TEST_TIME 26 10331
TEST_TIME 27 10007
TEST_TIME 28 10121
TEST_TIME 29 9898
TEST_TIME 30 9715
TEST_TIME 31 9777
TEST_TIME 32 9617
TEST_TIME 33 9499
TEST_TIME 34 9384
TEST_TIME 35 9197
TEST_TIME 36 9052
TEST_TIME 37 8858
TEST_TIME 38 8823
TEST_TIME 39 8914
TEST_TIME 40 9206
TEST_TIME 41 9085
TEST_TIME 42 9170
TEST_TIME 43 9083
TEST_TIME 44 9264
TEST_TIME 45 9146
TEST_TIME 46 9091
TEST_TIME 47 9402
TEST_TIME 48 9386
TEST_TIME 49 9375
TEST_TIME 50 9299
TEST_TIME 51 9153
TEST_TIME 52 9030
TEST_TIME 53 9086
TEST_TIME 54 8974
TEST_TIME 55 8874
TEST_TIME 56 8823
TEST_TIME 57 8780
TEST_TIME 58 8783
TEST_TIME 59 8749
TEST_TIME 60 8802
TEST_TIME 61 8787
TEST_TIME 62 8837
TEST_TIME 63 8801
TEST_TIME 64 8997
TEST_TIME 65 9041
TEST_TIME 66 8993
TEST_TIME 67 9081
TEST_TIME 68 9049
TEST_TIME 69 9061
TEST_TIME 70 9241
TEST_TIME 71 9225
TEST_TIME 72 9099
TEST_TIME 73 9015
TEST_TIME 74 8952
TEST_TIME 75 8901
TEST_TIME 76 8856
TEST_TIME 77 8798
TEST_TIME 78 8767
TEST_TIME 79 8790
TEST_TIME 80 8755
TEST_TIME 81 8810
TEST_TIME 82 8785
TEST_TIME 83 8775
TEST_TIME 84 8768
TEST_TIME 85 8776
TEST_TIME 86 8759
TEST_TIME 87 8821
TEST_TIME 88 8864
TEST_TIME 89 8832
TEST_TIME 90 8832
TEST_TIME 91 8768
TEST_TIME 92 8735
TEST_TIME 93 8688
TEST_TIME 94 8655
TEST_TIME 95 8665
TEST_TIME 96 8661
TEST_TIME 97 8671
TEST_TIME 98 8672
TEST_TIME 99 8683
TEST_TIME 100 8686
TEST_TIME 101 8713
TEST_TIME 102 8720
TEST_TIME 103 8677
TEST_TIME 104 8815
TEST_TIME 105 8860
TEST_TIME 106 8822
TEST_TIME 107 8896
TEST_TIME 108 8854
TEST_TIME 109 8836
TEST_TIME 110 8775
TEST_TIME 111 8734
TEST_TIME 112 8682
TEST_TIME 113 8678
TEST_TIME 114 8635
TEST_TIME 115 8644
TEST_TIME 116 8647
TEST_TIME 117 8628
TEST_TIME 118 8608
TEST_TIME 119 8599
TEST_TIME 120 8619

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-01-2021 13:28:16 to 14-01-2021 13:28:19.

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 1426539 1426539 0 0.00 0 21244 6 0 0
doc_list_biud 1426538 1426538 0 0.00 0 48867 31 0 0
sp_add_invoice_to_stock 4684 4542 142 3.03 43 171534 16957 0 142
sp_cancel_adding_invoice 5418 5418 0 0.00 5 56736 768 0 0
sp_cancel_client_order 1584 1584 0 0.00 5 9505 306 0 0
sp_cancel_customer_reserve 531007 531007 0 0.00 1 48869 67 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 3704 71 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 6832 75 0 0
sp_cancel_supplier_invoice 5937 5923 14 0.23 2 16353 853 0 14
sp_cancel_supplier_order 385 385 0 0.00 7 916904 115450 0 0
sp_cancel_write_off 9482 9482 0 0.00 1 9317 118 0 0
sp_client_order 18869 18869 0 0.00 4 25902 439 0 0
sp_customer_reserve 423018 423018 0 0.00 0 45896 165 0 0
sp_fill_shopping_cart 29041 29041 0 0.00 1 19501 46 0 0
sp_fill_shopping_cart_clo_res 423012 423012 0 0.00 0 22285 20 0 0
sp_get_clo_for_invoice 4536 4536 0 0.00 0 27297 452 0 0
sp_kill_cost_storno 15955 15955 0 0.00 0 9309 53 0 0
sp_kill_qstorno_ret_qs2qd 1077804 1077804 0 0.00 0 48863 36 0 0
sp_kill_qty_storno 567566 567566 0 0.00 0 48866 77 0 0
sp_lock_selected_doc 19001 19001 0 0.00 0 7073 5 0 0
sp_make_cost_storno 25612 25612 0 0.00 0 14060 51 0 0
sp_multiply_rows_for_pdistr 25612 25612 0 0.00 0 7172 29 0 0
sp_multiply_rows_for_qdistr 18869 18869 0 0.00 0 25844 260 0 0
sp_pay_from_customer 6373 6373 0 0.00 4 14982 101 0 0
sp_pay_to_supplier 5472 5472 0 0.00 5 19183 134 0 0
sp_payment_common 11845 11845 0 0.00 1 9885 23 0 0
sp_qd_handle_on_cancel_clo 1584 1584 0 0.00 0 9500 285 0 0
sp_qd_handle_on_invoice_upd_sts 9954 9954 0 0.00 0 15801 349 0 0
sp_qd_handle_on_reserve_upd_sts 9231 9231 0 0.00 0 7543 42 0 0
sp_reserve_write_off 9231 9231 0 0.00 7 17923 159 0 0
sp_supplier_invoice 5170 5170 0 0.00 7 101848 3535 0 0
sp_supplier_order 5015 5015 0 0.00 5 43778 1363 0 0
srv_aggregate_perf_data 95 95 0 0.00 3022 63271 12692 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2257 169260 52867 0 0
srv_find_qd_qs_mism 990611 990611 0 0.00 0 16729 5 0 0
srv_make_invnt_saldo 461 461 0 0.00 65 155859 2559 0 0
srv_make_money_saldo 701 701 0 0.00 1 23682 450 0 0
srv_recalc_idx_stat 4 4 0 0.00 96298 657240 312256 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 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-norm:sp_add_invoice_to_stock 4542 4542 0 0.00 0 20073 146 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 4121 109 0 0
t$perf-norm:sp_cancel_client_order 1584 1584 0 0.00 0 1025 9 0 0
t$perf-norm:sp_cancel_customer_reserve 589 589 0 0.00 0 5654 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 987 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 13707 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 4896 29 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 57431 1441 0 0
t$perf-norm:sp_cancel_write_off 878 878 0 0.00 0 2256 10 0 0
t$perf-norm:sp_client_order 18869 18869 0 0.00 0 13771 18 0 0
t$perf-norm:sp_customer_reserve 916 916 0 0.00 0 9541 13 0 0
t$perf-norm:sp_pay_from_customer 6373 6373 0 0.00 0 4450 8 0 0
t$perf-norm:sp_pay_to_supplier 5472 5472 0 0.00 0 5051 6 0 0
t$perf-norm:sp_reserve_write_off 9231 9231 0 0.00 0 7269 10 0 0
t$perf-norm:sp_supplier_invoice 5170 5170 0 0.00 0 6558 14 0 0
t$perf-norm:sp_supplier_order 5015 5015 0 0.00 0 4550 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 12 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 105 2 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 699 6 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 559 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 7 0 0
v_add_invoice_to_stock 9354 9070 284 3.03 0 7032 16 0 284
v_all_customers 34418 34418 0 0.00 0 38 1 0 0
v_all_suppliers 28330 28330 0 0.00 0 13 1 0 0
v_all_wares 37738 37738 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 965 6 0 0
v_cancel_client_order 3168 3168 0 0.00 0 3329 4 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 827 5 0 0
v_cancel_customer_reserve 1178 1178 0 0.00 0 2650 6 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 1655 7 0 0
v_cancel_supplier_invoice 758 730 28 3.69 0 809 13 0 28
v_cancel_supplier_order 770 770 0 0.00 0 85 2 0 0
v_cancel_write_off 1756 1756 0 0.00 0 2806 12 0 0
v_max_id_clo_ord 5008 5008 0 0.00 0 2039 1 0 0
v_max_id_clo_res 916 916 0 0.00 0 2498 20 0 0
v_max_id_ord_sup 5162 5162 0 0.00 0 5913 8 0 0
v_max_non_paid_invoice 1477 1477 0 0.00 0 1412 3 0 0
v_max_non_paid_realizn 3379 3379 0 0.00 0 9879 8 0 0
v_min_id_clo_ord 5008 5008 0 0.00 0 2597 6 0 0
v_min_id_clo_res 916 916 0 0.00 0 8228 20 0 0
v_min_id_ord_sup 5162 5162 0 0.00 0 5750 10 0 0
v_min_non_paid_invoice 5472 5472 0 0.00 0 7762 15 0 0
v_min_non_paid_realizn 6373 6373 0 0.00 0 7298 17 0 0
v_our_firm 9308 9308 0 0.00 0 9 1 0 0
v_reserve_write_off 18460 18460 0 0.00 0 3231 4 0 0
x_lock_depdocs_on_canc_invoice 10976 10976 0 0.00 0 21687 100 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 8218 344 0 0
x_make_qstorno_1000_1200 5015 5015 0 0.00 2 43771 1295 0 0
x_make_qstorno_1200_2000 5170 5170 0 0.00 3 101838 3349 0 0
x_make_qstorno_2100_3300 423018 423018 0 0.00 0 45804 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-01-2021 13:28:20 to 14-01-2021 13:28:24.

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 5 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:57:56 16960 8192000000 9702215088 9992577024 781833424 8.712 100 88 174 1 811372256 7412400 22126432
10:59:20 23728 8192000000 9748728096 10050580480 824274816 9.142 100 93 186 0 858375728 11081696 23019216
11:00:50 14160 8192000000 9790357872 10105315328 859011744 9.490 100 74 148 2 899352352 23296144 17044464
11:02:08 12615 8192000000 9869957312 10200621056 925185872 10.147 100 84 169 2 981477600 38114944 18176784
11:03:28 12623 8192000000 9939513792 10280665088 964371728 10.532 100 77 153 0 1044115280 64117376 15626176
11:04:44 4368 8192000000 9969236096 10324209664 1002155904 10.899 100 83 166 1 1089363584 70223488 16984192
11:05:50 3552 8192000000 10002062288 10364805120 1028609600 11.155 100 87 172 1 1113879200 68486704 16782896
11:06:56 1914 8192000000 10031488032 10399129600 1052385008 11.384 100 94 188 1 1139410400 68197536 18827856
11:07:59 12122 8192000000 10005550384 10378153984 1060709136 11.463 100 69 138 1 1116792496 41117440 14965920
11:09:11 7055 8192000000 10006318112 10401521664 1069523120 11.548 100 41 83 1 1120251312 42386496 8341696
11:10:21 6903 8192000000 9996475728 10387238912 1078111040 11.629 100 37 73 0 1118555568 32353888 8090640
11:11:29 18684 8192000000 10014389888 10396966912 1086643584 11.711 100 79 159 4 1121933952 17035072 18255296
11:12:48 6333 8192000000 10028300048 10421555200 1083161264 11.678 100 45 91 0 1127858032 35071488 9625280
11:13:56 5771 8192000000 10028281088 10425724928 1090627168 11.749 100 50 98 3 1124612560 23856240 10129152
11:15:04 30533 8192000000 10018812672 10423472128 1081549440 11.662 99 15 30 1 1106147728 21532640 3065648
11:16:38 8458 8192000000 10031230080 10433392640 1099091232 11.829 100 29 58 2 1126457456 20966240 6399984
11:17:48 6724 8192000000 9997863104 10393964544 1069998944 11.552 99 37 72 2 1094204400 15899472 8305984
11:18:56 48628 8192000000 9956546160 10335764480 1017457504 11.047 97 48 101 0 1043578064 15933632 10186928
11:20:52 4336 8192000000 9926381712 10306826240 1007264896 10.949 99 50 96 2 1029635920 12085968 10285056
11:21:57 8629 8192000000 9955232096 10339540992 1030872080 11.177 100 46 92 1 1059567776 18286288 10409408
11:23:08 8680 8192000000 9968833744 10358874112 1036999504 11.236 100 43 84 1 1067045088 20277168 9768416
11:24:17 31157 8192000000 9961728528 10361196544 1050001616 11.361 100 44 88 1 1088537616 29733888 8802112
11:25:50 6875 8192000000 9978803952 10368237568 1056261552 11.421 100 27 54 1 1086124112 23398192 6464368
11:26:59 29645 8192000000 10006931584 10401959936 1081911920 11.666 100 34 67 1 1106002256 16796160 7294176
11:28:38 82878 8192000000 10065347952 10480689152 1104434752 11.880 100 41 82 1 1187640704 74260816 8945136
11:31:38 13731 8192000000 10148577008 10567442432 1128762000 12.110 100 50 100 1 1260431936 120413440 11256496
11:32:56 9605 8192000000 10170257296 10576322560 1147060592 12.282 100 58 116 1 1280390608 120245952 13084064
11:34:09 4358 8192000000 10223639360 10631266304 1156784000 12.373 100 56 110 2 1332072416 162571792 12716624
11:35:20 22840 8192000000 10244260576 10665017344 1168030016 12.478 100 43 86 1 1356456688 178352688 10073984
11:36:49 7270 8192000000 10327207728 10734354432 1183558528 12.623 100 70 140 2 1421240752 221709200 15973024
11:38:02 6096 8192000000 10354479296 10759589888 1171333424 12.509 99 61 122 0 1458416592 273061648 14021520
11:39:12 2256 8192000000 10411603424 10828783616 1186698480 12.653 100 76 150 1 1516016672 310200304 19117888
11:40:17 6518 8192000000 10409882704 10822602752 1169838944 12.495 100 64 128 1 1516607456 330426176 16342336
11:41:28 2580 8192000000 10489609376 10896158720 1144570128 12.258 97 77 154 1 1574730016 410901984 19257904
11:42:36 4141 8192000000 10440675040 10870513664 1169748944 12.494 100 78 155 0 1554458432 366877808 17831680
11:43:52 9747 8192000000 10378201040 10808815616 1165455376 12.454 99 46 92 1 1468613248 291730864 11427008
11:45:06 30786 8192000000 10352473552 10784591872 1142834064 12.242 99 25 50 0 1434948288 285392928 6721296
11:47:06 9474 8192000000 10399387408 10832171008 1138258016 12.199 99 29 58 0 1494424688 348625168 7541504
11:48:18 26155 8192000000 10311674640 10743726080 1088609840 11.729 96 24 48 1 1378743696 284098048 6035808
11:49:48 32087 8192000000 10287751312 10728157184 1063815600 11.493 95 22 44 1 1342965264 273645504 5504160
11:51:30 52667 8192000000 10059170400 10477854720 1073730752 11.588 97 50 96 0 1111985680 28037520 10217408
11:53:24 37459 8192000000 10069272880 10484338688 1069821184 11.550 97 37 74 0 1154256320 76271584 8163552
11:55:03 72114 8192000000 10038791712 10469769216 1017815216 11.051 95 10 20 1 1081696800 62028336 1853248
11:57:15 81563 8192000000 10033582864 10474221568 1077025936 11.619 99 21 42 1 1117099072 35473904 4599232
12:00:03 71309 8192000000 10004164032 10440749056 1045260928 11.315 95 18 36 0 1073830960 25175440 3394592
12:02:17 61579 8192000000 10102499552 10543218688 1098711744 11.825 99 27 54 3 1191791552 87022464 6057344
12:04:41 111826 8192000000 10060593456 10635653120 1091434432 11.756 98 13 26 1 1238331312 143763328 3133552
12:07:42 77739 8192000000 10086081232 10546638848 1121296864 12.039 100 19 38 0 1204741536 79589424 3855248
12:10:05 90128 8192000000 10086081168 10517925888 1125278384 12.077 99 27 54 0 1185274496 54486160 5509952
12:12:37 88521 8192000000 10088530976 10585243648 1136922064 12.187 100 16 32 1 1238399392 97909680 3567648
12:15:11 60914 8192000000 10068114368 10494361600 1115816672 11.987 98 31 62 3 1147657744 26079680 5761392
12:17:17 71936 8192000000 10089760800 10526797824 1141181120 12.227 100 28 56 0 1202933488 56109408 5642960
12:19:35 31233 8192000000 10056879088 10546462720 1135874528 12.177 100 11 22 1 1175145584 36153392 3117664
12:21:26 73055 8192000000 10055229424 10523652096 1144344528 12.256 100 9 18 0 1177286080 30885600 2055952
12:23:50 55186 8192000000 10106178752 10567258112 1124851872 12.073 98 17 34 1 1196409936 67936432 3621632
12:25:46 61998 8192000000 10127688832 10598793216 1149376272 12.304 100 17 34 0 1207411360 54605136 3429952
12:27:49 45534 8192000000 10125487536 10585755648 1142904560 12.243 100 24 48 0 1244301600 96040656 5356384
12:29:35 60923 8192000000 10136547664 10647642112 1148352288 12.294 100 20 40 1 1252403568 99717328 4333952
12:31:47 55148 8192000000 10083715040 10516623360 1101403872 11.851 97 12 22 5 1161573200 57475824 2693504
12:33:46 46223 8192000000 10101641744 10529439744 1110048080 11.933 97 30 57 1 1169858912 53480000 6330832
12:35:33 37010 8192000000 10170368576 10610393088 1138779328 12.204 100 34 68 0 1262293392 116817616 6696448
12:37:12 169259 8192000000 10060779168 10510184448 1126175216 12.085 99 21 42 1 1157076704 26330832 4570656
12:41:09 35888 8192000000 10064413296 10529984512 1130811024 12.129 100 4 8 0 1158990304 27400608 778672
12:42:46 48345 8192000000 10083590320 10565861376 1123843696 12.063 99 9 18 0 1170658336 44683008 2131632
12:44:37 65611 8192000000 10071442720 10545442816 1105601952 11.891 97 13 26 1 1163392080 54765360 3024768
12:46:49 49643 8192000000 10044536384 10480525312 1057801104 11.435 94 23 46 0 1111902848 48810368 5291376
12:48:45 48656 8192000000 10034611344 10461769728 1043653840 11.300 96 38 76 3 1095412736 44095248 7663648
12:50:35 94830 8192000000 10057907360 10497310720 1042579360 11.289 96 18 36 0 1122850448 76378304 3892784
12:53:14 61238 8192000000 10071658592 10491432960 1075161472 11.601 99 29 58 0 1129640880 48658624 5820784
12:55:17 30707 8192000000 10046797328 10467356672 1061767456 11.473 97 31 59 1 1102795728 34671632 6356640
12:56:49 84938 8192000000 10041600944 10491117568 1090085392 11.743 99 16 32 0 1143728048 50289392 3353264
12:59:51 50843 8192000000 10023202192 10484436992 1071132560 11.563 97 7 14 0 1104090608 31703664 1254384
13:02:35 43741 8192000000 10101953360 10573475840 1111711216 11.949 99 23 46 0 1173017712 56262992 5043504
13:04:19 76479 8192000000 10094604752 10551328768 1115597280 11.985 99 13 26 0 1201893936 83531472 2765184
13:06:41 86897 8192000000 10132404288 10581397504 1136661792 12.184 100 13 26 1 1175714800 36211520 2841488
13:09:08 34270 8192000000 10158430352 10584281088 1145978352 12.272 100 44 86 1 1237230336 81779184 9472800
13:10:52 122621 8192000000 10115128032 10572812288 1151257280 12.321 100 7 14 1 1230524848 77677504 1590064
13:13:55 134074 8192000000 10074115680 10568941568 1101066256 11.848 96 14 25 1 1133425936 29378320 2981360
13:17:10 58257 8192000000 10068671936 10538938368 1136576304 12.183 99 7 14 0 1164664320 26545936 1542080
13:19:09 58208 8192000000 10082317936 10573361152 1149311648 12.303 100 11 22 2 1187558528 36015088 2231792
13:21:08 39170 8192000000 10077344896 10531602432 1148810928 12.298 100 18 36 2 1181011808 28500240 3700640
13:22:47 67105 8192000000 10099891520 10558767104 1123321440 12.058 97 21 40 1 1167965040 39985424 4658176
13:25:11 66865 8192000000 10137537984 10601041920 1147007168 12.281 100 21 42 1 1250276544 98549360 4720016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-01-2021 13:28:26 to 14-01-2021 13:28:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 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 14-01-2021 13:28:42 to 14-01-2021 13:28:44.

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          219267
MON$OLDEST_ACTIVE               225118
MON$OLDEST_SNAPSHOT             225118
MON$NEXT_TRANSACTION            225118
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                       2071776
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.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 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.2325 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Jan 14 13:26:59 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

14.01.21 13:28:45. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-01-14 13:28:47 No severe PSQL-related problems occured
14.01.21 13:28:49. End of report.