Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.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 16863000 kB
MemAvailable 31359592 kB
Buffers 0 kB
Cached 14694080 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-23 07:37:06
DTS_END                         2020-12-23 07:37:06.4650
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/47664
TRN_ID                          236792
ATT_ID                          677
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: 8731.43 22245 1047772
customer order: creation 172.43 423 20691
customer order: refuse 14.72 314 1766
order to supplier: creation 45.20 1340 5424
order to supplier: removal 3.25 111860 390
invoice (draft): creation 46.34 3854 5561
invoice (draft): removal 49.42 932 5930
invoice accept: apply 41.08 17701 4929
invoice accept: cancel 43.93 906 5271
customer reserve: creation 3721.29 177 446555
customer reserve: removal 4308.44 67 517013
realization accept: apply 82.99 158 9959
realization accept: cancel 74.75 109 8970
payment from customer: creation 58.25 101 6990
payment from customer: removal 5.59 76 671
payment to supplier: creation 49.49 142 5939
payment to supplier: removal 3.58 66 430
service: total inventory turnovers 3.75 3051 450
service: total monetary turnovers 5.98 561 717
service: aggregate perf. data 0.93 14217 112
service: refresh index statistics 0.03 288848 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-12-2020 07:37:55 to 23-12-2020 07:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7399
TEST_TIME 2 7686
TEST_TIME 3 7607
TEST_TIME 4 7796
TEST_TIME 5 7880
TEST_TIME 6 7308
TEST_TIME 7 7223
TEST_TIME 8 6996
TEST_TIME 9 6864
TEST_TIME 10 7180
TEST_TIME 11 7516
TEST_TIME 12 7333
TEST_TIME 13 7318
TEST_TIME 14 7311
TEST_TIME 15 7344
TEST_TIME 16 7890
TEST_TIME 17 7992
TEST_TIME 18 8772
TEST_TIME 19 8894
TEST_TIME 20 8893
TEST_TIME 21 8756
TEST_TIME 22 9026
TEST_TIME 23 9679
TEST_TIME 24 10546
TEST_TIME 25 11437
TEST_TIME 26 11391
TEST_TIME 27 11034
TEST_TIME 28 10710
TEST_TIME 29 10515
TEST_TIME 30 10271
TEST_TIME 31 10262
TEST_TIME 32 10153
TEST_TIME 33 9980
TEST_TIME 34 9839
TEST_TIME 35 9656
TEST_TIME 36 9445
TEST_TIME 37 9404
TEST_TIME 38 9417
TEST_TIME 39 9454
TEST_TIME 40 9429
TEST_TIME 41 9379
TEST_TIME 42 9388
TEST_TIME 43 9339
TEST_TIME 44 9386
TEST_TIME 45 9340
TEST_TIME 46 9344
TEST_TIME 47 9521
TEST_TIME 48 9459
TEST_TIME 49 9405
TEST_TIME 50 9399
TEST_TIME 51 9261
TEST_TIME 52 9157
TEST_TIME 53 9045
TEST_TIME 54 8921
TEST_TIME 55 8839
TEST_TIME 56 8906
TEST_TIME 57 8974
TEST_TIME 58 9042
TEST_TIME 59 9033
TEST_TIME 60 8965
TEST_TIME 61 8952
TEST_TIME 62 8932
TEST_TIME 63 9091
TEST_TIME 64 9071
TEST_TIME 65 9169
TEST_TIME 66 9134
TEST_TIME 67 9164
TEST_TIME 68 9224
TEST_TIME 69 9190
TEST_TIME 70 9089
TEST_TIME 71 9040
TEST_TIME 72 9001
TEST_TIME 73 9000
TEST_TIME 74 8953
TEST_TIME 75 8900
TEST_TIME 76 8879
TEST_TIME 77 8868
TEST_TIME 78 8941
TEST_TIME 79 9013
TEST_TIME 80 9019
TEST_TIME 81 9034
TEST_TIME 82 9135
TEST_TIME 83 9121
TEST_TIME 84 9134
TEST_TIME 85 9155
TEST_TIME 86 9103
TEST_TIME 87 9020
TEST_TIME 88 8972
TEST_TIME 89 8946
TEST_TIME 90 8929
TEST_TIME 91 8882
TEST_TIME 92 8839
TEST_TIME 93 8819
TEST_TIME 94 8872
TEST_TIME 95 8897
TEST_TIME 96 8895
TEST_TIME 97 8847
TEST_TIME 98 8844
TEST_TIME 99 8887
TEST_TIME 100 8880
TEST_TIME 101 8874
TEST_TIME 102 8988
TEST_TIME 103 8998
TEST_TIME 104 8989
TEST_TIME 105 8912
TEST_TIME 106 8839
TEST_TIME 107 8842
TEST_TIME 108 8792
TEST_TIME 109 8758
TEST_TIME 110 8760
TEST_TIME 111 8759
TEST_TIME 112 8755
TEST_TIME 113 8762
TEST_TIME 114 8748
TEST_TIME 115 8731
TEST_TIME 116 8724
TEST_TIME 117 8810
TEST_TIME 118 8832
TEST_TIME 119 8805
TEST_TIME 120 8736

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-12-2020 07:37:59 to 23-12-2020 07:38:03.

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 1461972 1461972 0 0.00 0 23614 7 0 0
doc_list_biud 1461971 1461971 0 0.00 0 90447 30 0 0
sp_add_invoice_to_stock 5022 4929 93 1.85 32 201574 17702 0 93
sp_cancel_adding_invoice 5271 5271 0 0.00 5 74044 907 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 9494 314 0 0
sp_cancel_customer_reserve 517013 517013 0 0.00 1 23710 68 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 2934 77 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 1820 67 0 0
sp_cancel_supplier_invoice 5938 5930 8 0.13 2 90486 932 0 8
sp_cancel_supplier_order 390 390 0 0.00 6 1104503 111861 0 0
sp_cancel_write_off 8970 8970 0 0.00 1 9808 109 0 0
sp_client_order 20691 20691 0 0.00 4 71129 424 0 0
sp_customer_reserve 446555 446555 0 0.00 0 54457 177 0 0
sp_fill_shopping_cart 31669 31669 0 0.00 1 36152 49 0 0
sp_fill_shopping_cart_clo_res 446552 446552 0 0.00 0 11610 16 0 0
sp_get_clo_for_invoice 4923 4923 0 0.00 0 33894 429 0 0
sp_kill_cost_storno 15342 15342 0 0.00 0 6244 55 0 0
sp_kill_qstorno_ret_qs2qd 1049316 1049316 0 0.00 0 90445 37 0 0
sp_kill_qty_storno 554222 554222 0 0.00 0 90447 79 0 0
sp_lock_selected_doc 20622 20622 0 0.00 0 7918 4 0 0
sp_make_cost_storno 27811 27811 0 0.00 0 13512 55 0 0
sp_multiply_rows_for_pdistr 27811 27811 0 0.00 0 11658 32 0 0
sp_multiply_rows_for_qdistr 20691 20691 0 0.00 0 71121 255 0 0
sp_pay_from_customer 6990 6990 0 0.00 4 20299 102 0 0
sp_pay_to_supplier 5939 5939 0 0.00 5 13390 142 0 0
sp_payment_common 12929 12929 0 0.00 1 7742 20 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 9456 293 0 0
sp_qd_handle_on_invoice_upd_sts 10194 10194 0 0.00 0 16407 360 0 0
sp_qd_handle_on_reserve_upd_sts 9959 9959 0 0.00 0 7714 40 0 0
sp_reserve_write_off 9959 9959 0 0.00 7 15269 158 0 0
sp_supplier_invoice 5561 5561 0 0.00 5 88856 3854 0 0
sp_supplier_order 5424 5424 0 0.00 6 31321 1341 0 0
srv_aggregate_perf_data 112 112 0 0.00 2704 126967 14217 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1645 139655 47185 0 0
srv_find_qd_qs_mism 1001086 1001086 0 0.00 0 23613 6 0 0
srv_make_invnt_saldo 450 450 0 0.00 30 128753 3051 0 0
srv_make_money_saldo 717 717 0 0.00 0 12984 562 0 0
srv_recalc_idx_stat 4 4 0 0.00 158766 434300 288849 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4929 4929 0 0.00 0 21124 164 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 3773 86 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 5326 16 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 869 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 3320 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 667 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2810 24 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 34779 1485 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 1138 3 0 0
t$perf-norm:sp_client_order 20691 20691 0 0.00 0 9399 16 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 6965 14 0 0
t$perf-norm:sp_pay_from_customer 6990 6990 0 0.00 0 6595 7 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 5442 10 0 0
t$perf-norm:sp_reserve_write_off 9959 9959 0 0.00 0 5694 10 0 0
t$perf-norm:sp_supplier_invoice 5561 5561 0 0.00 0 8891 20 0 0
t$perf-norm:sp_supplier_order 5424 5424 0 0.00 0 18726 19 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 103 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2359 52 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 546 8 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 3441 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10030 9844 186 1.85 0 6167 16 0 186
v_all_customers 37632 37632 0 0.00 0 34 1 0 0
v_all_suppliers 30358 30358 0 0.00 0 15 1 0 0
v_all_wares 41382 41382 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2965 10 0 0
v_cancel_client_order 3532 3532 0 0.00 0 1710 4 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2537 10 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1280 4 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 1814 11 0 0
v_cancel_supplier_invoice 858 842 16 1.86 0 374 11 0 16
v_cancel_supplier_order 780 780 0 0.00 0 1408 6 0 0
v_cancel_write_off 1906 1906 0 0.00 0 2799 15 0 0
v_max_id_clo_ord 5418 5418 0 0.00 0 3954 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 3788 18 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 7064 11 0 0
v_max_non_paid_invoice 1738 1738 0 0.00 0 862 2 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 2915 4 0 0
v_min_id_clo_ord 5418 5418 0 0.00 0 1429 4 0 0
v_min_id_clo_res 993 993 0 0.00 0 1217 4 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 7127 15 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 5315 14 0 0
v_min_non_paid_realizn 6990 6990 0 0.00 0 6107 13 0 0
v_our_firm 10268 10268 0 0.00 0 23 1 0 0
v_reserve_write_off 19918 19918 0 0.00 0 3177 7 0 0
x_lock_depdocs_on_canc_invoice 10780 10780 0 0.00 0 34970 115 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 14576 399 0 0
x_make_qstorno_1000_1200 5424 5424 0 0.00 2 31271 1272 0 0
x_make_qstorno_1200_2000 5561 5561 0 0.00 2 87158 3649 0 0
x_make_qstorno_2100_3300 446555 446555 0 0.00 0 54438 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2020 07:38:04 to 23-12-2020 07:38:08.

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:10 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
05:08:12 25108 8192000000 9716737008 10013503488 793244448 8.828 100 77 152 1 818907344 7188992 18473904
05:09:52 25573 8192000000 9764725536 10072793088 835071424 9.250 100 86 172 1 868824560 13294288 20458848
05:11:21 13310 8192000000 9796291472 10115162112 860243616 9.503 100 81 161 0 911038240 32451504 18343120
05:12:38 17469 8192000000 9870378400 10203869184 920939344 10.105 100 76 152 1 978748448 41660848 16148256
05:14:01 8893 8192000000 9924925824 10271653888 965905824 10.547 100 78 154 2 1035896080 53769216 16221040
05:15:12 5678 8192000000 9966641424 10321772544 994870128 10.829 100 77 154 0 1077075488 66596112 15609248
05:16:19 3406 8192000000 10006663792 10368282624 1018206784 11.055 100 87 174 1 1127754176 92463024 17084368
05:17:25 2080 8192000000 10023107056 10387259392 1035967456 11.226 100 95 191 0 1129684320 74154192 19562672
05:18:28 39476 8192000000 10002475248 10381971456 1056828128 11.426 100 59 118 0 1147692768 77622032 13242608
05:20:08 5434 8192000000 9988129856 10377035776 1070310288 11.555 100 44 89 0 1108055712 28096144 9649280
05:21:16 5065 8192000000 9986073808 10382458880 1073687504 11.587 100 43 80 6 1098278160 15985136 8605520
05:22:22 5205 8192000000 9997411392 10395746304 1081063936 11.658 100 49 99 0 1118678416 26315808 11298672
05:23:28 58585 8192000000 9998946560 10404749312 1083778832 11.683 100 27 54 1 1109572496 19681488 6112176
05:25:26 7361 8192000000 10001207120 10404945920 1095968576 11.799 100 38 76 2 1128048464 24074592 8005296
05:26:34 12830 8192000000 10002081232 10417942528 1097420064 11.813 100 37 74 2 1135295568 30046928 7828576
05:27:48 5112 8192000000 9972319280 10352881664 1044440816 11.307 97 34 64 3 1070099424 18161312 7497296
05:28:55 26024 8192000000 9981612784 10379517952 1040942944 11.274 98 32 64 1 1070914768 23094400 6877424
05:30:22 7974 8192000000 9937788464 10317946880 989907568 10.781 96 37 74 0 1019939008 21245600 8785840
05:31:33 6518 8192000000 9952815776 10331049984 999033920 10.869 98 40 80 3 1048044352 40302592 8707840
05:32:40 4888 8192000000 9942646848 10327826432 1027340688 11.143 99 47 95 1 1051549296 13152992 11055616
05:34:18 11752 8192000000 9957976816 10359414784 1050443120 11.365 100 25 50 2 1074129632 18101152 5585360
05:35:30 8013 8192000000 9994780720 10393124864 1066903616 11.523 100 45 91 2 1088708336 11868336 9936384
05:36:38 6562 8192000000 9982387072 10374975488 1075355088 11.603 100 40 77 3 1095297392 11149152 8793152
05:37:49 58232 8192000000 10035636864 10439872512 1104316384 11.879 100 32 64 3 1149425632 39142816 5966432
05:39:52 13640 8192000000 10081703616 10492342272 1121999200 12.046 100 53 104 2 1194536624 60764960 11772464
05:41:15 3879 8192000000 10114443280 10519945216 1144114400 12.254 100 68 136 0 1217580640 59606448 13859792
05:42:27 3935 8192000000 10136082128 10544308224 1157553904 12.380 100 58 114 1 1250613472 80831264 12228304
05:43:38 8458 8192000000 10183537264 10592780288 1153660768 12.344 99 57 114 3 1284759536 118886832 12211936
05:44:49 24615 8192000000 10200342992 10618318848 1170315824 12.500 100 44 88 3 1326510416 145839120 10355472
05:46:16 11845 8192000000 10253716496 10668220416 1178729248 12.578 100 57 113 2 1362756000 170335920 13690832
05:47:34 5012 8192000000 10293235568 10712039424 1186213280 12.648 100 76 151 2 1397465104 193773440 17478384
05:48:43 10256 8192000000 10282360832 10693091328 1183699760 12.625 100 58 115 3 1386756160 190496208 12560192
05:49:56 6333 8192000000 10339112576 10752638976 1185456640 12.641 100 68 135 0 1447604288 246695936 15451712
05:51:07 1750 8192000000 10408421888 10813997056 1193750144 12.718 100 87 174 2 1501392304 287172672 20469488
05:52:12 4439 8192000000 10413115408 10819821568 1154940832 12.356 98 72 144 1 1492841440 320805824 17094784
05:53:23 1643 8192000000 10445474080 10858012672 1164408304 12.445 100 76 148 1 1542185808 359717088 18060416
05:54:31 3240 8192000000 10442461536 10842877952 1153807056 12.345 99 86 166 1 1528435776 354336368 20292352
05:55:40 15756 8192000000 10402197792 10857140224 1135449600 12.173 99 39 78 1 1494232944 348518656 10264688
05:57:08 24753 8192000000 10387662320 10839486464 1153958048 12.347 100 30 59 1 1495919216 334098704 7862464
05:58:38 15458 8192000000 10455288736 10891784192 1132584432 12.146 99 34 68 1 1537757296 395972464 9200400
06:00:24 18572 8192000000 10326744480 10755039232 1097612544 11.815 98 24 48 0 1410503856 306161152 6730160
06:02:28 80928 8192000000 10046331376 10523881472 1087148528 11.716 97 10 20 0 1131712224 42215616 2348080
06:04:49 37887 8192000000 10032497360 10460061696 1098028000 11.819 98 39 78 1 1119293536 12993776 8271760
06:06:35 62380 8192000000 10072523936 10509881344 1103397120 11.870 98 23 47 1 1156842768 48345632 5100016
06:09:20 57220 8192000000 10031657328 10465599488 1096169984 11.801 97 6 12 0 1115670176 18241680 1258512
06:11:28 73291 8192000000 10060113744 10513555456 1111104832 11.943 98 15 30 0 1141833344 27690720 3037792
06:14:49 64990 8192000000 10060185536 10491412480 1088759920 11.731 97 14 28 0 1144082368 52101664 3220784
06:16:56 35135 8192000000 10066084544 10491002880 1116659632 11.995 99 39 78 2 1152952496 27743776 8549088
06:18:40 30243 8192000000 10082207344 10515259392 1134808288 12.167 100 23 46 1 1177747520 38450272 4488960
06:20:16 70280 8192000000 10099759440 10551312384 1134189200 12.161 99 17 33 1 1178324752 40391888 3743664
06:22:35 50236 8192000000 10118396976 10564358144 1151321248 12.322 100 24 48 0 1218607456 61603248 5682960
06:24:25 111266 8192000000 10080192832 10531524608 1112165568 11.953 97 12 24 1 1148564416 34164544 2234304
06:27:26 139621 8192000000 10078542672 10563153920 1147800976 12.289 100 22 44 0 1200970752 48577392 4592384
06:30:58 41538 8192000000 10095115456 10548781056 1139689328 12.213 100 6 12 0 1179450736 38507248 1254160
06:32:58 72189 8192000000 10119446992 10631213056 1110996400 11.942 97 25 49 2 1223975600 107362032 5617168
06:35:14 65454 8192000000 10051552416 10501562368 1112664160 11.958 98 19 37 2 1143033216 26225648 4143408
06:37:25 13567 8192000000 10057623648 10474807296 1107666912 11.910 99 66 131 3 1137707440 16121488 13919040
06:38:43 66010 8192000000 10127826592 10579730432 1111344864 11.945 99 18 36 0 1214838416 99755136 3738416
06:40:53 57728 8192000000 10084252528 10519183360 1071039696 11.562 95 31 62 5 1129520208 52179904 6300608
06:42:58 58218 8192000000 10044752480 10457415680 1055317104 11.412 94 36 72 0 1093110736 30135056 7658576
06:44:56 71827 8192000000 10067806336 10521300992 1086402384 11.708 98 34 67 3 1169824944 76796336 6626224
06:47:08 118132 8192000000 10064196112 10529267712 1092142544 11.763 98 20 40 1 1129274320 32846096 4285680
06:50:20 52413 8192000000 10044901888 10550185984 1091469680 11.757 98 3 6 0 1146877888 54864912 543296
06:52:15 40033 8192000000 10083878640 10520313856 1104692384 11.882 99 31 62 2 1153239856 42029840 6517632
06:54:07 39036 8192000000 10145606096 10585108480 1124330624 12.068 99 23 46 0 1229516864 100159408 5026832
06:55:48 71730 8192000000 10097394736 10562420736 1086316240 11.708 97 16 33 0 1166147664 76188672 3642752
06:58:11 58433 8192000000 10106992464 10589372416 1107273600 11.907 98 25 48 1 1194548112 82212240 5062272
07:00:10 48137 8192000000 10075064048 10515943424 1102198464 11.858 98 35 70 2 1156106272 46229968 7677840
07:02:00 19745 8192000000 10069602992 10525245440 1102225504 11.859 99 27 54 2 1172170688 64403888 5541296
07:03:23 139654 8192000000 10068280944 10515791872 1116027376 11.989 99 18 36 2 1160908336 40990400 3890560
07:06:57 50400 8192000000 10074035792 10550349824 1124393072 12.068 100 10 20 0 1182810688 56364432 2053184
07:08:47 51096 8192000000 10085904016 10521034752 1124831168 12.073 99 17 34 1 1178721328 50383696 3506464
07:10:40 88509 8192000000 10116318832 10562854912 1131244800 12.133 99 22 43 0 1234207440 98373104 4589536
07:13:09 36170 8192000000 10068871424 10522185728 1116511264 11.994 97 11 22 3 1140959088 22383136 2064688
07:14:52 29523 8192000000 10128414880 10568392704 1128260864 12.105 98 51 102 2 1191491088 53121648 10108576
07:16:23 49614 8192000000 10125517136 10587963392 1138782032 12.204 99 36 72 1 1212976272 67205392 6988848
07:18:21 21653 8192000000 10163917952 10597511168 1152048688 12.329 100 50 100 0 1246596928 84697168 9851072
07:19:44 77956 8192000000 10099774032 10583797760 1147248032 12.284 100 24 48 2 1220332624 68228000 4856592
07:22:02 130492 8192000000 10086017776 10536206336 1122929728 12.055 98 18 36 1 1158172736 31054432 4188576
07:25:14 37539 8192000000 10077169712 10548473856 1140030688 12.216 100 3 6 0 1186718784 46124896 563200
07:26:52 53835 8192000000 10059569808 10520879104 1094378960 11.784 97 18 36 1 1136877248 38492800 4005488
07:28:46 50830 8192000000 10074968544 10540044288 1110460512 11.937 99 20 40 0 1169415408 54537008 4417888
07:30:38 70635 8192000000 10073317680 10499489792 1074024160 11.590 95 30 60 1 1131324880 50933312 6367408
07:32:51 80190 8192000000 10133796560 10610155520 1100543024 11.843 98 20 40 3 1232272512 127000512 4728976
07:35:14 33701 8192000000 10051216048 10468622336 1090587552 11.748 98 30 60 3 1112681968 15205888 6888528
07:36:49 31216 8192000000 9400334464 9743462400 515059824 5.915 46 16 30 1 563924496 45733664 3131008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-12-2020 07:38:10 to 23-12-2020 07:38:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2020 07:38:25 to 23-12-2020 07:38:27.

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          238067
MON$OLDEST_ACTIVE               238068
MON$OLDEST_SNAPSHOT             238068
MON$NEXT_TRANSACTION            238068
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                       2083744
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             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2302 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.2302 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    Wed Dec 23 07:37:29 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

23.12.20 07:38:28. 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-23 07:38:31 No severe PSQL-related problems occured
23.12.20 07:38:32. End of report.