Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2021 07:39
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 16639736 kB
MemAvailable 31132900 kB
Buffers 0 kB
Cached 15075816 kB
SwapCached 3272 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-02-27 07:37:05
DTS_END                         2021-02-27 07:37:05.0590
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/50792
TRN_ID                          225875
ATT_ID                          631
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: 8583.25 30318 1029990
customer order: creation 153.37 443 18404
customer order: refuse 12.94 372 1553
order to supplier: creation 42.33 1466 5080
order to supplier: removal 3.37 124972 404
invoice (draft): creation 42.70 4013 5124
invoice (draft): removal 49.52 1097 5942
invoice accept: apply 38.83 19164 4660
invoice accept: cancel 44.44 990 5333
customer reserve: creation 3540.32 188 424838
customer reserve: removal 4390.59 74 526871
realization accept: apply 75.21 206 9025
realization accept: cancel 73.51 151 8821
payment from customer: creation 52.88 143 6345
payment from customer: removal 5.48 89 657
payment to supplier: creation 44.67 157 5360
payment to supplier: removal 3.04 102 365
service: total inventory turnovers 3.43 4156 412
service: total monetary turnovers 5.72 622 686
service: aggregate perf. data 0.88 14453 106
service: refresh index statistics 0.03 433492 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-02-2021 07:40:04 to 27-02-2021 07:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3238
TEST_TIME 2 4323
TEST_TIME 3 4551
TEST_TIME 4 4297
TEST_TIME 5 4438
TEST_TIME 6 4556
TEST_TIME 7 5020
TEST_TIME 8 5078
TEST_TIME 9 5091
TEST_TIME 10 5600
TEST_TIME 11 5705
TEST_TIME 12 6286
TEST_TIME 13 6252
TEST_TIME 14 6474
TEST_TIME 15 6521
TEST_TIME 16 6503
TEST_TIME 17 6821
TEST_TIME 18 7198
TEST_TIME 19 7548
TEST_TIME 20 7649
TEST_TIME 21 8137
TEST_TIME 22 8273
TEST_TIME 23 8210
TEST_TIME 24 8166
TEST_TIME 25 8374
TEST_TIME 26 8796
TEST_TIME 27 9471
TEST_TIME 28 10988
TEST_TIME 29 10911
TEST_TIME 30 10703
TEST_TIME 31 10683
TEST_TIME 32 10422
TEST_TIME 33 10241
TEST_TIME 34 10155
TEST_TIME 35 9981
TEST_TIME 36 9856
TEST_TIME 37 9698
TEST_TIME 38 9583
TEST_TIME 39 9436
TEST_TIME 40 9372
TEST_TIME 41 9369
TEST_TIME 42 9393
TEST_TIME 43 9356
TEST_TIME 44 9252
TEST_TIME 45 9304
TEST_TIME 46 9174
TEST_TIME 47 9211
TEST_TIME 48 9108
TEST_TIME 49 9089
TEST_TIME 50 9161
TEST_TIME 51 9132
TEST_TIME 52 9079
TEST_TIME 53 9147
TEST_TIME 54 9133
TEST_TIME 55 9139
TEST_TIME 56 9068
TEST_TIME 57 9021
TEST_TIME 58 9122
TEST_TIME 59 9121
TEST_TIME 60 9090
TEST_TIME 61 8968
TEST_TIME 62 8862
TEST_TIME 63 8828
TEST_TIME 64 8775
TEST_TIME 65 8716
TEST_TIME 66 8644
TEST_TIME 67 8667
TEST_TIME 68 8621
TEST_TIME 69 8712
TEST_TIME 70 8667
TEST_TIME 71 8663
TEST_TIME 72 8706
TEST_TIME 73 8691
TEST_TIME 74 8705
TEST_TIME 75 8737
TEST_TIME 76 8714
TEST_TIME 77 8782
TEST_TIME 78 8796
TEST_TIME 79 8893
TEST_TIME 80 8864
TEST_TIME 81 8818
TEST_TIME 82 8760
TEST_TIME 83 8682
TEST_TIME 84 8754
TEST_TIME 85 8687
TEST_TIME 86 8628
TEST_TIME 87 8658
TEST_TIME 88 8649
TEST_TIME 89 8622
TEST_TIME 90 8607
TEST_TIME 91 8588
TEST_TIME 92 8604
TEST_TIME 93 8574
TEST_TIME 94 8621
TEST_TIME 95 8719
TEST_TIME 96 8694
TEST_TIME 97 8740
TEST_TIME 98 8777
TEST_TIME 99 8767
TEST_TIME 100 8760
TEST_TIME 101 8704
TEST_TIME 102 8659
TEST_TIME 103 8659
TEST_TIME 104 8637
TEST_TIME 105 8590
TEST_TIME 106 8593
TEST_TIME 107 8684
TEST_TIME 108 8706
TEST_TIME 109 8702
TEST_TIME 110 8689
TEST_TIME 111 8667
TEST_TIME 112 8685
TEST_TIME 113 8679
TEST_TIME 114 8756
TEST_TIME 115 8786
TEST_TIME 116 8793
TEST_TIME 117 8763
TEST_TIME 118 8692
TEST_TIME 119 8652
TEST_TIME 120 8585

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-02-2021 07:40:08 to 27-02-2021 07:40:11.

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 1422653 1422653 0 0.00 0 34501 7 0 0
doc_list_biud 1422652 1422652 0 0.00 0 38786 35 0 0
sp_add_invoice_to_stock 4767 4660 107 2.24 28 187150 19164 0 107
sp_cancel_adding_invoice 5333 5333 0 0.00 5 90055 991 0 0
sp_cancel_client_order 1553 1553 0 0.00 5 12419 372 0 0
sp_cancel_customer_reserve 526871 526871 0 0.00 1 37141 75 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 6704 90 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 3 4357 102 0 0
sp_cancel_supplier_invoice 5953 5942 11 0.18 2 38256 1098 0 11
sp_cancel_supplier_order 404 404 0 0.00 11 1347322 124972 0 0
sp_cancel_write_off 8821 8821 0 0.00 1 16279 151 0 0
sp_client_order 18404 18404 0 0.00 4 27943 443 0 0
sp_customer_reserve 424838 424838 0 0.00 0 49552 188 0 0
sp_fill_shopping_cart 28570 28570 0 0.00 1 23651 54 0 0
sp_fill_shopping_cart_clo_res 424837 424837 0 0.00 0 27167 22 0 0
sp_get_clo_for_invoice 4659 4659 0 0.00 0 52263 546 0 0
sp_kill_cost_storno 15176 15176 0 0.00 0 16204 69 0 0
sp_kill_qstorno_ret_qs2qd 1068909 1068909 0 0.00 0 37089 41 0 0
sp_kill_qty_storno 562608 562608 0 0.00 0 38785 87 0 0
sp_lock_selected_doc 18971 18971 0 0.00 0 7461 5 0 0
sp_make_cost_storno 25389 25389 0 0.00 0 22857 71 0 0
sp_multiply_rows_for_pdistr 25389 25389 0 0.00 0 22689 41 0 0
sp_multiply_rows_for_qdistr 18404 18404 0 0.00 0 16364 243 0 0
sp_pay_from_customer 6345 6345 0 0.00 4 18094 144 0 0
sp_pay_to_supplier 5360 5360 0 0.00 5 22875 158 0 0
sp_payment_common 11705 11705 0 0.00 1 18080 32 0 0
sp_qd_handle_on_cancel_clo 1553 1553 0 0.00 0 12411 328 0 0
sp_qd_handle_on_invoice_upd_sts 9992 9992 0 0.00 0 38784 416 0 0
sp_qd_handle_on_reserve_upd_sts 9025 9025 0 0.00 0 11543 54 0 0
sp_reserve_write_off 9025 9025 0 0.00 7 20959 206 0 0
sp_supplier_invoice 5124 5124 0 0.00 6 115901 4013 0 0
sp_supplier_order 5080 5080 0 0.00 6 30216 1466 0 0
srv_aggregate_perf_data 106 106 0 0.00 2960 211929 14454 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1471 149142 49205 0 0
srv_find_qd_qs_mism 986283 986283 0 0.00 0 34501 6 0 0
srv_make_invnt_saldo 412 412 0 0.00 35 125475 4157 0 0
srv_make_money_saldo 686 686 0 0.00 1 15167 623 0 0
srv_recalc_idx_stat 4 4 0 0.00 213989 596813 433493 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4660 4660 0 0.00 0 25013 180 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 3201 98 0 0
t$perf-norm:sp_cancel_client_order 1553 1553 0 0.00 0 3879 17 0 0
t$perf-norm:sp_cancel_customer_reserve 600 600 0 0.00 0 819 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 2165 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 3511 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 644 12 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 27597 1644 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 3132 13 0 0
t$perf-norm:sp_client_order 18404 18404 0 0.00 0 14459 22 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 3832 13 0 0
t$perf-norm:sp_pay_from_customer 6345 6345 0 0.00 0 19633 14 0 0
t$perf-norm:sp_pay_to_supplier 5360 5360 0 0.00 0 5157 9 0 0
t$perf-norm:sp_reserve_write_off 9025 9025 0 0.00 0 19510 13 0 0
t$perf-norm:sp_supplier_invoice 5124 5124 0 0.00 0 4640 17 0 0
t$perf-norm:sp_supplier_order 5080 5080 0 0.00 0 12567 20 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 556 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 139 3 0 0
t$perf-norm:srv_make_invnt_saldo 412 412 0 0.00 0 371 5 0 0
t$perf-norm:srv_make_money_saldo 686 686 0 0.00 0 1414 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 52 14 0 0
v_add_invoice_to_stock 9532 9318 214 2.24 0 6911 20 0 214
v_all_customers 33366 33366 0 0.00 0 21 1 0 0
v_all_suppliers 27988 27988 0 0.00 0 129 1 0 0
v_all_wares 36808 36808 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 4618 16 0 0
v_cancel_client_order 3106 3106 0 0.00 0 3393 7 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 868 6 0 0
v_cancel_customer_reserve 1200 1200 0 0.00 0 1902 7 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 1912 10 0 0
v_cancel_supplier_invoice 814 792 22 2.70 0 3756 20 0 22
v_cancel_supplier_order 808 808 0 0.00 0 1142 5 0 0
v_cancel_write_off 1850 1850 0 0.00 0 2679 16 0 0
v_max_id_clo_ord 5058 5058 0 0.00 0 619 1 0 0
v_max_id_clo_res 938 938 0 0.00 0 4390 23 0 0
v_max_id_ord_sup 5105 5105 0 0.00 0 5196 4 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 3224 6 0 0
v_max_non_paid_realizn 3479 3479 0 0.00 0 1825 4 0 0
v_min_id_clo_ord 5058 5058 0 0.00 0 10234 9 0 0
v_min_id_clo_res 938 938 0 0.00 0 6035 11 0 0
v_min_id_ord_sup 5105 5105 0 0.00 0 6637 14 0 0
v_min_non_paid_invoice 5360 5360 0 0.00 0 7069 17 0 0
v_min_non_paid_realizn 6345 6345 0 0.00 0 18077 29 0 0
v_our_firm 9174 9174 0 0.00 0 19 1 0 0
v_reserve_write_off 18050 18050 0 0.00 0 4460 7 0 0
x_lock_depdocs_on_canc_invoice 10879 10879 0 0.00 0 15717 141 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 8393 403 0 0
x_make_qstorno_1000_1200 5080 5080 0 0.00 1 30198 1378 0 0
x_make_qstorno_1200_2000 5124 5124 0 0.00 3 115892 3803 0 0
x_make_qstorno_2100_3300 424838 424838 0 0.00 0 48230 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2021 07:40:12 to 27-02-2021 07:40:16.

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:08 12 8192000000 8799338064 8810844160 5349520 0.065 2 1 2 0 5447136 61840 35776
05:08:09 31522 8192000000 9727945184 10027511808 808116672 8.978 100 89 178 1 837965120 7581680 22266768
05:09:45 25719 8192000000 9780892928 10090479616 849913040 9.399 100 86 172 0 882540080 12133200 20493840
05:11:21 11568 8192000000 9824561824 10146390016 895254848 9.851 100 82 164 0 936187408 22784912 18147648
05:12:43 12448 8192000000 9877846800 10214178816 929061648 10.185 100 78 154 1 988420368 42588912 16769808
05:13:57 9277 8192000000 9918611104 10269351936 969329360 10.580 100 73 145 0 1037771472 53681872 14760240
05:15:13 4285 8192000000 9983099888 10337824768 1003971536 10.917 100 81 160 1 1089731152 69276112 16483504
05:16:19 4669 8192000000 10009375344 10368299008 1026516752 11.135 100 88 177 0 1115965712 71342480 18106480
05:17:26 1679 8192000000 10023025456 10387922944 1047676768 11.338 100 93 184 1 1133059600 66388224 18994608
05:18:29 17569 8192000000 10019923312 10400927744 1064218000 11.497 100 63 126 0 1150658912 74188080 12252832
05:19:48 12468 8192000000 9969671232 10371567616 1074927488 11.599 100 32 64 0 1100883024 18856064 7099472
05:21:01 9057 8192000000 9982583984 10381565952 1087333296 11.717 100 37 74 0 1111422912 15070336 9019280
05:22:12 5467 8192000000 9998447600 10392227840 1091935456 11.761 100 38 74 0 1114778960 14602144 8241360
05:23:21 6841 8192000000 10000349504 10412670976 1092392528 11.765 100 18 35 1 1115351424 19547616 3411280
05:24:32 14898 8192000000 10010866416 10404429824 1093365504 11.775 100 52 99 2 1116564464 11430816 11768144
05:25:48 3507 8192000000 10046543968 10443444224 1089687392 11.740 100 57 115 0 1152003344 50171680 12144272
05:26:55 5620 8192000000 10010001360 10411683840 1089893056 11.742 100 32 64 0 1120423504 24090032 6440416
05:28:04 6040 8192000000 9988408144 10377838592 1053581744 11.395 98 33 64 1 1068860720 7283056 7995920
05:29:10 42731 8192000000 9959919552 10350473216 990597664 10.787 94 39 76 3 1012785680 13780624 8407392
05:30:53 9494 8192000000 9958488736 10365161472 1031283440 11.181 99 39 78 2 1068476368 28320944 8871984
05:32:05 10722 8192000000 9942352896 10338050048 984883728 10.732 95 21 42 0 1013021472 23434432 4703312
05:33:17 6873 8192000000 9949038112 10342670336 1028392736 11.153 99 31 61 1 1058514832 23225952 6896144
05:34:26 42145 8192000000 9971251312 10374328320 1055080880 11.409 100 27 54 1 1092754736 31996720 5677136
05:36:09 8966 8192000000 10001774080 10407567360 1071383184 11.565 100 44 87 1 1100491504 19144384 9963936
05:37:25 29812 8192000000 9993808544 10390937600 1083248160 11.678 100 48 95 5 1100546256 7419872 9878224
05:38:59 54562 8192000000 10051299376 10457710592 1096651312 11.806 100 35 70 1 1175120592 70579856 7889424
05:41:18 18023 8192000000 10060119840 10471469056 1109213584 11.925 100 26 52 3 1171298320 55969328 6115408
05:42:45 16216 8192000000 10117853584 10526302208 1112755984 11.959 99 48 96 1 1217717648 94398688 10562976
05:44:05 17264 8192000000 10184564912 10594271232 1136899888 12.186 100 62 123 2 1290923184 140310784 13712512
05:45:31 20263 8192000000 10194329904 10610061312 1150102880 12.310 100 43 86 1 1303568448 144237632 9227936
05:46:55 9113 8192000000 10248496384 10658979840 1163187744 12.433 100 61 116 0 1353211648 175973184 14050720
05:48:08 2772 8192000000 10276229504 10684534784 1178578208 12.577 100 73 144 0 1386579152 192802128 15198816
05:49:18 5346 8192000000 10308845520 10725232640 1171128240 12.507 99 63 125 2 1417135520 231721760 14285520
05:50:26 6837 8192000000 10299111392 10711470080 1179310624 12.584 100 69 138 0 1406750800 212151616 15288560
05:51:39 5748 8192000000 10356453392 10764120064 1184535376 12.632 100 81 161 2 1454223280 251768592 17919312
05:52:53 5335 8192000000 10400403120 10808926208 1186413920 12.650 100 79 155 1 1495651760 291146912 18090928
05:54:08 2040 8192000000 10464300960 10869551104 1182667776 12.615 100 91 184 0 1563480704 358462992 22349936
05:55:15 1468 8192000000 10438299472 10842398720 1156984112 12.375 99 89 179 0 1525875088 348112032 20778944
05:56:21 1833 8192000000 10436467856 10859794432 1169578400 12.493 99 78 155 1 1537363712 350327936 17457376
05:57:29 2176 8192000000 10458526576 10863210496 1178198544 12.573 100 85 169 1 1557519120 358892208 20428368
05:58:34 10493 8192000000 10428438768 10892763136 1172060912 12.516 100 36 72 0 1537870400 356471840 9337648
05:59:50 11575 8192000000 10469077264 10898092032 1180737968 12.597 100 35 70 1 1570204080 380258848 9207264
06:01:07 18433 8192000000 10506224928 10964516864 1169763408 12.495 100 36 72 0 1604127296 424996368 9367520
06:02:29 16908 8192000000 10508092944 10944929792 1137194400 12.189 99 34 68 2 1602576992 456215920 9166672
06:03:52 27573 8192000000 10471172592 10918756352 1105879392 11.893 97 25 50 1 1542249472 428870928 7499152
06:05:20 43074 8192000000 10068544032 10514771968 1109005888 11.923 97 8 16 0 1248359200 137331088 2022224
06:07:04 47347 8192000000 10120266880 10557378560 1136915984 12.187 100 34 68 1 1194743072 51216176 6610912
06:08:58 135191 8192000000 10063452656 10541989888 1113921824 11.970 98 15 30 2 1150906544 33739312 3245408
06:12:26 64298 8192000000 10072943152 10527035392 1099847104 11.836 97 17 34 1 1129092832 25835392 3410336
06:14:32 65326 8192000000 10143350608 10585509888 1126339728 12.087 99 25 50 2 1215158928 82908016 5911184
06:17:26 74887 8192000000 10152264816 10641559552 1123233040 12.058 99 22 43 1 1273485280 145026384 5225856
06:19:52 62819 8192000000 10127816528 10567364608 1147414656 12.285 100 23 46 1 1237660912 85419232 4827024
06:23:52 66546 8192000000 10058748384 10547052544 1140411840 12.219 100 11 22 1 1182409152 39970208 2027104
06:26:00 75308 8192000000 10084418976 10607763456 1109211968 11.925 97 12 24 1 1207282144 95069984 3000192
06:28:20 61248 8192000000 10088274816 10515881984 1111865920 11.950 97 47 93 3 1180841088 58715264 10259904
06:30:21 97722 8192000000 10082147104 10557186048 1133476128 12.154 99 21 42 0 1185768976 48382848 3910000
06:32:59 69221 8192000000 10103944400 10542563328 1126844608 12.092 98 23 46 3 1153418928 21988496 4585824
06:35:09 139846 8192000000 10067957744 10524921856 1075606928 11.606 94 22 43 1 1130835264 50322368 4905968
06:38:39 63206 8192000000 10076880816 10511794176 1130294016 12.124 99 40 82 1 1168639936 29226464 9119456
06:40:43 25515 8192000000 10071766080 10548883456 1143449104 12.248 100 21 42 1 1180007024 32734240 3823680
06:42:11 59153 8192000000 10071529392 10534830080 1145595136 12.268 100 7 14 0 1187102656 40326736 1180784
06:44:12 79369 8192000000 10085465680 10562285568 1057396720 11.432 92 6 12 0 1131041504 72448944 1195840
06:46:43 47374 8192000000 10048778112 10489667584 1089502112 11.738 96 9 18 1 1112934704 21469152 1963440
06:48:31 63681 8192000000 10056492464 10534711296 1102641440 11.863 98 17 32 1 1137354336 31867024 2845872
06:50:56 30844 8192000000 10096381248 10526982144 1113454240 11.965 100 37 74 1 1181421104 59639584 8327280
06:52:39 82688 8192000000 10097974752 10529075200 1077181744 11.621 96 11 22 0 1156079280 76559248 2338288
06:55:05 72421 8192000000 10097830992 10583384064 1091304208 11.755 98 23 46 2 1181349984 85424576 4621200
06:57:20 81745 8192000000 10114975264 10561953792 1115154640 11.981 99 23 46 2 1176838032 56651952 5031440
07:01:32 72856 8192000000 10071751248 10602143744 1108862528 11.922 98 5 11 0 1162713552 52872176 978848
07:03:46 68686 8192000000 10045224240 10540818432 1102160176 11.858 98 14 28 0 1163450144 58371616 2918352
07:05:56 45730 8192000000 10063018896 10491908096 1108151248 11.915 98 29 55 1 1145086048 31769632 5165168
07:07:45 37077 8192000000 10116697040 10574827520 1110151968 11.934 98 20 40 1 1182207248 67875072 4180208
07:09:26 59147 8192000000 10119571728 10554490880 1102103440 11.858 97 29 57 2 1200693168 92158576 6431152
07:11:30 49209 8192000000 10097549232 10616754176 1120433552 12.031 100 20 40 0 1210898720 86427328 4037840
07:13:19 54127 8192000000 10112642320 10545860608 1096759968 11.807 97 29 57 2 1150249504 47287856 6201680
07:15:36 51403 8192000000 10119958784 10583994368 1138937456 12.206 100 22 44 1 1206882864 63482320 4463088
07:17:28 123282 8192000000 10091959792 10551496704 1131032368 12.131 99 20 40 0 1203234736 68421408 3780960
07:20:31 52110 8192000000 10096132848 10591887360 1141717136 12.232 100 8 16 0 1185082576 41799200 1566240
07:22:24 42355 8192000000 10113805248 10560671744 1128537936 12.108 98 11 22 1 1203126480 71968944 2619600
07:24:18 111493 8192000000 10105804560 10631335936 1132857696 12.148 99 22 45 3 1233057232 95156816 5042720
07:27:17 39188 8192000000 10089203712 10539462656 1142536016 12.239 99 17 34 1 1187754768 41836864 3381888
07:28:57 53279 8192000000 10135740976 10607316992 1141398256 12.229 99 15 29 0 1194667840 50042608 3226976
07:30:50 18284 8192000000 10163759072 10611228672 1158280704 12.387 100 46 91 1 1227746960 59099152 10367104
07:32:18 81258 8192000000 10093901088 10526269440 1088321424 11.727 94 23 47 2 1136486064 43495584 4669056
07:34:50 149142 8192000000 9729853120 10147512320 820365952 9.102 71 19 38 0 887169536 62349088 4454496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-02-2021 07:40:18 to 27-02-2021 07:40:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-02-2021 07:40:33 to 27-02-2021 07:40:35.

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          224779
MON$OLDEST_ACTIVE               226076
MON$OLDEST_SNAPSHOT             226076
MON$NEXT_TRANSACTION            226076
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                       2081312
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2377 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.2377 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 Feb 27 07:37:30 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Feb 27 07:37:51 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Feb 27 07:38:00 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Feb 27 07:38:03 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

27.02.21 07:40:37. 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-02-27 07:40:39 No severe PSQL-related problems occured
27.02.21 07:40:40. End of report.